Mar 26 20:44:03 volumioadi go-librespot[6962]: time="2026-03-26T20:44:03+01: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" Mar 26 20:44:04 volumioadi bash[7709]: setdatetime-helper: all HTTPS Date fallbacks failed Mar 26 20:44:04 volumioadi systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 26 20:44:04 volumioadi systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 26 20:44:04 volumioadi systemd[1]: setdatetime-helper.service: Consumed 1.008s CPU time. Mar 26 20:44:06 volumioadi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 169. Mar 26 20:44:06 volumioadi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:44:06 volumioadi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:44:06 volumioadi upmpdcli[7938]: Could not open config: /tmp/upmpdcli.conf Mar 26 20:44:06 volumioadi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:44:06 volumioadi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 26 20:44:11 volumioadi go-librespot[6962]: time="2026-03-26T20:44:11+01:00" level=error msg="did not receive last pong from dealer, 180s passed" Mar 26 20:44:11 volumioadi go-librespot[6962]: time="2026-03-26T20:44:11+01:00" level=error msg="did not receive last pong ack from accesspoint, 240s passed" Mar 26 20:44:11 volumioadi go-librespot[6962]: panic: runtime error: invalid memory address or nil pointer dereference Mar 26 20:44:11 volumioadi go-librespot[6962]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f7ef0] Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 75 gp=0x21c8c68 m=4 mp=0x204d808 [running]: Mar 26 20:44:11 volumioadi go-librespot[6962]: panic({0x841d88, 0xfa0250}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x2279f20 sp=0x2279ecc pc=0xa92ec Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.panicmem(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/panic.go:262 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.sigpanic() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x2279f50 sp=0x2279f20 pc=0xabca4 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x241c6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:380 +0x284 fp=0x2279fe4 sp=0x2279f54 pc=0x4f7ef0 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:277 +0x28 fp=0x2279fec sp=0x2279fe4 pc=0x4f73c4 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2279fec sp=0x2279fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 88 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:277 +0x15c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 1 gp=0x2002128 m=nil [select, 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2059ce0 sp=0x2059ccc pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.selectgo(0x2059df0, 0x21d3dbc, 0x0, 0x0, 0x2, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2059d90 sp=0x2059ce0 pc=0x85000 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x2184000, 0x2059e90) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x2059e38 sp=0x2059d90 pc=0x62f43c Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*App).withAppPlayer(0x2196400, {0xa3da1c, 0x1005090}, 0x211f600) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x2059ed0 sp=0x2059e38 pc=0x6bcc60 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*App).withCredentials(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:209 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*App).SpotifyToken(0x2196400, {0xa3da1c, 0x1005090}, {0x218e820, 0x1c}, {0x21ae140, 0x13e}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x2059f00 sp=0x2059ed0 pc=0x6bc02c Mar 26 20:44:11 volumioadi go-librespot[6962]: main.main() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:620 +0x660 fp=0x2059fa8 sp=0x2059f00 pc=0x6bf56c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.main() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x2059fec sp=0x2059fa8 pc=0x6f390 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2059fec sp=0x2059fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 2 gp=0x20027e8 m=nil [force gc (idle), 4 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2048fd4 sp=0x2048fc0 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goparkunlock(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:466 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.forcegchelper() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2048fec sp=0x2048fd4 pc=0x6f7f4 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2048fec sp=0x2048fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.init.6 in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:361 +0x1c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 3 gp=0x2002b48 m=nil [GC sweep wait]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20497c4 sp=0x20497b0 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goparkunlock(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:466 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.bgsweep(0x202e050) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x20497e4 sp=0x20497c4 pc=0x5768c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcenable.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x20497ec sp=0x20497e4 pc=0x46c3c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20497ec sp=0x20497ec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.gcenable in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 4 gp=0x2002c68 m=nil [GC scavenge wait]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97746c, 0xffa170, 0xd, 0xa, 0x2) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2049fb4 sp=0x2049fa0 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goparkunlock(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:466 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.(*scavengerState).park(0xffa170) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2049fc8 sp=0x2049fb4 pc=0x54a28 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.bgscavenge(0x202e050) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2049fe4 sp=0x2049fc8 pc=0x5516c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcenable.gowrap2() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2049fec sp=0x2049fe4 pc=0x46be8 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2049fec sp=0x2049fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.gcenable in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 5 gp=0x2002fc8 m=nil [GOMAXPROCS updater (idle), 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x204a7a4 sp=0x204a790 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goparkunlock(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:466 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.updateMaxProcsGoroutine() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x204a7ec sp=0x204a7a4 pc=0x7f174 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x204a7ec sp=0x204a7ec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 6 gp=0x2003328 m=nil [finalizer wait]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x204af88 sp=0x204af74 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.runFinalizers() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x204afec sp=0x204af88 pc=0x45888 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x204afec sp=0x204afec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.createfing in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 18 gp=0x2103448 m=nil [cleanup wait, 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20447a4 sp=0x2044790 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goparkunlock(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:466 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.(*cleanupQueue).dequeue(0xffa2e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x20447c4 sp=0x20447a4 pc=0x41cb0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.runCleanups() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x20447ec sp=0x20447c4 pc=0x4284c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20447ec sp=0x20447ec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 21 gp=0x21c86c8 m=nil [IO wait, 4 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97745c, 0x76164e10, 0x2, 0x2, 0x5) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2278cf0 sp=0x2278cdc pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.netpollblock(0x76164e00, 0x72, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2278d08 sp=0x2278cf0 pc=0x675a0 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.runtime_pollWait(0x76164e00, 0x72) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2278d1c sp=0x2278d08 pc=0xa8864 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*pollDesc).wait(0x2183328, 0x72, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2278d30 sp=0x2278d1c pc=0x136044 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*pollDesc).waitRead(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*FD).Accept(0x2183310) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2278d78 sp=0x2278d30 pc=0x13a800 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*netFD).accept(0x2183310) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x2278de0 sp=0x2278d78 pc=0x1abc28 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*TCPListener).accept(0x2180450) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2278e34 sp=0x2278de0 pc=0x1c3554 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*TCPListener).Accept(0x2180450) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x2278e50 sp=0x2278e34 pc=0x1c259c Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.(*onceCloseListener).Accept(0x22004c0) Mar 26 20:44:11 volumioadi go-librespot[6962]: :1 +0x34 fp=0x2278e68 sp=0x2278e50 pc=0x367f9c Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.(*Server).Serve(0x2204168, {0xa3d218, 0x2180450}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x2278f00 sp=0x2278e68 pc=0x341154 Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.Serve(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:2971 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*ConcreteApiServer).serve(0x2184540) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x2278fe4 sp=0x2278f00 pc=0x6b17bc Mar 26 20:44:11 volumioadi go-librespot[6962]: main.NewApiServer.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x2278fec sp=0x2278fe4 pc=0x6b04f4 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2278fec sp=0x2278fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by main.NewApiServer in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/api_server.go:332 +0x310 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 7 gp=0x20f8248 m=nil [GC worker (idle), 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x977320, 0x2420200, 0x1c, 0xa, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2048788 sp=0x2048774 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcBgMarkWorker(0x243e0c0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x20487e4 sp=0x2048788 pc=0x49f34 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x20487ec sp=0x20487e4 pc=0x49e14 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20487ec sp=0x20487ec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 8 gp=0x20f8368 m=nil [GC worker (idle), 4 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x977320, 0x2420400, 0x1c, 0xa, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2416788 sp=0x2416774 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcBgMarkWorker(0x243e0c0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x24167e4 sp=0x2416788 pc=0x49f34 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x24167ec sp=0x24167e4 pc=0x49e14 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24167ec sp=0x24167ec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 9 gp=0x20f8488 m=nil [GC worker (idle), 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x977320, 0x2420600, 0x1c, 0xa, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x204bf88 sp=0x204bf74 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcBgMarkWorker(0x243e0c0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x204bfe4 sp=0x204bf88 pc=0x49f34 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x204bfec sp=0x204bfe4 pc=0x49e14 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x204bfec sp=0x204bfec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 10 gp=0x20f85a8 m=nil [GC worker (idle)]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x977320, 0x2420800, 0x1c, 0xa, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2276f88 sp=0x2276f74 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcBgMarkWorker(0x243e0c0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2276fe4 sp=0x2276f88 pc=0x49f34 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2276fec sp=0x2276fe4 pc=0x49e14 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2276fec sp=0x2276fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 36 gp=0x2203688 m=nil [chan receive, 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9772f4, 0x21bc2b8, 0xe, 0x7, 0x2) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2445778 sp=0x2445764 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.chanrecv(0x21bc280, 0x0, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x24457c0 sp=0x2445778 pc=0x3414c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.chanrecv1(0x21bc280, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x24457d4 sp=0x24457c0 pc=0x33bcc Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/godbus/dbus/v5.newConn.func1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x24457ec sp=0x24457d4 pc=0x4aa8ec Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24457ec sp=0x24457ec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 27 gp=0x21c8008 m=nil [IO wait, 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97745c, 0x76164810, 0x2, 0x2, 0x5) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2274cbc sp=0x2274ca8 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.netpollblock(0x76164800, 0x72, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2274cd4 sp=0x2274cbc pc=0x675a0 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.runtime_pollWait(0x76164800, 0x72) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2274ce8 sp=0x2274cd4 pc=0xa8864 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*pollDesc).wait(0x23a80b8, 0x72, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2274cfc sp=0x2274ce8 pc=0x136044 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*pollDesc).waitRead(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*FD).ReadMsg(0x23a80a0, {0x2012220, 0x10, 0x10}, {0x23ca618, 0x1000, 0x1000}, 0x40000000) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x2274d6c sp=0x2274cfc pc=0x1381bc Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*netFD).readMsg(0x23a80a0, {0x2012220, 0x10, 0x10}, {0x23ca618, 0x1000, 0x1000}, 0x40000000) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x2274dc8 sp=0x2274d6c pc=0x1aa2c8 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*UnixConn).readMsg(0x23b21a8, {0x2012220, 0x10, 0x10}, {0x23ca618, 0x1000, 0x1000}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x2274e28 sp=0x2274dc8 pc=0x1c9d80 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*UnixConn).ReadMsgUnix(0x23b21a8, {0x2012220, 0x10, 0x10}, {0x23ca618, 0x1000, 0x1000}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x2274e78 sp=0x2274e28 pc=0x1c820c Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/godbus/dbus/v5.(*oobReader).Read(0x23ca608, {0x2012220, 0x10, 0x10}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x2274ec8 sp=0x2274e78 pc=0x4c2af0 Mar 26 20:44:11 volumioadi go-librespot[6962]: io.ReadAtLeast({0xa39d58, 0x23ca608}, {0x2012220, 0x10, 0x10}, 0x10) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2274ef4 sp=0x2274ec8 pc=0xee954 Mar 26 20:44:11 volumioadi go-librespot[6962]: io.ReadFull(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/io/io.go:354 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x23860c0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x2274fa0 sp=0x2274ef4 pc=0x4c32c4 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x20a6400) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x2274fe4 sp=0x2274fa0 pc=0x4aaef4 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x2274fec sp=0x2274fe4 pc=0x4a8318 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2274fec sp=0x2274fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 88 gp=0x21c8128 m=nil [select, 4 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24cd930 sp=0x24cd91c pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.selectgo(0x24cdf70, 0x24cdb20, 0x0, 0x0, 0xa, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x24cd9e0 sp=0x24cd930 pc=0x85000 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*AppPlayer).Run(0x228bae0, {0xa3da1c, 0x1005090}, 0x207a380, 0x207a3c0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/player.go:661 +0x368 fp=0x24cdfd4 sp=0x24cd9e0 pc=0x6c5950 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*App).withAppPlayer.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:274 +0x48 fp=0x24cdfec sp=0x24cdfd4 pc=0x6bd9f0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24cdfec sp=0x24cdfec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by main.(*App).withAppPlayer in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:274 +0x46c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 203 gp=0x21c8368 m=nil [select]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24adb60 sp=0x24adb4c pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.selectgo(0x24adc68, 0x24adc38, 0x0, 0x0, 0x2, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x24adc10 sp=0x24adb60 pc=0x85000 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.doBlockingWithCtx[...]({0xa3da54, 0x21f9890}, {0x218c360, 0x13}, 0x22f2c60) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/cgo_unix.go:71 +0x280 fp=0x24adc90 sp=0x24adc10 pc=0x1cd880 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.cgoLookupIP({0xa3da54, 0x21f9890}, {0x919486, 0x3}, {0x218c360, 0x13}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/cgo_unix.go:228 +0x98 fp=0x24adcc0 sp=0x24adc90 pc=0x19d584 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*Resolver).lookupIP(0xff9700, {0xa3da54, 0x21f9890}, {0x919486, 0x3}, {0x218c360, 0x13}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/lookup_unix.go:64 +0x90 fp=0x24adefc sp=0x24adcc0 pc=0x1b8c5c Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*Resolver).lookupIP-fm({0xa3da54, 0x21f9890}, {0x919486, 0x3}, {0x218c360, 0x13}) Mar 26 20:44:11 volumioadi go-librespot[6962]: :1 +0x58 fp=0x24adf30 sp=0x24adefc pc=0x1d3574 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.init.func1({0xa3da54, 0x21f9890}, 0x2410960, {0x919486, 0x3}, {0x218c360, 0x13}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/hook.go:21 +0x4c fp=0x24adf60 sp=0x24adf30 pc=0x19abf8 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*Resolver).lookupIPAddr.func1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/lookup.go:335 +0x58 fp=0x24adf9c sp=0x24adf60 pc=0x1b849c Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/singleflight.(*Group).doCall(0xff9708, 0x21f98c0, {0x2404570, 0x17}, 0x23846e0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x24adfd4 sp=0x24adf9c pc=0x198e98 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/singleflight.(*Group).DoChan.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x24adfec sp=0x24adfd4 pc=0x198e50 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24adfec sp=0x24adfec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by internal/singleflight.(*Group).DoChan in goroutine 74 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x304 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 87 gp=0x2003688 m=nil [select, 4 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2449e74 sp=0x2449e60 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.selectgo(0x2449f80, 0x2449f4c, 0x0, 0x0, 0x3, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2449f24 sp=0x2449e74 pc=0x85000 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x2581c20) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/player/player.go:215 +0x1f4 fp=0x2449fe4 sp=0x2449f24 pc=0x582954 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/player/player.go:197 +0x28 fp=0x2449fec sp=0x2449fe4 pc=0x58253c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2449fec sp=0x2449fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/player/player.go:197 +0x220 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 89 gp=0x20f8a28 m=nil [chan receive, 4 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9772f4, 0x21bc178, 0xe, 0x7, 0x2) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2445f3c sp=0x2445f28 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.chanrecv(0x21bc140, 0x2445fd0, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2445f84 sp=0x2445f3c pc=0x3414c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.chanrecv2(0x21bc140, 0x2445fd0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x2445f98 sp=0x2445f84 pc=0x33bf0 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*App).withAppPlayer.func1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:284 +0x68 fp=0x2445fec sp=0x2445f98 pc=0x6bd918 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2445fec sp=0x2445fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by main.(*App).withAppPlayer in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:281 +0x4f8 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 90 gp=0x20f8c68 m=nil [chan receive, 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9772f4, 0x21bc138, 0xe, 0x7, 0x2) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2446f40 sp=0x2446f2c pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.chanrecv(0x21bc100, 0x2446fe0, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2446f88 sp=0x2446f40 pc=0x3414c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.chanrecv2(0x21bc100, 0x2446fe0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x2446f9c sp=0x2446f88 pc=0x33bf0 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*App).withAppPlayer.func2() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:300 +0x6c fp=0x2446fec sp=0x2446f9c pc=0x6bd47c Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2446fec sp=0x2446fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by main.(*App).withAppPlayer in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/main.go:297 +0x598 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 91 gp=0x20f8d88 m=nil [IO wait, 6 minutes]: Mar 26 20:44:11 volumioadi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 26 20:44:11 volumioadi volumio[6772]: info: Connection to go-librespot Websocket closed Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97745c, 0x76164a10, 0x2, 0x2, 0x5) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24475ac sp=0x2447598 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.netpollblock(0x76164a00, 0x72, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x24475c4 sp=0x24475ac pc=0x675a0 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.runtime_pollWait(0x76164a00, 0x72) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x24475d8 sp=0x24475c4 pc=0xa8864 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*pollDesc).wait(0x23a8068, 0x72, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24475ec sp=0x24475d8 pc=0x136044 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*pollDesc).waitRead(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*FD).Accept(0x23a8050) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2447634 sp=0x24475ec pc=0x13a800 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*netFD).accept(0x23a8050) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x244769c sp=0x2447634 pc=0x1abc28 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*TCPListener).accept(0x2388060) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x24476f0 sp=0x244769c pc=0x1c3554 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*TCPListener).Accept(0x2388060) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x244770c sp=0x24476f0 pc=0x1c259c Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.(*onceCloseListener).Accept(0x23770c0) Mar 26 20:44:11 volumioadi go-librespot[6962]: :1 +0x34 fp=0x2447724 sp=0x244770c pc=0x367f9c Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.(*Server).Serve(0x23729a8, {0xa3d218, 0x2388060}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x24477bc sp=0x2447724 pc=0x341154 Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.Serve(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:2971 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x24477ec sp=0x24477bc pc=0x62f538 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24477ec sp=0x24477ec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/zeroconf/zeroconf.go:297 +0x160 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 76 gp=0x21c8d88 m=nil [select]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2057dc0 sp=0x2057dac pc=0xa97c0 Mar 26 20:44:11 volumioadi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.selectgo(0x2057ec4, 0x2057e98, 0x0, 0x0, 0x2, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2057e70 sp=0x2057dc0 pc=0x85000 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x2057f14, {0xa3c8c0, 0x258a8a0}, 0x0, {0x0, 0x0}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:112 +0x244 fp=0x2057eec sp=0x2057e70 pc=0x4eb7bc Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x2057f68, {0xa3c8c0, 0x258a8a0}, 0x0, {0x0, 0x0}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x2057f1c sp=0x2057eec pc=0x4eb180 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.RetryNotify(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.Retry(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x241c168) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/dealer/dealer.go:237 +0x688 fp=0x2057fe4 sp=0x2057f1c pc=0x5a285c Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/dealer/dealer.go:125 +0x28 fp=0x2057fec sp=0x2057fe4 pc=0x5a3d28 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2057fec sp=0x2057fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 88 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/dealer/dealer.go:125 +0x98 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 74 gp=0x20f8ea8 m=nil [select]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x205c868 sp=0x205c854 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.selectgo(0x205c9d8, 0x205c944, 0x0, 0x0, 0x2, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x205c918 sp=0x205c868 pc=0x85000 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*Resolver).lookupIPAddr(0xff9700, {0xa3da8c, 0x228b310}, {0x919486, 0x3}, {0x218c360, 0x13}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/lookup.go:343 +0x394 fp=0x205ca20 sp=0x205c918 pc=0x1b7b00 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*Resolver).internetAddrList(0xff9700, {0xa3da8c, 0x228b310}, {0x919486, 0x3}, {0x218c360, 0x17}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/ipsock.go:289 +0x6b0 fp=0x205cab4 sp=0x205ca20 pc=0x1b5abc Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*Resolver).resolveAddrList(0xff9700, {0xa3da8c, 0x228b310}, {0x9198a7, 0x4}, {0x919486, 0x3}, {0x218c360, 0x17}, {0x0, ...}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/dial.go:353 +0x510 fp=0x205cb3c sp=0x205cab4 pc=0x1a03f8 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*Dialer).DialContext(0x205cc68, {0xa3da8c, 0x228b310}, {0x919486, 0x3}, {0x218c360, 0x17}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/dial.go:560 +0x43c fp=0x205cc38 sp=0x205cb3c pc=0x1a1334 Mar 26 20:44:11 volumioadi go-librespot[6962]: golang.org/x/net/proxy.direct.DialContext(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 Mar 26 20:44:11 volumioadi go-librespot[6962]: golang.org/x/net/proxy.(*direct).DialContext(0x1005090, {0xa3da8c, 0x228b310}, {0x919486, 0x3}, {0x218c360, 0x17}) Mar 26 20:44:11 volumioadi go-librespot[6962]: :1 +0x74 fp=0x205cccc sp=0x205cc38 pc=0x4f2fcc Mar 26 20:44:11 volumioadi go-librespot[6962]: golang.org/x/net/proxy.Dial({0xa3da8c, 0x228b310}, {0x919486, 0x3}, {0x218c360, 0x17}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0xac fp=0x205cd08 sp=0x205cccc pc=0x4f0e90 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0x241c6e8, {0xa3da1c, 0x1005090}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:95 +0x2a8 fp=0x205cd80 sp=0x205cd08 pc=0x4f5950 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0x241c6e8, {0xa3da1c, 0x1005090}, 0x23884b0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:205 +0xd4 fp=0x205cde4 sp=0x205cd80 pc=0x4f6a58 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0x241c6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:394 +0xcc fp=0x205ce0c sp=0x205cde4 pc=0x4f8018 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Mar 26 20:44:11 volumioadi go-librespot[6962]: :1 +0x28 fp=0x205ce1c sp=0x205ce0c pc=0x4fc270 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x205ce28 sp=0x205ce1c pc=0x4eb1c8 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x205cecc, {0xa3c8c0, 0x258a7e0}, 0x0, {0x0, 0x0}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x205cea4 sp=0x205ce28 pc=0x4eb644 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x205cf5c, {0xa3c8c0, 0x258a7e0}, 0x0, {0x0, 0x0}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x205ced4 sp=0x205cea4 pc=0x4eb180 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.RetryNotify(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/cenkalti/backoff/v4.Retry(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x241c6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:335 +0x434 fp=0x205cfe4 sp=0x205ced4 pc=0x4f7878 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:273 +0x28 fp=0x205cfec sp=0x205cfe4 pc=0x4f7418 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x205cfec sp=0x205cfec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 88 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/ap/ap.go:273 +0x98 Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 77 gp=0x21c8fc8 m=nil [select]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24aee84 sp=0x24aee70 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.selectgo(0x24aefd4, 0x24aef5c, 0x0, 0x0, 0x2, 0x1) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x24aef34 sp=0x24aee84 pc=0x85000 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x241c168) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/dealer/dealer.go:138 +0x8c fp=0x24aefe4 sp=0x24aef34 pc=0x5a1d90 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/dealer/dealer.go:129 +0x28 fp=0x24aefec sp=0x24aefe4 pc=0x5a3cd4 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24aefec sp=0x24aefec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 88 Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/dealer/dealer.go:129 +0x15c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 100 gp=0x21c98c8 m=nil [IO wait, 6 minutes]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.gopark(0x97745c, 0x7612bc10, 0x2, 0x2, 0x5) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24c9908 sp=0x24c98f4 pc=0xa97c0 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.netpollblock(0x7612bc00, 0x72, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x24c9920 sp=0x24c9908 pc=0x675a0 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.runtime_pollWait(0x7612bc00, 0x72) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x24c9934 sp=0x24c9920 pc=0xa8864 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*pollDesc).wait(0x228a3d8, 0x72, 0x0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24c9948 sp=0x24c9934 pc=0x136044 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*pollDesc).waitRead(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 26 20:44:11 volumioadi go-librespot[6962]: internal/poll.(*FD).Read(0x228a3c0, {0x21ef000, 0x1000, 0x1000}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x24c9990 sp=0x24c9948 pc=0x1371c4 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*netFD).Read(0x228a3c0, {0x21ef000, 0x1000, 0x1000}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x24c99bc sp=0x24c9990 pc=0x1a9e78 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.(*conn).Read(0x2113c48, {0x21ef000, 0x1000, 0x1000}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x24c99e8 sp=0x24c99bc pc=0x1b967c Mar 26 20:44:11 volumioadi go-librespot[6962]: io.(*multiReader).Read(0x23863d0, {0x21ef000, 0x1000, 0x1000}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x24c9a14 sp=0x24c99e8 pc=0xefb88 Mar 26 20:44:11 volumioadi go-librespot[6962]: bufio.(*Reader).fill(0x226f5f0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x24c9a38 sp=0x24c9a14 pc=0x2b6c1c Mar 26 20:44:11 volumioadi go-librespot[6962]: bufio.(*Reader).ReadByte(0x226f5f0) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x24c9a44 sp=0x24c9a38 pc=0x2b7498 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/coder/websocket.readFrameHeader(0x226f5f0, {0x20dc338, 0x8, 0x8}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x24c9a84 sp=0x24c9a44 pc=0x372a08 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/coder/websocket.(*Conn).readFrameHeader(0x20dc308, {0xa3da00, 0x1005090}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x24c9b08 sp=0x24c9a84 pc=0x375bd4 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/coder/websocket.(*Conn).readLoop(0x20dc308, {0xa3da00, 0x1005090}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x24c9bcc sp=0x24c9b08 pc=0x375390 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/coder/websocket.(*Conn).reader(0x20dc308, {0xa3da00, 0x1005090}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x24c9c50 sp=0x24c9bcc pc=0x376798 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/coder/websocket.(*Conn).Reader(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Mar 26 20:44:11 volumioadi go-librespot[6962]: github.com/coder/websocket.(*Conn).Read(0x20dc308, {0xa3da00, 0x1005090}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x24c9c78 sp=0x24c9c50 pc=0x374944 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x238a848}, 0x238a6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x24c9cfc sp=0x24c9c78 pc=0x6b2040 Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.HandlerFunc.ServeHTTP(0x2218248, {0xa3d2d8, 0x238a848}, 0x238a6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x24c9d0c sp=0x24c9cfc pc=0x33d7d8 Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.(*ServeMux).ServeHTTP(0x2210180, {0xa3d2d8, 0x238a848}, 0x238a6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x24c9d4c sp=0x24c9d0c pc=0x33f6c8 Mar 26 20:44:11 volumioadi go-librespot[6962]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x238a848}, 0x238a6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x24c9d74 sp=0x24c9d4c pc=0x6b19b8 Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.HandlerFunc.ServeHTTP(0x2208290, {0xa3d2d8, 0x238a848}, 0x238a6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x24c9d84 sp=0x24c9d74 pc=0x33d7d8 Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.serverHandler.ServeHTTP({0x2204168}, {0xa3d2d8, 0x238a848}, 0x238a6e8) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x24c9da0 sp=0x24c9d84 pc=0x35cb44 Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.(*conn).serve(0x222c9c0, {0xa3da38, 0x224e288}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x24c9fdc sp=0x24c9da0 pc=0x33b894 Mar 26 20:44:11 volumioadi go-librespot[6962]: net/http.(*Server).Serve.gowrap3() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x24c9fec sp=0x24c9fdc pc=0x3415cc Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24c9fec sp=0x24c9fec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by net/http.(*Server).Serve in goroutine 21 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 26 20:44:11 volumioadi go-librespot[6962]: goroutine 204 gp=0x21c9d48 m=8 mp=0x207d808 [syscall]: Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.cgocall(0x75ea90, 0x24186fc) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/cgocall.go:167 +0x50 fp=0x24186e4 sp=0x24186cc pc=0xa628c Mar 26 20:44:11 volumioadi go-librespot[6962]: net._C2func_getaddrinfo(0x2404588, 0x0, 0x2384700, 0x2410978) Mar 26 20:44:11 volumioadi go-librespot[6962]: _cgo_gotypes.go:105 +0x44 fp=0x24186f8 sp=0x24186e4 pc=0x1caa18 Mar 26 20:44:11 volumioadi go-librespot[6962]: net._C_getaddrinfo.func1(...) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/cgo_unix_cgo.go:84 Mar 26 20:44:11 volumioadi go-librespot[6962]: net._C_getaddrinfo(0x2404588, 0x0, 0x2384700, 0x2410978) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/cgo_unix_cgo.go:84 +0x80 fp=0x2418718 sp=0x24186f8 pc=0x1cacdc Mar 26 20:44:11 volumioadi go-librespot[6962]: net.cgoLookupHostIP({0x919486, 0x3}, {0x218c360, 0x13}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/cgo_unix.go:174 +0x1f4 fp=0x241878c sp=0x2418718 pc=0x19cfe0 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.cgoLookupIP.func1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/cgo_unix.go:229 +0x34 fp=0x24187b4 sp=0x241878c pc=0x19d604 Mar 26 20:44:11 volumioadi go-librespot[6962]: net.doBlockingWithCtx[...].func1() Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/cgo_unix.go:67 +0x54 fp=0x24187ec sp=0x24187b4 pc=0x1cdac8 Mar 26 20:44:11 volumioadi go-librespot[6962]: runtime.goexit({}) Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24187ec sp=0x24187ec pc=0xb0874 Mar 26 20:44:11 volumioadi go-librespot[6962]: created by net.doBlockingWithCtx[...] in goroutine 203 Mar 26 20:44:11 volumioadi go-librespot[6962]: /usr/local/go/src/net/cgo_unix.go:64 +0x204 Mar 26 20:44:11 volumioadi go-librespot[6959]: Aborted Mar 26 20:44:14 volumioadi volumio[6772]: info: Initializing connection to go-librespot Websocket Mar 26 20:44:14 volumioadi volumio[6772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 20:44:14 volumioadi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 26 20:44:14 volumioadi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:44:14 volumioadi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:44:14 volumioadi go-librespot[7954]: go-librespot daemon starting... Mar 26 20:44:14 volumioadi go-librespot[7955]: time="2026-03-26T20:44:14+01:00" level=info msg="running go-librespot 0.7.1" Mar 26 20:44:14 volumioadi go-librespot[7955]: time="2026-03-26T20:44:14+01:00" level=debug msg="app state loaded" Mar 26 20:44:14 volumioadi go-librespot[7955]: time="2026-03-26T20:44:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 20:44:17 volumioadi volumio[6772]: info: Initializing connection to go-librespot Websocket Mar 26 20:44:17 volumioadi go-librespot[7955]: time="2026-03-26T20:44:17+01:00" level=debug msg="new websocket client" Mar 26 20:44:17 volumioadi volumio[6772]: info: Connection to go-librespot Websocket established Mar 26 20:44:20 volumioadi volumio[6772]: info: Getting Spotify volume Mar 26 20:44:20 volumioadi volumio[6772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 26 20:44:20 volumioadi volumio[6772]: info: CoreCommandRouter::volumioGetState Mar 26 20:44:20 volumioadi volumio[6772]: info: CorePlayQueue::getTrack 23 Mar 26 20:44:20 volumioadi volumio[6772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Mar 26 20:44:21 volumioadi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 170. Mar 26 20:44:21 volumioadi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:44:21 volumioadi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:44:21 volumioadi upmpdcli[7964]: Could not open config: /tmp/upmpdcli.conf Mar 26 20:44:21 volumioadi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:44:21 volumioadi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 26 20:44:27 volumioadi go-librespot[7955]: time="2026-03-26T20:44:27+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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 26 20:44:27 volumioadi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:44:27 volumioadi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 20:44:27 volumioadi volumio[6772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:44:27 volumioadi volumio[6772]: Error: socket hang up Mar 26 20:44:27 volumioadi volumio[6772]: at connResetException (node:internal/errors:720:14) Mar 26 20:44:27 volumioadi volumio[6772]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 26 20:44:27 volumioadi volumio[6772]: at Socket.emit (node:events:526:35) Mar 26 20:44:27 volumioadi volumio[6772]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 26 20:44:27 volumioadi volumio[6772]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 26 20:44:27 volumioadi volumio[6772]: code: 'ECONNRESET', Mar 26 20:44:27 volumioadi volumio[6772]: response: undefined Mar 26 20:44:27 volumioadi volumio[6772]: } Mar 26 20:44:27 volumioadi volumio[6772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:44:27 volumioadi sudo[7994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 20:43' Mar 26 20:44:27 volumioadi sudo[7994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:28 volumioadi sudo[7994]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:30 volumioadi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 26 20:44:30 volumioadi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:44:30 volumioadi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:44:30 volumioadi go-librespot[8005]: go-librespot daemon starting... Mar 26 20:44:30 volumioadi go-librespot[8006]: time="2026-03-26T20:44:30+01:00" level=info msg="running go-librespot 0.7.1" Mar 26 20:44:30 volumioadi go-librespot[8006]: time="2026-03-26T20:44:30+01:00" level=debug msg="app state loaded" Mar 26 20:44:30 volumioadi go-librespot[8006]: time="2026-03-26T20:44:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 20:44:36 volumioadi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 171. Mar 26 20:44:36 volumioadi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:44:36 volumioadi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:44:36 volumioadi upmpdcli[8028]: Could not open config: /tmp/upmpdcli.conf Mar 26 20:44:37 volumioadi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:44:37 volumioadi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 26 20:44:40 volumioadi go-librespot[8006]: time="2026-03-26T20:44:40+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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 26 20:44:40 volumioadi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:44:40 volumioadi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 20:44:40 volumioadi volumio-remote-updater[695]: [2026-03-26 20:44:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 26 20:44:40 volumioadi volumio-remote-updater[695]: [2026-03-26 20:44:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 26 20:44:40 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:40.659+01:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 26 20:44:40 volumioadi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:44:40 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:40.666+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 26 20:44:40 volumioadi ntfs-3g[6848]: Unmounting /dev/sda1 (My Passport) Mar 26 20:44:40 volumioadi systemd[1]: media-My_Passport.mount: Deactivated successfully. Mar 26 20:44:40 volumioadi systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 26 20:44:40 volumioadi systemd[1]: volumio.service: Consumed 59.858s CPU time. Mar 26 20:44:40 volumioadi systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 26 20:44:40 volumioadi systemd[1]: dynamicswap.service: Deactivated successfully. Mar 26 20:44:40 volumioadi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Mar 26 20:44:40 volumioadi systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 26 20:44:40 volumioadi systemd[1]: Stopped volumio.service - Volumio Backend Module. Mar 26 20:44:40 volumioadi systemd[1]: volumio.service: Consumed 59.858s CPU time. Mar 26 20:44:40 volumioadi systemd[1]: Started volumio.service - Volumio Backend Module. Mar 26 20:44:40 volumioadi systemd[1]: dynamicswap.service: Deactivated successfully. Mar 26 20:44:41 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:41.668+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 26 20:44:42 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:42.669+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 26 20:44:43 volumioadi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 26 20:44:43 volumioadi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:44:43 volumioadi volumio[8049]: info: ------------------------------------------- Mar 26 20:44:43 volumioadi volumio[8049]: info: ----- Volumio3 ---- Mar 26 20:44:43 volumioadi volumio[8049]: info: ------------------------------------------- Mar 26 20:44:43 volumioadi volumio[8049]: info: ----- System startup ---- Mar 26 20:44:43 volumioadi volumio[8049]: info: ------------------------------------------- Mar 26 20:44:43 volumioadi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:44:43 volumioadi go-librespot[8070]: go-librespot daemon starting... Mar 26 20:44:43 volumioadi go-librespot[8071]: time="2026-03-26T20:44:43+01:00" level=info msg="running go-librespot 0.7.1" Mar 26 20:44:43 volumioadi go-librespot[8071]: time="2026-03-26T20:44:43+01:00" level=debug msg="app state loaded" Mar 26 20:44:43 volumioadi go-librespot[8071]: time="2026-03-26T20:44:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 20:44:44 volumioadi volumio[8049]: info: MYVOLUMIO Environment detected Mar 26 20:44:44 volumioadi volumio[8049]: info: Plugin folders cleanup Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning into folder /volumio/app/plugins/ Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category audio_interface Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category miscellanea Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category music_service Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category plugins.json Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category system_controller Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category user_interface Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning into folder /data/plugins/ Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category music_service Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category system_controller Mar 26 20:44:44 volumioadi volumio[8049]: info: Scanning category system_hardware Mar 26 20:44:44 volumioadi volumio[8049]: info: Plugin folders cleanup completed Mar 26 20:44:44 volumioadi volumio[8049]: info: ------------------------------------------- Mar 26 20:44:44 volumioadi volumio[8049]: info: ----- Core plugins startup ---- Mar 26 20:44:44 volumioadi volumio[8049]: info: ------------------------------------------- Mar 26 20:44:44 volumioadi volumio[8049]: info: Loading plugins from folder /volumio/app/plugins/ Mar 26 20:44:44 volumioadi volumio[8049]: info: Adding plugin upnp to MyMusic Plugins Mar 26 20:44:44 volumioadi volumio[8049]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 26 20:44:44 volumioadi volumio[8049]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 26 20:44:44 volumioadi volumio[8049]: info: Loading plugins from folder /data/plugins/ Mar 26 20:44:44 volumioadi volumio[8049]: info: Loading plugin "system"... Mar 26 20:44:44 volumioadi volumio[8049]: info: Loading plugin "appearance"... Mar 26 20:44:45 volumioadi volumio-remote-updater[695]: [2026-03-26 20:44:45] [connect] Successful connection Mar 26 20:44:46 volumioadi volumio[8049]: info: Loading plugin "network"... Mar 26 20:44:46 volumioadi volumio[8049]: info: Refreshing Cached IP Addresses Mar 26 20:44:46 volumioadi sudo[8086]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 20:44:46 volumioadi sudo[8086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:46 volumioadi sudo[8088]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 20:44:46 volumioadi sudo[8088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:46 volumioadi sudo[8086]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:46 volumioadi volumio[8049]: info: Loading plugin "services"... Mar 26 20:44:46 volumioadi sudo[8088]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:46 volumioadi volumio[8049]: info: Loading plugin "volumio5onboarding"... Mar 26 20:44:46 volumioadi sudo[8095]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 26 20:44:46 volumioadi sudo[8095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:46 volumioadi volumio[8049]: info: Loading plugin "alsa_controller"... Mar 26 20:44:46 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 20:44:46 volumioadi volumio[8049]: info: Loading plugin "wizard"... Mar 26 20:44:46 volumioadi volumio[8049]: info: Loading plugin "networkfs"... Mar 26 20:44:46 volumioadi volumio[8049]: info: Starting Udev Watcher for removable devices Mar 26 20:44:46 volumioadi volumio[8049]: info: Ignoring mount for partition: boot Mar 26 20:44:46 volumioadi volumio[8049]: info: Ignoring mount for partition: volumio Mar 26 20:44:46 volumioadi volumio[8049]: info: Ignoring mount for partition: volumio_data Mar 26 20:44:46 volumioadi volumio[8049]: info: Mounting Device My_Passport Mar 26 20:44:46 volumioadi sudo[8125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/My_Passport -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 26 20:44:46 volumioadi sudo[8125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:47 volumioadi sudo[8095]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:47 volumioadi ntfs-3g[8129]: Version 2022.10.3 integrated FUSE 28 Mar 26 20:44:47 volumioadi ntfs-3g[8129]: Mounted /dev/sda1 (Read-Write, label "My Passport", NTFS 3.1) Mar 26 20:44:47 volumioadi ntfs-3g[8129]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 26 20:44:47 volumioadi ntfs-3g[8129]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Mar 26 20:44:47 volumioadi ntfs-3g[8129]: Global ownership and permissions enforced, configuration type 7 Mar 26 20:44:47 volumioadi sudo[8125]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:47 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 20:44:47 volumioadi volumio[8049]: info: Loading plugin "volumio_command_line_client"... Mar 26 20:44:47 volumioadi volumio[8049]: info: Plugin upnp is not enabled Mar 26 20:44:47 volumioadi volumio[8049]: info: Loading plugin "my_music"... Mar 26 20:44:47 volumioadi volumio[8049]: info: Loading plugin "mpd"... Mar 26 20:44:47 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:47.859+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" available=true connected=true macAddress=b8:27:eb:34:60:91 ip4Address=192.168.1.56/24 ip6Address= ssid=NetBoss Mar 26 20:44:48 volumioadi volumio[8049]: info: Plugin upnp_browser is not enabled Mar 26 20:44:48 volumioadi volumio[8049]: info: Loading plugin "alarm-clock"... Mar 26 20:44:48 volumioadi volumio[8049]: info: Plugin airplay_emulation is not enabled Mar 26 20:44:48 volumioadi volumio[8049]: info: Loading plugin "last_100"... Mar 26 20:44:48 volumioadi volumio[8049]: info: Loading plugin "webradio"... Mar 26 20:44:49 volumioadi volumio[8049]: info: Loading plugin "i2s_dacs"... Mar 26 20:44:49 volumioadi volumio[8049]: info: Loading plugin "volumiodiscovery"... Mar 26 20:44:50 volumioadi volumio[8049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 20:44:50 volumioadi volumio[8049]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 20:44:50 volumioadi volumio[8049]: *** WARNING *** For more information see Mar 26 20:44:50 volumioadi volumio[8049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 20:44:50 volumioadi volumio[8049]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 20:44:50 volumioadi volumio[8049]: *** WARNING *** For more information see Mar 26 20:44:50 volumioadi node[8049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 20:44:50 volumioadi node[8049]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 20:44:50 volumioadi node[8049]: *** WARNING *** For more information see Mar 26 20:44:50 volumioadi node[8049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 20:44:50 volumioadi node[8049]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 20:44:50 volumioadi node[8049]: *** WARNING *** For more information see Mar 26 20:44:50 volumioadi volumio[8049]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 26 20:44:50 volumioadi volumio[8049]: info: Discovery: Started advertising with name: VolumioADI Mar 26 20:44:50 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 20:44:50 volumioadi volumio[8049]: info: Loading plugin "spop"... Mar 26 20:44:51 volumioadi volumio[8049]: info: Loading plugin "outputs"... Mar 26 20:44:51 volumioadi volumio[8049]: info: Loading plugin "albumart"... Mar 26 20:44:51 volumioadi volumio[8049]: info: Plugin example_plugin is not enabled Mar 26 20:44:51 volumioadi volumio[8049]: info: Loading plugin "inputs"... Mar 26 20:44:51 volumioadi volumio[8049]: info: Loading plugin "updater_comm"... Mar 26 20:44:51 volumioadi volumio[8049]: info: Plugin mpdemulation is not enabled Mar 26 20:44:51 volumioadi volumio[8049]: info: Loading plugin "rest_api"... Mar 26 20:44:51 volumioadi volumio[8049]: info: Loading plugin "websocket"... Mar 26 20:44:51 volumioadi volumio[8049]: info: Starting Socket.io Server version 1.7.4 Mar 26 20:44:51 volumioadi volumio[8049]: info: Loading plugin "backup_restore"... Mar 26 20:44:52 volumioadi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 172. Mar 26 20:44:52 volumioadi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:44:52 volumioadi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:44:52 volumioadi volumio[8049]: info: Applying required configuration parameters for plugin backup_restore Mar 26 20:44:52 volumioadi volumio[8049]: info: Loading plugin "Bluetoothremote"... Mar 26 20:44:52 volumioadi upmpdcli[8144]: Could not open config: /tmp/upmpdcli.conf Mar 26 20:44:52 volumioadi volumio[8134]: Forking 3 albumart workers Mar 26 20:44:52 volumioadi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:44:52 volumioadi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 26 20:44:52 volumioadi volumio[8049]: info: Applying required configuration parameters for plugin Bluetoothremote Mar 26 20:44:52 volumioadi volumio[8049]: info: Loading i18n strings for locale pl Mar 26 20:44:52 volumioadi volumio[8049]: Updating browse sources language Mar 26 20:44:52 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::initPlayerControls Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: Express server listening on port 3000 Mar 26 20:44:53 volumioadi volumio[8049]: [Metrics] WebUI: 10s 112.67ms Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreStateMachine::resetVolumioState Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreStateMachine::getcurrentVolume Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 20:44:53 volumioadi volumio[8049]: info: Volumio Network Manager: Network status updated: 2 Mar 26 20:44:53 volumioadi volumio[8049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 20:44:53 volumioadi volumio[8049]: info: Reloading queue from file Mar 26 20:44:53 volumioadi volumio[8049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 26 20:44:53 volumioadi volumio[8049]: info: VolumeController:: Volume=56 Mute =false Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreStateMachine::pushState Mar 26 20:44:53 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::volumioPushState Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreStateMachine::updateTrackBlock Mar 26 20:44:53 volumioadi volumio[8049]: info: CorePlayQueue::getTrackBlock Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 20:44:53 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:53.746+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" state=STATUS_STOPPED positionMs=0 volume=56 Mar 26 20:44:53 volumioadi volumio[8147]: Starting albumart workers Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreStateMachine::setRepeat null single undefined Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreStateMachine::pushState Mar 26 20:44:53 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::volumioPushState Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreStateMachine::setRandom null Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreStateMachine::pushState Mar 26 20:44:53 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::volumioPushState Mar 26 20:44:53 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:53.785+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" state=STATUS_STOPPED positionMs=0 volume=56 Mar 26 20:44:53 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:53.786+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" id="mnt/USB/My_Passport/Spotify 02-2026 FLAC/Moja/Złe żądze - Rufuz, TPS, Jongmen.flac" title="Złe żądze - Rufuz, TPS, Jongmen.flac" Mar 26 20:44:53 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:53.792+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" state=STATUS_STOPPED positionMs=0 volume=56 Mar 26 20:44:53 volumioadi volumio[8049]: info: Setting Device type: Raspberry PI Mar 26 20:44:53 volumioadi volumio[8145]: Starting albumart workers Mar 26 20:44:53 volumioadi volumio[8049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 26 20:44:53 volumioadi volumio[8049]: info: Received Get System Info Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:44:53 volumioadi volumio[8049]: info: Discovery: Getting this device information Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:44:53 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:44:53 volumioadi volumio[8146]: Starting albumart workers Mar 26 20:44:53 volumioadi volumio[8049]: info: Completed loading Core Plugins Mar 26 20:44:53 volumioadi volumio[8049]: info: Preparing to generate the ALSA configuration file Mar 26 20:44:53 volumioadi volumio[8049]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 26 20:44:53 volumioadi volumio[8049]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a52082 Mar 26 20:44:53 volumioadi volumio[8049]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 20:44:53 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 20:44:53 volumioadi volumio[8049]: info: Asound.conf file unchanged, so no further update is needed Mar 26 20:44:53 volumioadi volumio[8049]: info: Output device has changed, restarting MPD Mar 26 20:44:53 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:53.972+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" id="mnt/USB/My_Passport/Spotify 02-2026 FLAC/Moja/Złe żądze - Rufuz, TPS, Jongmen.flac" title="Złe żądze - Rufuz, TPS, Jongmen.flac" Mar 26 20:44:54 volumioadi volumio[8049]: info: ___________ START PLUGINS ___________ Mar 26 20:44:54 volumioadi sudo[8191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 20:44:54 volumioadi sudo[8191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:54 volumioadi sudo[8191]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:54 volumioadi sudo[8193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 20:44:54 volumioadi volumio[8049]: info: ControllerMpd::onStart: Initializing MPD Mar 26 20:44:54 volumioadi volumio[8049]: info: Creating MPD Configuration file Mar 26 20:44:54 volumioadi sudo[8193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:54 volumioadi volumio[8049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 20:44:54 volumioadi volumio[8049]: info: [1774554294138] CoreMusicLibrary::Adding element Last_100 Mar 26 20:44:54 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 20:44:54 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:54.146+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" id="mnt/USB/My_Passport/Spotify 02-2026 FLAC/Moja/Złe żądze - Rufuz, TPS, Jongmen.flac" title="Złe żądze - Rufuz, TPS, Jongmen.flac" Mar 26 20:44:54 volumioadi sudo[8196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 26 20:44:54 volumioadi systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 26 20:44:54 volumioadi sudo[8196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:54 volumioadi sudo[8201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 20:44:54 volumioadi sudo[8201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:54 volumioadi sudo[8199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 20:44:54 volumioadi sudo[8199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:54 volumioadi volumio[8049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 20:44:54 volumioadi volumio[8049]: info: [1774554294200] CoreMusicLibrary::Adding element Webradio Mar 26 20:44:54 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 20:44:54 volumioadi sudo[8199]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:54 volumioadi sudo[8196]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:54 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 20:44:54 volumioadi volumio[8049]: info: Initializing BBC Radios Mar 26 20:44:54 volumioadi systemd[1]: mpd.service: Deactivated successfully. Mar 26 20:44:54 volumioadi systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 20:44:54 volumioadi systemd[1]: mpd.service: Consumed 1min 49.123s CPU time. Mar 26 20:44:54 volumioadi systemd[1]: mpd.socket: Deactivated successfully. Mar 26 20:44:54 volumioadi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 20:44:54 volumioadi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 20:44:54 volumioadi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 20:44:54 volumioadi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 20:44:54 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 20:44:54 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 20:44:54 volumioadi volumio[8049]: info: Creating Spotify config file Mar 26 20:44:54 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:54 volumioadi sudo[8219]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 20:44:54 volumioadi sudo[8219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 20:44:54 volumioadi sudo[8219]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:54 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:54.833+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 26 20:44:55 volumioadi volumio[8049]: info: Loading i18n strings for locale pl Mar 26 20:44:55 volumioadi volumio[8049]: info: Volumio Calling Home Mar 26 20:44:55 volumioadi volumio[8049]: info: MPD Permissions set Mar 26 20:44:55 volumioadi volumio[8049]: info: MPD Permissions set Mar 26 20:44:55 volumioadi volumio[8049]: info: VolumeController:: Volume=56 Mute =false Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreStateMachine::pushState Mar 26 20:44:55 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::volumioPushState Mar 26 20:44:55 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:55.412+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" state=STATUS_STOPPED positionMs=0 volume=56 Mar 26 20:44:55 volumioadi volumio[8049]: info: Spotify config file written Mar 26 20:44:55 volumioadi sudo[8245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 26 20:44:55 volumioadi sudo[8245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:44:55 volumioadi volumio[8049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 26 20:44:55 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:44:55.466+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" id="mnt/USB/My_Passport/Spotify 02-2026 FLAC/Moja/Złe żądze - Rufuz, TPS, Jongmen.flac" title="Złe żądze - Rufuz, TPS, Jongmen.flac" Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 26 20:44:55 volumioadi systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 26 20:44:55 volumioadi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:44:55 volumioadi volumio[8049]: info: No need to fix Spotify hosts Mar 26 20:44:55 volumioadi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:44:55 volumioadi sudo[8245]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:55 volumioadi go-librespot[8257]: go-librespot daemon starting... Mar 26 20:44:55 volumioadi go-librespot[8258]: time="2026-03-26T20:44:55+01:00" level=info msg="running go-librespot 0.7.1" Mar 26 20:44:55 volumioadi go-librespot[8258]: time="2026-03-26T20:44:55+01:00" level=debug msg="app state loaded" Mar 26 20:44:55 volumioadi go-librespot[8258]: time="2026-03-26T20:44:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 20:44:57 volumioadi mpd[8227]: 2026-03-26T20:44:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 26 20:44:57 volumioadi systemd[1]: Started mpd.service - Music Player Daemon. Mar 26 20:44:57 volumioadi sudo[8201]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:57 volumioadi sudo[8193]: pam_unix(sudo:session): session closed for user root Mar 26 20:44:57 volumioadi volumio[8049]: info: Completed starting Core Plugins Mar 26 20:44:58 volumioadi volumio[8049]: info: ------------------------------------------- Mar 26 20:44:58 volumioadi volumio[8049]: info: ----- MyVolumio plugins startup ---- Mar 26 20:44:58 volumioadi volumio[8049]: info: ------------------------------------------- Mar 26 20:44:58 volumioadi volumio[8049]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 26 20:44:58 volumioadi volumio[8049]: error: MPD error: The expression evaluated to a falsy value: Mar 26 20:44:58 volumioadi volumio[8049]: assert.ok(self.idling) Mar 26 20:44:58 volumioadi volumio[8049]: error: The expression evaluated to a falsy value: Mar 26 20:44:58 volumioadi volumio[8049]: assert.ok(self.idling) Mar 26 20:44:58 volumioadi volumio[8049]: info: MPD running with PID8227 Mar 26 20:44:58 volumioadi volumio[8049]: ,establishing connection Mar 26 20:44:58 volumioadi volumio[8049]: error: updateQueue error: null Mar 26 20:44:58 volumioadi volumio[8049]: error: updateQueue error: null Mar 26 20:44:58 volumioadi volumio-remote-updater[695]: [2026-03-26 20:44:58] [connect] Successful connection Mar 26 20:44:58 volumioadi volumio-remote-updater[695]: [2026-03-26 20:44:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774554298 101 Mar 26 20:44:58 volumioadi volumio[8049]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Mar 26 20:44:58 volumioadi volumio[8049]: info: go-librespot daemon successfully initialized Mar 26 20:45:01 volumioadi volumio[8049]: info: Initializing connection to go-librespot Websocket Mar 26 20:45:05 volumioadi go-librespot[8258]: time="2026-03-26T20:45:05+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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 26 20:45:05 volumioadi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:45:05 volumioadi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 20:45:06 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:06.168+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%22 @ 0x28001e0" latency=111.743579ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET Mar 26 20:45:06 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:06.296+01:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="00:00:00:00:00:00%22 @ 0x28001e0" latency=190.798734ms timeout=1m0s Mar 26 20:45:06 volumioadi volumio[8049]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Mar 26 20:45:07 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:07.155+01:00 level=INFO msg="emitting wifi scan event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" networks=9 Mar 26 20:45:07 volumioadi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 173. Mar 26 20:45:07 volumioadi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:45:07 volumioadi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:45:07 volumioadi upmpdcli[8298]: Could not open config: /tmp/upmpdcli.conf Mar 26 20:45:07 volumioadi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:45:07 volumioadi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 26 20:45:07 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:07.679+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" available=true connected=true macAddress=b8:27:eb:34:60:91 ip4Address=192.168.1.56/24 ip6Address= ssid=NetBoss Mar 26 20:45:07 volumioadi volumio[8049]: info: Received Get System Info Mar 26 20:45:07 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:45:07 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:45:07 volumioadi volumio[8049]: info: Discovery: Getting this device information Mar 26 20:45:07 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:07 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:07 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:45:07 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 20:45:07 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 20:45:08 volumioadi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 26 20:45:08 volumioadi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:45:08 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:08.653+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 26 20:45:08 volumioadi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:45:08 volumioadi go-librespot[8301]: go-librespot daemon starting... Mar 26 20:45:08 volumioadi go-librespot[8302]: time="2026-03-26T20:45:08+01:00" level=info msg="running go-librespot 0.7.1" Mar 26 20:45:08 volumioadi go-librespot[8302]: time="2026-03-26T20:45:08+01:00" level=debug msg="app state loaded" Mar 26 20:45:08 volumioadi go-librespot[8302]: time="2026-03-26T20:45:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 20:45:16 volumioadi volumio[8049]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.103&uuid=74d7dbc6b74030ecdbb691316f59e2cf" http://updates.volumio.org/downloader-v1/track-device Mar 26 20:45:16 volumioadi volumio[8049]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 26 20:45:16 volumioadi volumio[8049]: Dload Upload Total Spent Left Speed Mar 26 20:45:16 volumioadi volumio[8049]: [922B blob data] Mar 26 20:45:16 volumioadi volumio[8049]: retrying in 5 seconds, trial 0 Mar 26 20:45:16 volumioadi volumio[8049]: info: Volumio Calling Home Mar 26 20:45:18 volumioadi volumio[8049]: error: MyVolumio Plugin failed to start in a timely fashion Mar 26 20:45:18 volumioadi volumio[8049]: [Metrics] CommandRouter: 34s 360.62ms Mar 26 20:45:18 volumioadi volumio[8049]: info: CoreCommandRouter::volumiosetStartupVolume Mar 26 20:45:18 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:45:18 volumioadi volumio[8049]: info: VolumeController:: Setting startup Volume 15 Mar 26 20:45:18 volumioadi volumio[8049]: info: VolumeController::SetAlsaVolume15 Mar 26 20:45:18 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 20:45:18 volumioadi volumio[8049]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:18 volumioadi volumio[8049]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:18 volumioadi volumio[8049]: info: CoreStateMachine::pushState Mar 26 20:45:18 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:18 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:18 volumioadi volumio[8049]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:18 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:18.057+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" state=STATUS_STOPPED positionMs=0 volume=15 Mar 26 20:45:18 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:18.116+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" id="mnt/USB/My_Passport/Spotify 02-2026 FLAC/Moja/Złe żądze - Rufuz, TPS, Jongmen.flac" title="Złe żądze - Rufuz, TPS, Jongmen.flac" Mar 26 20:45:19 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 26 20:45:19 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 20:45:19 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 26 20:45:20 volumioadi volumio[8049]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Mar 26 20:45:20 volumioadi volumio[8049]: info: Bluetoothremote--- Device list cleared and placeholder written. Mar 26 20:45:20 volumioadi bluetoothd[820]: Adv Monitor app :1.117 disconnected from D-Bus Mar 26 20:45:20 volumioadi bluetoothd[820]: Adv Monitor app :1.118 disconnected from D-Bus Mar 26 20:45:20 volumioadi volumio[8049]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 20:45:20 volumioadi volumio[8049]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Mar 26 20:45:20 volumioadi bluetoothd[820]: Adv Monitor app :1.119 disconnected from D-Bus Mar 26 20:45:21 volumioadi go-librespot[8302]: time="2026-03-26T20:45:21+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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 26 20:45:21 volumioadi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:45:21 volumioadi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 20:45:22 volumioadi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 174. Mar 26 20:45:22 volumioadi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:45:22 volumioadi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 26 20:45:22 volumioadi upmpdcli[8336]: Could not open config: /tmp/upmpdcli.conf Mar 26 20:45:22 volumioadi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:45:22 volumioadi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 26 20:45:24 volumioadi kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Mar 26 20:45:24 volumioadi dhcpcd[713]: eth0: carrier acquired Mar 26 20:45:24 volumioadi dhcpcd[713]: eth0: config file changed, expiring leases Mar 26 20:45:24 volumioadi dhcpcd[713]: eth0: IAID eb:61:35:c4 Mar 26 20:45:24 volumioadi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 26 20:45:24 volumioadi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:45:24 volumioadi dhcpcd[713]: eth0: soliciting an IPv6 router Mar 26 20:45:24 volumioadi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:45:24 volumioadi go-librespot[8338]: go-librespot daemon starting... Mar 26 20:45:24 volumioadi go-librespot[8339]: time="2026-03-26T20:45:24+01:00" level=info msg="running go-librespot 0.7.1" Mar 26 20:45:24 volumioadi go-librespot[8339]: time="2026-03-26T20:45:24+01:00" level=debug msg="app state loaded" Mar 26 20:45:24 volumioadi go-librespot[8339]: time="2026-03-26T20:45:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 20:45:24 volumioadi ifplugd(eth0)[1016]: Link beat detected. Mar 26 20:45:24 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:24.728+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" available=true connected=false macAddress= ip4Address= ip6Address= Mar 26 20:45:24 volumioadi volumio[8049]: info: Received Get System Info Mar 26 20:45:24 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:45:24 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:45:24 volumioadi volumio[8049]: info: Discovery: Getting this device information Mar 26 20:45:24 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:24 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:24 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:45:24 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 20:45:24 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 20:45:24 volumioadi ifplugd(eth0)[1016]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 26 20:45:24 volumioadi dhcpcd[713]: ps_ctl_dispatch: cannot handle another client Mar 26 20:45:24 volumioadi ifplugd(eth0)[1016]: client: sending commands to dhcpcd process Mar 26 20:45:24 volumioadi dhcpcd[713]: control_free: No such file or directory Mar 26 20:45:25 volumioadi volumio[8049]: info: BOOT COMPLETED Mar 26 20:45:25 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 20:45:25 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 20:45:25 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 20:45:25 volumioadi ifplugd(eth0)[1016]: Program executed successfully. Mar 26 20:45:25 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:25.675+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: === SNM TRANSITION === Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: New ethernet state: connected Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: Single Network Mode: enabled Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: First start: no Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: === END TRANSITION === Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Mar 26 20:45:25 volumioadi sudo[8402]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Mar 26 20:45:25 volumioadi sudo[8402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 20:45:25 volumioadi dhcpcd[8403]: dhcpcd not running Mar 26 20:45:25 volumioadi sudo[8402]: pam_unix(sudo:session): session closed for user root Mar 26 20:45:25 volumioadi wireless.js[3022]: dhcpcd not running Mar 26 20:45:25 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: wlan0: CTRL-EVENT-DISCONNECTED bssid=84:a3:29:03:a9:2e reason=3 locally_generated=1 Mar 26 20:45:26 volumioadi dhcpcd[713]: wlan0: carrier lost - roaming Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Mar 26 20:45:26 volumioadi dhcpcd[713]: wlan0: changing route to 192.168.1.0/24 Mar 26 20:45:26 volumioadi dhcpcd[713]: wlan0: changing default route via 192.168.1.1 Mar 26 20:45:26 volumioadi sudo[8411]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 26 20:45:26 volumioadi sudo[8411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 20:45:26 volumioadi avahi-daemon[7630]: Withdrawing address record for 192.168.1.56 on wlan0. Mar 26 20:45:26 volumioadi avahi-daemon[7630]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.56. Mar 26 20:45:26 volumioadi avahi-daemon[7630]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 26 20:45:26 volumioadi sudo[8411]: pam_unix(sudo:session): session closed for user root Mar 26 20:45:26 volumioadi volumio[8049]: info: Discovery: A device disappeared from network Mar 26 20:45:26 volumioadi sudo[8426]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 26 20:45:26 volumioadi sudo[8426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 20:45:26 volumioadi dhcpcd[713]: eth0: rebinding lease of 192.168.2.41 Mar 26 20:45:26 volumioadi dhcpcd[713]: wlan0: pid 8419 deleted IP address 192.168.1.56/24 Mar 26 20:45:26 volumioadi dhcpcd[713]: wlan0: deleting route to 192.168.1.0/24 Mar 26 20:45:26 volumioadi systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 26 20:45:26 volumioadi systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Mar 26 20:45:26 volumioadi systemd[1]: welcome.service: Deactivated successfully. Mar 26 20:45:26 volumioadi systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 26 20:45:26 volumioadi systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 26 20:45:26 volumioadi systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 26 20:45:26 volumioadi dhcpcd[713]: wlan0: deleting default route via 192.168.1.1 Mar 26 20:45:26 volumioadi sudo[8426]: pam_unix(sudo:session): session closed for user root Mar 26 20:45:26 volumioadi welcome[8434]: Resolved ip:[0] Mar 26 20:45:26 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: Cleaning previous... Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Mar 26 20:45:26 volumioadi wpa_supplicant[3099]: wlan0: CTRL-EVENT-TERMINATING Mar 26 20:45:26 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:26.712+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 26 20:45:26 volumioadi volumio[8049]: info: Received Get System Info Mar 26 20:45:26 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:45:26 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:45:26 volumioadi volumio[8049]: info: Discovery: Getting this device information Mar 26 20:45:26 volumioadi systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 26 20:45:26 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:26 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:26 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:45:26 volumioadi systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 26 20:45:26 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 20:45:26 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 20:45:26 volumioadi dhcpcd[713]: wlan0: probing address 192.168.1.56/24 Mar 26 20:45:26 volumioadi sudo[8452]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 26 20:45:26 volumioadi sudo[8452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 20:45:26 volumioadi volumio[8049]: info: Volumio Network Manager: Network status updated: 0 Mar 26 20:45:26 volumioadi sudo[8452]: pam_unix(sudo:session): session closed for user root Mar 26 20:45:26 volumioadi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 26 20:45:26 volumioadi dhcpcd[713]: eth0: probing address 192.168.2.41/24 Mar 26 20:45:26 volumioadi dhcpcd[713]: wlan0: carrier lost Mar 26 20:45:26 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Mar 26 20:45:26 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 1ms Mar 26 20:45:26 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:34:60:91) Mar 26 20:45:26 volumioadi sudo[8486]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 26 20:45:26 volumioadi sudo[8486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:45:26 volumioadi sudo[8486]: pam_unix(sudo:session): session closed for user root Mar 26 20:45:26 volumioadi sudo[8494]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 26 20:45:26 volumioadi sudo[8494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:45:27 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:27.300+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 26 20:45:27 volumioadi volumio[8049]: info: Received Get System Info Mar 26 20:45:27 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:45:27 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:45:27 volumioadi volumio[8049]: info: Discovery: Getting this device information Mar 26 20:45:27 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:27 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:27 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:45:27 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 20:45:27 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 20:45:27 volumioadi sudo[8494]: pam_unix(sudo:session): session closed for user root Mar 26 20:45:27 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: Regdomain already correct: DE Mar 26 20:45:27 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Mar 26 20:45:27 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Mar 26 20:45:27 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Mar 26 20:45:27 volumioadi sudo[8503]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 26 20:45:27 volumioadi sudo[8503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 20:45:27 volumioadi sudo[8503]: pam_unix(sudo:session): session closed for user root Mar 26 20:45:27 volumioadi sudo[8506]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 26 20:45:27 volumioadi sudo[8506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 20:45:27 volumioadi sudo[8506]: pam_unix(sudo:session): session closed for user root Mar 26 20:45:27 volumioadi wpa_supplicant[8509]: Successfully initialized wpa_supplicant Mar 26 20:45:28 volumioadi wpa_supplicant[8512]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Mar 26 20:45:28 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 2253ms Mar 26 20:45:28 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Mar 26 20:45:28 volumioadi wireless.js[3022]: WIRELESS.JS - INFO: Notified systemd about wireless ready Mar 26 20:45:28 volumioadi nmbd[1064]: [2026/03/26 20:45:28.234873, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Mar 26 20:45:28 volumioadi nmbd[1064]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Mar 26 20:45:28 volumioadi volumio[8049]: info: Received Get System Info Mar 26 20:45:28 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:28.332+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 26 20:45:28 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:45:28 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:45:28 volumioadi volumio[8049]: info: Discovery: Getting this device information Mar 26 20:45:28 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:28 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:28 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:45:28 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 20:45:28 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 20:45:29 volumioadi volumio[8049]: info: Received Get System Info Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:45:29 volumioadi volumio[8049]: info: Discovery: Getting this device information Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:29 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:29.154+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 26 20:45:29 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 20:45:29 volumioadi volumio[8049]: info: Discovery: adding 7c3397b0-e5ab-485e-9a4f-f815ae25104c Mar 26 20:45:29 volumioadi volumio[8049]: info: Discovery: Found device VolumioADI Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:29 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:29 volumioadi volumio[8049]: info: Discovery: this is already registered, 7c3397b0-e5ab-485e-9a4f-f815ae25104c Mar 26 20:45:29 volumioadi volumio[8049]: info: Discovery: Found device VolumioADI Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:29 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:29 volumioadi go-librespot[8339]: time="2026-03-26T20:45:29+01:00" level=debug msg="new websocket client" Mar 26 20:45:29 volumioadi volumio[8049]: info: Connection to go-librespot Websocket established Mar 26 20:45:29 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:29 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:29 volumioadi go-librespot[8339]: time="2026-03-26T20:45:29+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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 26 20:45:29 volumioadi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:45:29 volumioadi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 20:45:29 volumioadi volumio[8049]: info: Connection to go-librespot Websocket closed Mar 26 20:45:30 volumioadi ntpd[1003]: IO: Deleting interface #4 wlan0, 192.168.1.56#123, interface stats: received=0, sent=8, dropped=0, active_time=218 secs Mar 26 20:45:30 volumioadi ntpd[1003]: PROTO: 85.214.38.116 unlink local addr 192.168.1.56 -> Mar 26 20:45:30 volumioadi ntpd[1003]: PROTO: 131.234.220.232 unlink local addr 192.168.1.56 -> Mar 26 20:45:30 volumioadi ntpd[1003]: PROTO: 185.228.139.165 unlink local addr 192.168.1.56 -> Mar 26 20:45:30 volumioadi ntpd[1003]: PROTO: 217.91.44.17 unlink local addr 192.168.1.56 -> Mar 26 20:45:30 volumioadi ntpd[1003]: PROTO: 130.162.222.153 unlink local addr 192.168.1.56 -> Mar 26 20:45:30 volumioadi ntpd[1003]: PROTO: 185.233.107.180 unlink local addr 192.168.1.56 -> Mar 26 20:45:30 volumioadi ntpd[1003]: PROTO: 79.133.44.136 unlink local addr 192.168.1.56 -> Mar 26 20:45:30 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:30.730+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 26 20:45:31 volumioadi dhcpcd[713]: eth0: leased 192.168.2.41 for 1814400 seconds Mar 26 20:45:31 volumioadi dhcpcd[713]: eth0: adding route to 192.168.2.0/24 Mar 26 20:45:31 volumioadi dhcpcd[713]: eth0: adding default route via 192.168.2.1 Mar 26 20:45:31 volumioadi avahi-daemon[7630]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.41. Mar 26 20:45:31 volumioadi avahi-daemon[7630]: New relevant interface eth0.IPv4 for mDNS. Mar 26 20:45:31 volumioadi avahi-daemon[7630]: Registering new address record for 192.168.2.41 on eth0.IPv4. Mar 26 20:45:31 volumioadi systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 26 20:45:31 volumioadi systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 26 20:45:31 volumioadi systemd[1]: welcome.service: Deactivated successfully. Mar 26 20:45:31 volumioadi systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 26 20:45:31 volumioadi systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 26 20:45:31 volumioadi systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 26 20:45:31 volumioadi welcome[8547]: Resolved ip:[1] 192.168.2.41 Mar 26 20:45:31 volumioadi systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 26 20:45:31 volumioadi systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 26 20:45:31 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:31.952+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%22 @ 0x28001e0" latency=108.504408ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 26 20:45:32 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:32.192+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="00:00:00:00:00:00%22 @ 0x28001e0" available=true connected=true macAddress=b8:27:eb:61:35:c4 ip4Address=192.168.2.41/24 ip6Address= Mar 26 20:45:32 volumioadi volumio[8049]: info: Received Get System Info Mar 26 20:45:32 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:45:32 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:45:32 volumioadi volumio[8049]: info: Discovery: Getting this device information Mar 26 20:45:32 volumioadi volumio[8049]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:32 volumioadi volumio[8049]: info: CorePlayQueue::getTrack 0 Mar 26 20:45:32 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:45:32 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 20:45:32 volumioadi volumio[8049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 20:45:32 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:32.256+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.46:55646 Mar 26 20:45:32 volumioadi volumio[8049]: info: Getting Spotify volume Mar 26 20:45:32 volumioadi volumio[8049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:45:32 volumioadi volumio[8049]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 20:45:32 volumioadi volumio[8049]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 26 20:45:32 volumioadi volumio[8049]: errno: -111, Mar 26 20:45:32 volumioadi volumio[8049]: code: 'ECONNREFUSED', Mar 26 20:45:32 volumioadi volumio[8049]: syscall: 'connect', Mar 26 20:45:32 volumioadi volumio[8049]: address: '127.0.0.1', Mar 26 20:45:32 volumioadi volumio[8049]: port: 9879, Mar 26 20:45:32 volumioadi volumio[8049]: response: undefined Mar 26 20:45:32 volumioadi volumio[8049]: } Mar 26 20:45:32 volumioadi volumio[8049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:45:32 volumioadi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 26 20:45:32 volumioadi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:45:32 volumioadi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 20:45:32 volumioadi go-librespot[8566]: go-librespot daemon starting... Mar 26 20:45:32 volumioadi go-librespot[8567]: time="2026-03-26T20:45:32+01:00" level=info msg="running go-librespot 0.7.1" Mar 26 20:45:32 volumioadi go-librespot[8567]: time="2026-03-26T20:45:32+01:00" level=debug msg="app state loaded" Mar 26 20:45:32 volumioadi go-librespot[8567]: time="2026-03-26T20:45:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=info msg="zeroconf server listening on port 38623" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 26 20:45:33 volumioadi volumio5-onboarding[1475]: time=2026-03-26T20:45:33.139+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=debug msg="obtained new client token: AADXhwhbBZ1YSIqEsG+eUyGhcKipKndn1OBW8AbOhwx/SUXBHK9zVekXyxHKfp9+I66X3F370B51/CrWMH4oDjFxZPJT+4G8X6bQbdfGdKgLlp/Zy9TyewAfXWhj5tM63oysO8goxBhSkV9RN9hTYY/QvdGGHiRS+gTc3IGqL8BQ9abF9IvI0rxHha4Nq3HpowlGBTZRpf1pY/1nDU0Qck184BzJoXrWfc1cspUJ1Mv0+6H8j1Ts" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=debug msg="completed keyexchange" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=debug msg="completed challenge" Mar 26 20:45:33 volumioadi sudo[8578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 20:44' Mar 26 20:45:33 volumioadi sudo[8578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=info msg="authenticated AP" username="31************************fi" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=info msg="authenticated Login5" username="31************************fi" Mar 26 20:45:33 volumioadi go-librespot[8567]: time="2026-03-26T20:45:33+01:00" level=debug msg="initializing zeroconf session" username="31************************fi" 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"