May 26 17:22:10 volumio go-librespot[4306]: time="2026-05-26T17:22:10+03:00" level=trace msg="sent dealer ping" May 26 17:22:10 volumio go-librespot[4306]: time="2026-05-26T17:22:10+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:22:10 volumio go-librespot[4306]: time="2026-05-26T17:22:10+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:22:10 volumio go-librespot[4306]: time="2026-05-26T17:22:10+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:22:10 volumio go-librespot[4306]: time="2026-05-26T17:22:10+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" May 26 17:22:10 volumio go-librespot[4306]: time="2026-05-26T17:22:10+03:00" level=trace msg="received dealer pong" May 26 17:22:10 volumio go-librespot[4306]: time="2026-05-26T17:22:10+03:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" May 26 17:22:18 volumio systemd[1]: Starting apt-daily.service - Daily apt download activities... May 26 17:22:20 volumio systemd[1]: apt-daily.service: Deactivated successfully. May 26 17:22:20 volumio systemd[1]: Finished apt-daily.service - Daily apt download activities. May 26 17:22:20 volumio systemd[1]: apt-daily.service: Consumed 1.878s CPU time. May 26 17:22:40 volumio go-librespot[4306]: time="2026-05-26T17:22:40+03:00" level=trace msg="sent dealer ping" May 26 17:22:40 volumio go-librespot[4306]: time="2026-05-26T17:22:40+03:00" level=trace msg="received dealer pong" May 26 17:22:50 volumio go-librespot[4306]: time="2026-05-26T17:22:50+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:22:50 volumio go-librespot[4306]: time="2026-05-26T17:22:50+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:22:50 volumio go-librespot[4306]: time="2026-05-26T17:22:50+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:22:50 volumio go-librespot[4306]: time="2026-05-26T17:22:50+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" May 26 17:22:50 volumio go-librespot[4306]: time="2026-05-26T17:22:50+03:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" May 26 17:23:10 volumio go-librespot[4306]: time="2026-05-26T17:23:10+03:00" level=error msg="did not receive last pong ack from accesspoint, 240s passed" May 26 17:23:10 volumio go-librespot[4306]: panic: runtime error: invalid memory address or nil pointer dereference May 26 17:23:10 volumio go-librespot[4306]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f7ef0] May 26 17:23:10 volumio go-librespot[4306]: goroutine 94 gp=0x26a66c8 m=5 mp=0x2480008 [running]: May 26 17:23:10 volumio go-librespot[4306]: panic({0x841d88, 0xfa0250}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x2615f20 sp=0x2615ecc pc=0xa92ec May 26 17:23:10 volumio go-librespot[4306]: runtime.panicmem(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/panic.go:262 May 26 17:23:10 volumio go-librespot[4306]: runtime.sigpanic() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x2615f50 sp=0x2615f20 pc=0xabca4 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x2738798) May 26 17:23:10 volumio go-librespot[4306]: /src/ap/ap.go:380 +0x284 fp=0x2615fe4 sp=0x2615f54 pc=0x4f7ef0 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() May 26 17:23:10 volumio go-librespot[4306]: /src/ap/ap.go:277 +0x28 fp=0x2615fec sp=0x2615fe4 pc=0x4f73c4 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2615fec sp=0x2615fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 85 May 26 17:23:10 volumio go-librespot[4306]: /src/ap/ap.go:277 +0x15c May 26 17:23:10 volumio go-librespot[4306]: goroutine 1 gp=0x2402128 m=nil [select, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x245dce0 sp=0x245dccc pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.selectgo(0x245ddf0, 0x24bfdbc, 0x0, 0x0, 0x2, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x245dd90 sp=0x245dce0 pc=0x85000 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x24b8ae0, 0x245de90) May 26 17:23:10 volumio go-librespot[4306]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x245de38 sp=0x245dd90 pc=0x62f43c May 26 17:23:10 volumio go-librespot[4306]: main.(*App).withAppPlayer(0x24c6200, {0xa3da1c, 0x1005090}, 0x249ce10) May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x245ded0 sp=0x245de38 pc=0x6bcc60 May 26 17:23:10 volumio go-librespot[4306]: main.(*App).withCredentials(...) May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:209 May 26 17:23:10 volumio go-librespot[4306]: main.(*App).SpotifyToken(0x24c6200, {0xa3da1c, 0x1005090}, {0x25229c0, 0x19}, {0x247e3c0, 0x136}) May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x245df00 sp=0x245ded0 pc=0x6bc02c May 26 17:23:10 volumio go-librespot[4306]: main.main() May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:620 +0x660 fp=0x245dfa8 sp=0x245df00 pc=0x6bf56c May 26 17:23:10 volumio go-librespot[4306]: runtime.main() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x245dfec sp=0x245dfa8 pc=0x6f390 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x245dfec sp=0x245dfec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: goroutine 2 gp=0x24027e8 m=nil [force gc (idle), 2 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2448fd4 sp=0x2448fc0 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.goparkunlock(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:466 May 26 17:23:10 volumio go-librespot[4306]: runtime.forcegchelper() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2448fec sp=0x2448fd4 pc=0x6f7f4 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2448fec sp=0x2448fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.init.6 in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:361 +0x1c May 26 17:23:10 volumio go-librespot[4306]: goroutine 3 gp=0x2402b48 m=nil [GC sweep wait]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24497c4 sp=0x24497b0 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.goparkunlock(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:466 May 26 17:23:10 volumio go-librespot[4306]: runtime.bgsweep(0x242e050) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x24497e4 sp=0x24497c4 pc=0x5768c May 26 17:23:10 volumio go-librespot[4306]: runtime.gcenable.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x24497ec sp=0x24497e4 pc=0x46c3c May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24497ec sp=0x24497ec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.gcenable in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:212 +0x74 May 26 17:23:10 volumio go-librespot[4306]: goroutine 4 gp=0x2402c68 m=nil [GC scavenge wait]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97746c, 0xffa170, 0xd, 0xa, 0x2) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2449fb4 sp=0x2449fa0 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.goparkunlock(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:466 May 26 17:23:10 volumio go-librespot[4306]: runtime.(*scavengerState).park(0xffa170) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2449fc8 sp=0x2449fb4 pc=0x54a28 May 26 17:23:10 volumio go-librespot[4306]: runtime.bgscavenge(0x242e050) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2449fe4 sp=0x2449fc8 pc=0x5516c May 26 17:23:10 volumio go-librespot[4306]: runtime.gcenable.gowrap2() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2449fec sp=0x2449fe4 pc=0x46be8 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2449fec sp=0x2449fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.gcenable in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:213 +0xbc May 26 17:23:10 volumio go-librespot[4306]: goroutine 5 gp=0x2402fc8 m=nil [GOMAXPROCS updater (idle), 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244a7a4 sp=0x244a790 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.goparkunlock(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:466 May 26 17:23:10 volumio go-librespot[4306]: runtime.updateMaxProcsGoroutine() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x244a7ec sp=0x244a7a4 pc=0x7f174 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244a7ec sp=0x244a7ec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:6708 +0x40 May 26 17:23:10 volumio go-librespot[4306]: goroutine 6 gp=0x2403328 m=nil [finalizer wait, 168 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244af88 sp=0x244af74 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.runFinalizers() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x244afec sp=0x244af88 pc=0x45888 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244afec sp=0x244afec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.createfing in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c May 26 17:23:10 volumio go-librespot[4306]: goroutine 7 gp=0x25de908 m=nil [cleanup wait, 168 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24487a4 sp=0x2448790 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.goparkunlock(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:466 May 26 17:23:10 volumio go-librespot[4306]: runtime.(*cleanupQueue).dequeue(0xffa2e8) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x24487c4 sp=0x24487a4 pc=0x41cb0 May 26 17:23:10 volumio go-librespot[4306]: runtime.runCleanups() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x24487ec sp=0x24487c4 pc=0x4284c May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24487ec sp=0x24487ec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.(*cleanupQueue).createGs in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 May 26 17:23:10 volumio go-librespot[4306]: goroutine 20 gp=0x2482c68 m=nil [IO wait, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97745c, 0x760a4e10, 0x2, 0x2, 0x5) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2456cf0 sp=0x2456cdc pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.netpollblock(0x760a4e00, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2456d08 sp=0x2456cf0 pc=0x675a0 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.runtime_pollWait(0x760a4e00, 0x72) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2456d1c sp=0x2456d08 pc=0xa8864 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).wait(0x24b4248, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2456d30 sp=0x2456d1c pc=0x136044 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).waitRead(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*FD).Accept(0x24b4230) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2456d78 sp=0x2456d30 pc=0x13a800 May 26 17:23:10 volumio go-librespot[4306]: net.(*netFD).accept(0x24b4230) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x2456de0 sp=0x2456d78 pc=0x1abc28 May 26 17:23:10 volumio go-librespot[4306]: net.(*TCPListener).accept(0x24b6270) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2456e34 sp=0x2456de0 pc=0x1c3554 May 26 17:23:10 volumio go-librespot[4306]: net.(*TCPListener).Accept(0x24b6270) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x2456e50 sp=0x2456e34 pc=0x1c259c May 26 17:23:10 volumio go-librespot[4306]: net/http.(*onceCloseListener).Accept(0x2694080) May 26 17:23:10 volumio go-librespot[4306]: :1 +0x34 fp=0x2456e68 sp=0x2456e50 pc=0x367f9c May 26 17:23:10 volumio go-librespot[4306]: net/http.(*Server).Serve(0x26a4008, {0xa3d218, 0x24b6270}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x2456f00 sp=0x2456e68 pc=0x341154 May 26 17:23:10 volumio go-librespot[4306]: net/http.Serve(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:2971 May 26 17:23:10 volumio go-librespot[4306]: main.(*ConcreteApiServer).serve(0x24b8180) May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x2456fe4 sp=0x2456f00 pc=0x6b17bc May 26 17:23:10 volumio go-librespot[4306]: main.NewApiServer.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x2456fec sp=0x2456fe4 pc=0x6b04f4 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2456fec sp=0x2456fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by main.NewApiServer in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/api_server.go:332 +0x310 May 26 17:23:10 volumio go-librespot[4306]: goroutine 25 gp=0x24830e8 m=nil [GC worker (idle)]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x977320, 0x24d8200, 0x1c, 0xa, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2705f88 sp=0x2705f74 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.gcBgMarkWorker(0x24acb40) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2705fe4 sp=0x2705f88 pc=0x49f34 May 26 17:23:10 volumio go-librespot[4306]: runtime.gcBgMarkStartWorkers.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2705fec sp=0x2705fe4 pc=0x49e14 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2705fec sp=0x2705fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.gcBgMarkStartWorkers in goroutine 34 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c May 26 17:23:10 volumio go-librespot[4306]: goroutine 26 gp=0x2483208 m=nil [GC worker (idle), 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x977320, 0x24d8400, 0x1c, 0xa, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2445f88 sp=0x2445f74 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.gcBgMarkWorker(0x24acb40) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2445fe4 sp=0x2445f88 pc=0x49f34 May 26 17:23:10 volumio go-librespot[4306]: runtime.gcBgMarkStartWorkers.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2445fec sp=0x2445fe4 pc=0x49e14 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2445fec sp=0x2445fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.gcBgMarkStartWorkers in goroutine 34 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c May 26 17:23:10 volumio go-librespot[4306]: goroutine 27 gp=0x2483328 m=nil [GC worker (idle), 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x977320, 0x24d8600, 0x1c, 0xa, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2446788 sp=0x2446774 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.gcBgMarkWorker(0x24acb40) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x24467e4 sp=0x2446788 pc=0x49f34 May 26 17:23:10 volumio go-librespot[4306]: runtime.gcBgMarkStartWorkers.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x24467ec sp=0x24467e4 pc=0x49e14 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24467ec sp=0x24467ec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.gcBgMarkStartWorkers in goroutine 34 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c May 26 17:23:10 volumio go-librespot[4306]: goroutine 28 gp=0x2483448 m=nil [GC worker (idle), 49 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x977320, 0x24d8800, 0x1c, 0xa, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26b3f88 sp=0x26b3f74 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.gcBgMarkWorker(0x24acb40) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x26b3fe4 sp=0x26b3f88 pc=0x49f34 May 26 17:23:10 volumio go-librespot[4306]: runtime.gcBgMarkStartWorkers.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x26b3fec sp=0x26b3fe4 pc=0x49e14 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26b3fec sp=0x26b3fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by runtime.gcBgMarkStartWorkers in goroutine 34 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c May 26 17:23:10 volumio go-librespot[4306]: goroutine 8 gp=0x25dea28 m=nil [chan receive, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x9772f4, 0x29173b8, 0xe, 0x7, 0x2) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2444f78 sp=0x2444f64 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.chanrecv(0x2917380, 0x0, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2444fc0 sp=0x2444f78 pc=0x3414c May 26 17:23:10 volumio go-librespot[4306]: runtime.chanrecv1(0x2917380, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x2444fd4 sp=0x2444fc0 pc=0x33bcc May 26 17:23:10 volumio go-librespot[4306]: github.com/godbus/dbus/v5.newConn.func1() May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x2444fec sp=0x2444fd4 pc=0x4aa8ec May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2444fec sp=0x2444fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c May 26 17:23:10 volumio go-librespot[4306]: goroutine 29 gp=0x26a6368 m=nil [IO wait, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97745c, 0x760a4810, 0x2, 0x2, 0x5) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x261acbc sp=0x261aca8 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.netpollblock(0x760a4800, 0x72, 0x0) May 26 17:23:10 volumio volumio[1130]: info: Connection to go-librespot Websocket closed May 26 17:23:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x261acd4 sp=0x261acbc pc=0x675a0 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.runtime_pollWait(0x760a4800, 0x72) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x261ace8 sp=0x261acd4 pc=0xa8864 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).wait(0x24b59b8, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x261acfc sp=0x261ace8 pc=0x136044 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).waitRead(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*FD).ReadMsg(0x24b59a0, {0x28c2900, 0x10, 0x10}, {0x27e8618, 0x1000, 0x1000}, 0x40000000) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x261ad6c sp=0x261acfc pc=0x1381bc May 26 17:23:10 volumio go-librespot[4306]: net.(*netFD).readMsg(0x24b59a0, {0x28c2900, 0x10, 0x10}, {0x27e8618, 0x1000, 0x1000}, 0x40000000) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x261adc8 sp=0x261ad6c pc=0x1aa2c8 May 26 17:23:10 volumio go-librespot[4306]: net.(*UnixConn).readMsg(0x24913f8, {0x28c2900, 0x10, 0x10}, {0x27e8618, 0x1000, 0x1000}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x261ae28 sp=0x261adc8 pc=0x1c9d80 May 26 17:23:10 volumio go-librespot[4306]: net.(*UnixConn).ReadMsgUnix(0x24913f8, {0x28c2900, 0x10, 0x10}, {0x27e8618, 0x1000, 0x1000}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x261ae78 sp=0x261ae28 pc=0x1c820c May 26 17:23:10 volumio go-librespot[4306]: github.com/godbus/dbus/v5.(*oobReader).Read(0x27e8608, {0x28c2900, 0x10, 0x10}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x261aec8 sp=0x261ae78 pc=0x4c2af0 May 26 17:23:10 volumio go-librespot[4306]: io.ReadAtLeast({0xa39d58, 0x27e8608}, {0x28c2900, 0x10, 0x10}, 0x10) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x261aef4 sp=0x261aec8 pc=0xee954 May 26 17:23:10 volumio go-librespot[4306]: io.ReadFull(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/io/io.go:354 May 26 17:23:10 volumio go-librespot[4306]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x249d780) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x261afa0 sp=0x261aef4 pc=0x4c32c4 May 26 17:23:10 volumio go-librespot[4306]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x2526600) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x261afe4 sp=0x261afa0 pc=0x4aaef4 May 26 17:23:10 volumio go-librespot[4306]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() May 26 17:23:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x261afec sp=0x261afe4 pc=0x4a8318 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x261afec sp=0x261afec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c May 26 17:23:10 volumio go-librespot[4306]: goroutine 95 gp=0x2403568 m=nil [IO wait, 2 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97745c, 0x760a4010, 0x2, 0x2, 0x5) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x245b998 sp=0x245b984 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.netpollblock(0x760a4000, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x245b9b0 sp=0x245b998 pc=0x675a0 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.runtime_pollWait(0x760a4000, 0x72) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x245b9c4 sp=0x245b9b0 pc=0xa8864 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).wait(0x2648018, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x245b9d8 sp=0x245b9c4 pc=0x136044 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).waitRead(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*FD).Read(0x2648000, {0x287c000, 0x1500, 0x1500}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x245ba20 sp=0x245b9d8 pc=0x1371c4 May 26 17:23:10 volumio go-librespot[4306]: net.(*netFD).Read(0x2648000, {0x287c000, 0x1500, 0x1500}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x245ba4c sp=0x245ba20 pc=0x1a9e78 May 26 17:23:10 volumio go-librespot[4306]: net.(*conn).Read(0x2912230, {0x287c000, 0x1500, 0x1500}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x245ba78 sp=0x245ba4c pc=0x1b967c May 26 17:23:10 volumio go-librespot[4306]: crypto/tls.(*atLeastReader).Read(0x26850c0, {0x287c000, 0x1500, 0x1500}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x245baa4 sp=0x245ba78 pc=0x2674c0 May 26 17:23:10 volumio go-librespot[4306]: bytes.(*Buffer).ReadFrom(0x24e8ccc, {0xa3a068, 0x26850c0}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x245bae0 sp=0x245baa4 pc=0x162058 May 26 17:23:10 volumio go-librespot[4306]: crypto/tls.(*Conn).readFromUntil(0x24e8b48, {0xa38fa8, 0x2912230}, 0x5) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x245bb08 sp=0x245bae0 pc=0x26771c May 26 17:23:10 volumio go-librespot[4306]: crypto/tls.(*Conn).readRecordOrCCS(0x24e8b48, 0x0) May 26 17:23:10 volumio systemd[1]: go-librespot-daemon.service: Consumed 2.254s CPU time. May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x245bc68 sp=0x245bb08 pc=0x264e40 May 26 17:23:10 volumio go-librespot[4306]: crypto/tls.(*Conn).readRecord(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/crypto/tls/conn.go:589 May 26 17:23:10 volumio go-librespot[4306]: crypto/tls.(*Conn).Read(0x24e8b48, {0x24a5000, 0x1000, 0x1000}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x245bc98 sp=0x245bc68 pc=0x26ac04 May 26 17:23:10 volumio go-librespot[4306]: net/http.(*readWriteCloserBody).Read(0x2684ca0, {0x24a5000, 0x1000, 0x1000}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/transport.go:2578 +0xec fp=0x245bcb8 sp=0x245bc98 pc=0x358048 May 26 17:23:10 volumio go-librespot[4306]: bufio.(*Reader).fill(0x24d1920) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x245bcdc sp=0x245bcb8 pc=0x2b6c1c May 26 17:23:10 volumio go-librespot[4306]: bufio.(*Reader).ReadByte(0x24d1920) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x245bce8 sp=0x245bcdc pc=0x2b7498 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.readFrameHeader(0x24d1920, {0x255cc38, 0x8, 0x8}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x245bd28 sp=0x245bce8 pc=0x372a08 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).readFrameHeader(0x255cc08, {0xa3da00, 0x1005090}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x245bdac sp=0x245bd28 pc=0x375bd4 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).readLoop(0x255cc08, {0xa3da00, 0x1005090}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x245be70 sp=0x245bdac pc=0x375390 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).reader(0x255cc08, {0xa3da00, 0x1005090}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x245bef4 sp=0x245be70 pc=0x376798 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).Reader(...) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).Read(0x255cc08, {0xa3da00, 0x1005090}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x245bf1c sp=0x245bef4 pc=0x374944 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x26a40b8) May 26 17:23:10 volumio go-librespot[4306]: /src/dealer/dealer.go:188 +0x58 fp=0x245bfe4 sp=0x245bf1c pc=0x5a222c May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /src/dealer/dealer.go:125 +0x28 fp=0x245bfec sp=0x245bfe4 pc=0x5a3d28 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x245bfec sp=0x245bfec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 85 May 26 17:23:10 volumio go-librespot[4306]: /src/dealer/dealer.go:125 +0x98 May 26 17:23:10 volumio go-librespot[4306]: goroutine 84 gp=0x27a4008 m=nil [select, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26b6674 sp=0x26b6660 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.selectgo(0x26b6780, 0x26b674c, 0x0, 0x0, 0x3, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x26b6724 sp=0x26b6674 pc=0x85000 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x250acd0) May 26 17:23:10 volumio go-librespot[4306]: /src/player/player.go:215 +0x1f4 fp=0x26b67e4 sp=0x26b6724 pc=0x582954 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /src/player/player.go:197 +0x28 fp=0x26b67ec sp=0x26b67e4 pc=0x58253c May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26b67ec sp=0x26b67ec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /src/player/player.go:197 +0x220 May 26 17:23:10 volumio go-librespot[4306]: goroutine 85 gp=0x26a6908 m=nil [select, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24bd930 sp=0x24bd91c pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.selectgo(0x24bdf70, 0x24bdb20, 0x0, 0x0, 0xa, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x24bd9e0 sp=0x24bd930 pc=0x85000 May 26 17:23:10 volumio go-librespot[4306]: main.(*AppPlayer).Run(0x27461e0, {0xa3da1c, 0x1005090}, 0x2916140, 0x2916180) May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/player.go:661 +0x368 fp=0x24bdfd4 sp=0x24bd9e0 pc=0x6c5950 May 26 17:23:10 volumio go-librespot[4306]: main.(*App).withAppPlayer.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:274 +0x48 fp=0x24bdfec sp=0x24bdfd4 pc=0x6bd9f0 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24bdfec sp=0x24bdfec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by main.(*App).withAppPlayer in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:274 +0x46c May 26 17:23:10 volumio go-librespot[4306]: goroutine 86 gp=0x26a6a28 m=nil [chan receive, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x9772f4, 0x24ac0f8, 0xe, 0x7, 0x2) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26b773c sp=0x26b7728 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.chanrecv(0x24ac0c0, 0x26b77d0, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x26b7784 sp=0x26b773c pc=0x3414c May 26 17:23:10 volumio go-librespot[4306]: runtime.chanrecv2(0x24ac0c0, 0x26b77d0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x26b7798 sp=0x26b7784 pc=0x33bf0 May 26 17:23:10 volumio go-librespot[4306]: main.(*App).withAppPlayer.func1() May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:284 +0x68 fp=0x26b77ec sp=0x26b7798 pc=0x6bd918 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26b77ec sp=0x26b77ec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by main.(*App).withAppPlayer in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:281 +0x4f8 May 26 17:23:10 volumio go-librespot[4306]: goroutine 87 gp=0x26a6b48 m=nil [chan receive, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x9772f4, 0x24ac0b8, 0xe, 0x7, 0x2) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26b7f40 sp=0x26b7f2c pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.chanrecv(0x24ac080, 0x26b7fe0, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x26b7f88 sp=0x26b7f40 pc=0x3414c May 26 17:23:10 volumio go-librespot[4306]: runtime.chanrecv2(0x24ac080, 0x26b7fe0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x26b7f9c sp=0x26b7f88 pc=0x33bf0 May 26 17:23:10 volumio go-librespot[4306]: main.(*App).withAppPlayer.func2() May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:300 +0x6c fp=0x26b7fec sp=0x26b7f9c pc=0x6bd47c May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26b7fec sp=0x26b7fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by main.(*App).withAppPlayer in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/main.go:297 +0x598 May 26 17:23:10 volumio go-librespot[4306]: goroutine 88 gp=0x26a6c68 m=nil [IO wait, 11 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97745c, 0x760a4a10, 0x2, 0x2, 0x5) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26b85ac sp=0x26b8598 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.netpollblock(0x760a4a00, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x26b85c4 sp=0x26b85ac pc=0x675a0 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.runtime_pollWait(0x760a4a00, 0x72) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x26b85d8 sp=0x26b85c4 pc=0xa8864 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).wait(0x24b5968, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x26b85ec sp=0x26b85d8 pc=0x136044 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).waitRead(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*FD).Accept(0x24b5950) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x26b8634 sp=0x26b85ec pc=0x13a800 May 26 17:23:10 volumio go-librespot[4306]: net.(*netFD).accept(0x24b5950) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x26b869c sp=0x26b8634 pc=0x1abc28 May 26 17:23:10 volumio go-librespot[4306]: net.(*TCPListener).accept(0x24b7da0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x26b86f0 sp=0x26b869c pc=0x1c3554 May 26 17:23:10 volumio go-librespot[4306]: net.(*TCPListener).Accept(0x24b7da0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x26b870c sp=0x26b86f0 pc=0x1c259c May 26 17:23:10 volumio go-librespot[4306]: net/http.(*onceCloseListener).Accept(0x26948e0) May 26 17:23:10 volumio go-librespot[4306]: :1 +0x34 fp=0x26b8724 sp=0x26b870c pc=0x367f9c May 26 17:23:10 volumio go-librespot[4306]: net/http.(*Server).Serve(0x26a4168, {0xa3d218, 0x24b7da0}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x26b87bc sp=0x26b8724 pc=0x341154 May 26 17:23:10 volumio go-librespot[4306]: net/http.Serve(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:2971 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() May 26 17:23:10 volumio go-librespot[4306]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x26b87ec sp=0x26b87bc pc=0x62f538 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26b87ec sp=0x26b87ec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 May 26 17:23:10 volumio go-librespot[4306]: /src/zeroconf/zeroconf.go:297 +0x160 May 26 17:23:10 volumio go-librespot[4306]: goroutine 93 gp=0x26a6fc8 m=nil [select]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x245cd78 sp=0x245cd64 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.selectgo(0x245ce7c, 0x245ce50, 0x0, 0x0, 0x2, 0x1) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x245ce28 sp=0x245cd78 pc=0x85000 May 26 17:23:10 volumio go-librespot[4306]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x245cecc, {0xa3c8c0, 0x24b8900}, 0x0, {0x0, 0x0}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:112 +0x244 fp=0x245cea4 sp=0x245ce28 pc=0x4eb7bc May 26 17:23:10 volumio go-librespot[4306]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x245cf5c, {0xa3c8c0, 0x24b8900}, 0x0, {0x0, 0x0}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x245ced4 sp=0x245cea4 pc=0x4eb180 May 26 17:23:10 volumio go-librespot[4306]: github.com/cenkalti/backoff/v4.RetryNotify(...) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 May 26 17:23:10 volumio go-librespot[4306]: github.com/cenkalti/backoff/v4.Retry(...) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x2738798) May 26 17:23:10 volumio go-librespot[4306]: /src/ap/ap.go:335 +0x434 fp=0x245cfe4 sp=0x245ced4 pc=0x4f7878 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() May 26 17:23:10 volumio go-librespot[4306]: /src/ap/ap.go:273 +0x28 fp=0x245cfec sp=0x245cfe4 pc=0x4f7418 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x245cfec sp=0x245cfec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 85 May 26 17:23:10 volumio go-librespot[4306]: /src/ap/ap.go:273 +0x98 May 26 17:23:10 volumio go-librespot[4306]: goroutine 96 gp=0x26a70e8 m=nil [runnable]: May 26 17:23:10 volumio go-librespot[4306]: internal/runtime/maps.(*Map).deleteSmall(0x28525c0, 0x81d908, 0xf392c381, 0x2459d38) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/runtime/maps/map.go:683 +0x28c fp=0x2459ce4 sp=0x2459ce4 pc=0x25d9c May 26 17:23:10 volumio go-librespot[4306]: internal/runtime/maps.(*Map).Delete(0x28525c0, 0x81d908, 0x2459d38) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/runtime/maps/map.go:662 +0x128 fp=0x2459d00 sp=0x2459ce4 pc=0x25a34 May 26 17:23:10 volumio go-librespot[4306]: runtime.mapdelete(0x81d908, 0x28525c0, 0x2459d38) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/map_swiss.go:153 +0x2c fp=0x2459d10 sp=0x2459d00 pc=0xa7350 May 26 17:23:10 volumio go-librespot[4306]: context.removeChild({0xa3da8c, 0x2747770}, {0xa3c220, 0x262a880}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/context/context.go:410 +0x94 fp=0x2459d2c sp=0x2459d10 pc=0xeb204 May 26 17:23:10 volumio go-librespot[4306]: context.(*afterFuncCtx).cancel(0x262a880, 0x1, {0xa38da8, 0xf9fa08}, {0x0, 0x0}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/context/context.go:355 +0x78 fp=0x2459d50 sp=0x2459d2c pc=0xeafd4 May 26 17:23:10 volumio go-librespot[4306]: context.AfterFunc.func1() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/context/context.go:336 +0x90 fp=0x2459d7c sp=0x2459d50 pc=0xeaf24 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.swapTimeoutStop(0x255cc30, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/conn.go:198 +0x44 fp=0x2459d8c sp=0x2459d7c pc=0x36f6c0 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).clearWriteTimeout(...) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/conn.go:180 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).writeFrame.deferwrap2() May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/write.go:277 +0x38 fp=0x2459d98 sp=0x2459d8c pc=0x379794 May 26 17:23:10 volumio go-librespot[4306]: runtime.deferreturn() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/panic.go:589 +0x50 fp=0x2459ddc sp=0x2459d98 pc=0x6b420 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).writeFrame(0x255cc08, {0xa3da8c, 0x2747770}, 0x1, 0x0, 0x1, {0x28c20c0, 0xf, 0xf}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/write.go:327 +0x544 fp=0x2459eb4 sp=0x2459ddc pc=0x379698 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).write(0x255cc08, {0xa3da8c, 0x2747770}, 0x1, {0x28c20c0, 0xf, 0xf}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/write.go:110 +0x1a0 fp=0x2459f00 sp=0x2459eb4 pc=0x378254 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).Write(0x255cc08, {0xa3da8c, 0x2747770}, 0x1, {0x28c20c0, 0xf, 0xf}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/write.go:43 +0x4c fp=0x2459f34 sp=0x2459f00 pc=0x377ed8 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x26a40b8) May 26 17:23:10 volumio go-librespot[4306]: /src/dealer/dealer.go:156 +0x35c fp=0x2459fe4 sp=0x2459f34 pc=0x5a2060 May 26 17:23:10 volumio go-librespot[4306]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() May 26 17:23:10 volumio go-librespot[4306]: /src/dealer/dealer.go:129 +0x28 fp=0x2459fec sp=0x2459fe4 pc=0x5a3cd4 May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2459fec sp=0x2459fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 85 May 26 17:23:10 volumio go-librespot[4306]: /src/dealer/dealer.go:129 +0x15c May 26 17:23:10 volumio go-librespot[4306]: goroutine 99 gp=0x25df0e8 m=nil [IO wait, 170 minutes]: May 26 17:23:10 volumio go-librespot[4306]: runtime.gopark(0x97745c, 0x756cc810, 0x2, 0x2, 0x5) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24c1908 sp=0x24c18f4 pc=0xa97c0 May 26 17:23:10 volumio go-librespot[4306]: runtime.netpollblock(0x756cc800, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x24c1920 sp=0x24c1908 pc=0x675a0 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.runtime_pollWait(0x756cc800, 0x72) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x24c1934 sp=0x24c1920 pc=0xa8864 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).wait(0x2857be8, 0x72, 0x0) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24c1948 sp=0x24c1934 pc=0x136044 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*pollDesc).waitRead(...) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 26 17:23:10 volumio go-librespot[4306]: internal/poll.(*FD).Read(0x2857bd0, {0x2782000, 0x1000, 0x1000}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x24c1990 sp=0x24c1948 pc=0x1371c4 May 26 17:23:10 volumio go-librespot[4306]: net.(*netFD).Read(0x2857bd0, {0x2782000, 0x1000, 0x1000}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x24c19bc sp=0x24c1990 pc=0x1a9e78 May 26 17:23:10 volumio go-librespot[4306]: net.(*conn).Read(0x28424c0, {0x2782000, 0x1000, 0x1000}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x24c19e8 sp=0x24c19bc pc=0x1b967c May 26 17:23:10 volumio go-librespot[4306]: io.(*multiReader).Read(0x28f8380, {0x2782000, 0x1000, 0x1000}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x24c1a14 sp=0x24c19e8 pc=0xefb88 May 26 17:23:10 volumio go-librespot[4306]: bufio.(*Reader).fill(0x2854a80) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x24c1a38 sp=0x24c1a14 pc=0x2b6c1c May 26 17:23:10 volumio go-librespot[4306]: bufio.(*Reader).ReadByte(0x2854a80) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x24c1a44 sp=0x24c1a38 pc=0x2b7498 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.readFrameHeader(0x2854a80, {0x27b8038, 0x8, 0x8}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x24c1a84 sp=0x24c1a44 pc=0x372a08 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).readFrameHeader(0x27b8008, {0xa3da00, 0x1005090}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x24c1b08 sp=0x24c1a84 pc=0x375bd4 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).readLoop(0x27b8008, {0xa3da00, 0x1005090}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x24c1bcc sp=0x24c1b08 pc=0x375390 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).reader(0x27b8008, {0xa3da00, 0x1005090}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x24c1c50 sp=0x24c1bcc pc=0x376798 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).Reader(...) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 May 26 17:23:10 volumio go-librespot[4306]: github.com/coder/websocket.(*Conn).Read(0x27b8008, {0xa3da00, 0x1005090}) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x24c1c78 sp=0x24c1c50 pc=0x374944 May 26 17:23:10 volumio go-librespot[4306]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x2841088}, 0x2840f28) May 26 17:23:10 volumio go-librespot[4306]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x24c1cfc sp=0x24c1c78 pc=0x6b2040 May 26 17:23:10 volumio go-librespot[4306]: net/http.HandlerFunc.ServeHTTP(0x2688188, {0xa3d2d8, 0x2841088}, 0x2840f28) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x24c1d0c sp=0x24c1cfc pc=0x33d7d8 May 26 17:23:10 volumio go-librespot[4306]: net/http.(*ServeMux).ServeHTTP(0x2680000, {0xa3d2d8, 0x2841088}, 0x2840f28) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x24c1d4c sp=0x24c1d0c pc=0x33f6c8 May 26 17:23:10 volumio go-librespot[4306]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x2841088}, 0x2840f28) May 26 17:23:10 volumio go-librespot[4306]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x24c1d74 sp=0x24c1d4c pc=0x6b19b8 May 26 17:23:10 volumio go-librespot[4306]: net/http.HandlerFunc.ServeHTTP(0x2684140, {0xa3d2d8, 0x2841088}, 0x2840f28) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x24c1d84 sp=0x24c1d74 pc=0x33d7d8 May 26 17:23:10 volumio go-librespot[4306]: net/http.serverHandler.ServeHTTP({0x26a4008}, {0xa3d2d8, 0x2841088}, 0x2840f28) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x24c1da0 sp=0x24c1d84 pc=0x35cb44 May 26 17:23:10 volumio go-librespot[4306]: net/http.(*conn).serve(0x284c900, {0xa3da38, 0x26921e0}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x24c1fdc sp=0x24c1da0 pc=0x33b894 May 26 17:23:10 volumio go-librespot[4306]: net/http.(*Server).Serve.gowrap3() May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x24c1fec sp=0x24c1fdc pc=0x3415cc May 26 17:23:10 volumio go-librespot[4306]: runtime.goexit({}) May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24c1fec sp=0x24c1fec pc=0xb0874 May 26 17:23:10 volumio go-librespot[4306]: created by net/http.(*Server).Serve in goroutine 20 May 26 17:23:10 volumio go-librespot[4306]: /usr/local/go/src/net/http/server.go:3493 +0x42c May 26 17:23:10 volumio go-librespot[4305]: Aborted May 26 17:23:13 volumio volumio[1130]: info: Initializing connection to go-librespot Websocket May 26 17:23:13 volumio volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 26 17:23:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. May 26 17:23:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 17:23:13 volumio systemd[1]: go-librespot-daemon.service: Consumed 2.254s CPU time. May 26 17:23:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 17:23:13 volumio go-librespot[19894]: go-librespot daemon starting... May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=info msg="running go-librespot 0.7.1" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=debug msg="app state loaded" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=info msg="zeroconf server listening on port 45495" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=debug msg="obtained new client token: AACSwh7eBiEKTx8a7y3giALk0yNMghk6fRup7tj7fmnQLXF/I0VlZHLwywicZcSD81q/J3DR961BlW02bybPL28cbd3HyExyWbnveWW029jCVYccaBb8GbSDsuSj5sTvI9Zt4fet8Q11hMVBfNWSYId7BKqTZyftCtVFQWIqOEDMz2VYUJ8YkWOnrXBCJODxyqCJLDW22Js3R4XT/wG1otj0f5nRGYLPo5PEafObR9bYKZLcVlyulyyDRFo=" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" May 26 17:23:14 volumio go-librespot[19895]: time="2026-05-26T17:23:14+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed to connect to AP ap-gew4.spotify.com:80: dial tcp: lookup ap-gew4.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed to connect to AP ap-gew4.spotify.com:80: dial tcp: lookup ap-gew4.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" May 26 17:23:15 volumio go-librespot[19895]: time="2026-05-26T17:23:15+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed to connect to AP ap-gew4.spotify.com:80: dial tcp: lookup ap-gew4.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed to connect to AP ap-gew4.spotify.com:80: dial tcp: lookup ap-gew4.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed to connect to AP ap-gew4.spotify.com:80: dial tcp: lookup ap-gew4.spotify.com: device or resource busy" May 26 17:23:16 volumio volumio[1130]: info: Initializing connection to go-librespot Websocket May 26 17:23:16 volumio go-librespot[19895]: time="2026-05-26T17:23:16+03:00" level=debug msg="new websocket client" May 26 17:23:16 volumio volumio[1130]: info: Connection to go-librespot Websocket established May 26 17:23:17 volumio go-librespot[19895]: time="2026-05-26T17:23:17+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:17 volumio go-librespot[19895]: time="2026-05-26T17:23:17+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:17 volumio go-librespot[19895]: time="2026-05-26T17:23:17+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" May 26 17:23:17 volumio go-librespot[19895]: time="2026-05-26T17:23:17+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" May 26 17:23:17 volumio go-librespot[19895]: time="2026-05-26T17:23:17+03:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" May 26 17:23:17 volumio go-librespot[19895]: time="2026-05-26T17:23:17+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed to connect to AP ap-gew4.spotify.com:80: dial tcp: lookup ap-gew4.spotify.com: device or resource busy" May 26 17:23:17 volumio go-librespot[19895]: time="2026-05-26T17:23:17+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed to connect to AP ap-gew4.spotify.com:80: dial tcp: lookup ap-gew4.spotify.com: device or resource busy" May 26 17:23:17 volumio volumio[1130]: info: Connection to go-librespot Websocket closed May 26 17:23:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 17:23:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 17:23:19 volumio volumio[1130]: info: Getting Spotify volume May 26 17:23:19 volumio volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 17:23:19 volumio volumio[1130]: Error: connect ECONNREFUSED 127.0.0.1:9879 May 26 17:23:19 volumio volumio[1130]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 26 17:23:19 volumio volumio[1130]: errno: -111, May 26 17:23:19 volumio volumio[1130]: code: 'ECONNREFUSED', May 26 17:23:19 volumio volumio[1130]: syscall: 'connect', May 26 17:23:19 volumio volumio[1130]: address: '127.0.0.1', May 26 17:23:19 volumio volumio[1130]: port: 9879, May 26 17:23:19 volumio volumio[1130]: response: undefined May 26 17:23:19 volumio volumio[1130]: } May 26 17:23:19 volumio volumio[1130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 17:23:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. May 26 17:23:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 17:23:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 17:23:20 volumio go-librespot[19930]: go-librespot daemon starting... May 26 17:23:20 volumio go-librespot[19931]: time="2026-05-26T17:23:20+03:00" level=info msg="running go-librespot 0.7.1" May 26 17:23:20 volumio go-librespot[19931]: time="2026-05-26T17:23:20+03:00" level=debug msg="app state loaded" May 26 17:23:20 volumio go-librespot[19931]: time="2026-05-26T17:23:20+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 17:23:20 volumio sudo[19941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 17:22' May 26 17:23:20 volumio sudo[19941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 17:23:20 volumio go-librespot[19931]: time="2026-05-26T17:23:20+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 26 17:23:20 volumio go-librespot[19931]: time="2026-05-26T17:23:20+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 26 17:23:20 volumio go-librespot[19931]: time="2026-05-26T17:23:20+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"