Feb 26 04:01:04 volumio go-librespot[1500]: time="2026-02-26T04:01:04+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 26 04:01:10 volumio go-librespot[1500]: time="2026-02-26T04:01:10+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Feb 26 04:01:16 volumio go-librespot[1500]: time="2026-02-26T04:01:16+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 26 04:01:22 volumio go-librespot[1500]: time="2026-02-26T04:01:22+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Feb 26 04:01:28 volumio go-librespot[1500]: time="2026-02-26T04:01:28+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 26 04:01:34 volumio go-librespot[1500]: time="2026-02-26T04:01:34+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Feb 26 04:01:40 volumio go-librespot[1500]: time="2026-02-26T04:01:40+01:00" level=error msg="did not receive last pong ack from accesspoint, 1032s passed" Feb 26 04:01:40 volumio go-librespot[1500]: time="2026-02-26T04:01:40+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 26 04:01:46 volumio go-librespot[1500]: time="2026-02-26T04:01:46+01:00" level=error msg="failed reconnecting accesspoint" error="failed to connect to AP ap-gew1.spotify.com:4070: dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Feb 26 04:01:46 volumio go-librespot[1500]: fatal error: sync: Unlock of unlocked RWMutex Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 7235050 gp=0x17be128 m=0 mp=0xce0d20 [running]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.fatal({0x7a3604, 0x20}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/panic.go:1042 +0x4c fp=0x1f01ee0 sp=0x1f01ecc pc=0x597e0 Feb 26 04:01:46 volumio go-librespot[1500]: sync.fatal({0x7a3604, 0x20}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/panic.go:1007 +0x24 fp=0x1f01eec sp=0x1f01ee0 pc=0x8ff0c Feb 26 04:01:46 volumio go-librespot[1500]: sync.(*RWMutex).Unlock(0x1556ef8) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/sync/rwmutex.go:208 +0x50 fp=0x1f01f04 sp=0x1f01eec pc=0xa0ec0 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x1556e78) Feb 26 04:01:46 volumio go-librespot[1500]: /src/ap/ap.go:329 +0x4bc fp=0x1f01fe4 sp=0x1f01f04 pc=0x48c784 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Feb 26 04:01:46 volumio go-librespot[1500]: /src/ap/ap.go:390 +0x28 fp=0x1f01fec sp=0x1f01fe4 pc=0x48d010 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1f01fec sp=0x1f01fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 7235085 Feb 26 04:01:46 volumio go-librespot[1500]: /src/ap/ap.go:390 +0x120 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 1 gp=0x1402128 m=nil [select, 21651 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x162fd00 sp=0x162fcec pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.selectgo(0x162fe00, 0x16d1dcc, 0x0, 0x0, 0x2, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x162fda0 sp=0x162fd00 pc=0x70e78 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x15168a0, 0x162fe90) Feb 26 04:01:46 volumio go-librespot[1500]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x162fe44 sp=0x162fda0 pc=0x5a8088 Feb 26 04:01:46 volumio go-librespot[1500]: main.(*App).withAppPlayer(0x1592300, {0x8874f0, 0xd0ad00}, 0x140f040) Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:321 +0x554 fp=0x162fed0 sp=0x162fe44 pc=0x63db54 Feb 26 04:01:46 volumio go-librespot[1500]: main.(*App).withCredentials(...) Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:193 Feb 26 04:01:46 volumio go-librespot[1500]: main.(*App).SpotifyToken(0x1592300, {0x8874f0, 0xd0ad00}, {0x15845e0, 0x1c}, {0x15ac000, 0x13c}) Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x162ff00 sp=0x162fed0 pc=0x63cfa8 Feb 26 04:01:46 volumio go-librespot[1500]: main.main() Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:557 +0x588 fp=0x162ffa8 sp=0x162ff00 pc=0x63feb4 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.main() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x162ffec sp=0x162ffa8 pc=0x5cb30 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x162ffec sp=0x162ffec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 18 gp=0x1484248 m=nil [force gc (idle), 2 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x14407d4 sp=0x14407c0 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goparkunlock(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:408 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.forcegchelper() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x14407ec sp=0x14407d4 pc=0x5cefc Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14407ec sp=0x14407ec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by runtime.init.5 in goroutine 1 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:314 +0x1c Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 19 gp=0x1484368 m=nil [GC sweep wait]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1440fc4 sp=0x1440fb0 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goparkunlock(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:408 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.bgsweep(0x1492000) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x1440fe4 sp=0x1440fc4 pc=0x45848 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gcenable.gowrap1() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x1440fec sp=0x1440fe4 pc=0x35968 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1440fec sp=0x1440fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by runtime.gcenable in goroutine 1 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 20 gp=0x1484488 m=nil [GC scavenge wait]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x14417b4 sp=0x14417a0 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goparkunlock(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:408 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.(*scavengerState).park(0xce0208) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x14417c8 sp=0x14417b4 pc=0x42b68 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.bgscavenge(0x1492000) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x14417e4 sp=0x14417c8 pc=0x432a0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gcenable.gowrap2() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x14417ec sp=0x14417e4 pc=0x35914 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14417ec sp=0x14417ec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by runtime.gcenable in goroutine 1 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 2 gp=0x1402ea8 m=nil [finalizer wait, 2060 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1444f8c sp=0x1444f78 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.runfinq() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x1444fec sp=0x1444f8c pc=0x34754 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1444fec sp=0x1444fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by runtime.createfing in goroutine 1 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 5 gp=0x1403e68 m=nil [IO wait, 21651 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e40f0, 0x76041ed8, 0x2, 0x2, 0x5) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x163ad28 sp=0x163ad14 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.netpollblock(0x76041ec8, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x163ad40 sp=0x163ad28 pc=0x548cc Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.runtime_pollWait(0x76041ec8, 0x72) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x163ad54 sp=0x163ad40 pc=0x8f4a8 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).wait(0x15830a8, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x163ad68 sp=0x163ad54 pc=0x10a318 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).waitRead(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*FD).Accept(0x1583090) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x163adb0 sp=0x163ad68 pc=0x10eb84 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*netFD).accept(0x1583090) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x163ae18 sp=0x163adb0 pc=0x17e4c4 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*TCPListener).accept(0x14265e8) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x163ae30 sp=0x163ae18 pc=0x196900 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*TCPListener).Accept(0x14265e8) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x163ae4c sp=0x163ae30 pc=0x19596c Feb 26 04:01:46 volumio go-librespot[1500]: net/http.(*onceCloseListener).Accept(0x1519080) Feb 26 04:01:46 volumio go-librespot[1500]: :1 +0x34 fp=0x163ae64 sp=0x163ae4c pc=0x362b54 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.(*Server).Serve(0x16b03c8, {0x886cb8, 0x14265e8}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x163aefc sp=0x163ae64 pc=0x341210 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.Serve(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:2794 Feb 26 04:01:46 volumio go-librespot[1500]: main.(*ConcreteApiServer).serve(0x1428180) Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x163afe4 sp=0x163aefc pc=0x6330b8 Feb 26 04:01:46 volumio go-librespot[1500]: main.NewApiServer.gowrap1() Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x163afec sp=0x163afe4 pc=0x631de4 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x163afec sp=0x163afec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by main.NewApiServer in goroutine 1 Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/api_server.go:296 +0x310 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 100 gp=0x14847e8 m=nil [select, 5350 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1910ea8 sp=0x1910e94 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.selectgo(0x1910fa4, 0x1910f6c, 0x0, 0x0, 0x5, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1910f48 sp=0x1910ea8 pc=0x70e78 Feb 26 04:01:46 volumio go-librespot[1500]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x15b8008) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1910fe4 sp=0x1910f48 pc=0x474738 Feb 26 04:01:46 volumio go-librespot[1500]: nhooyr.io/websocket.newConn.gowrap1() Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1910fec sp=0x1910fe4 pc=0x47432c Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1910fec sp=0x1910fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by nhooyr.io/websocket.newConn in goroutine 98 Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 50 gp=0x18aa128 m=nil [GC worker (idle)]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e3fbc, 0x189ce28, 0x1a, 0xa, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1912790 sp=0x191277c pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gcBgMarkWorker() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x19127ec sp=0x1912790 pc=0x38590 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x19127ec sp=0x19127ec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 36 gp=0x15047e8 m=nil [GC worker (idle), 137 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e3fbc, 0x15294e8, 0x1a, 0xa, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1444790 sp=0x144477c pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gcBgMarkWorker() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x14447ec sp=0x1444790 pc=0x38590 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14447ec sp=0x14447ec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 51 gp=0x18aa248 m=nil [GC worker (idle), 2 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e3fbc, 0x189ce40, 0x1a, 0xa, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1912f90 sp=0x1912f7c pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gcBgMarkWorker() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1912fec sp=0x1912f90 pc=0x38590 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1912fec sp=0x1912fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 52 gp=0x18aa368 m=nil [GC worker (idle), 2454 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e3fbc, 0x189ce58, 0x1a, 0xa, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1913790 sp=0x191377c pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gcBgMarkWorker() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x19137ec sp=0x1913790 pc=0x38590 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x19137ec sp=0x19137ec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 53 gp=0x1504248 m=nil [IO wait, 6 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e40f0, 0x76041cf8, 0x2, 0x2, 0x5) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1632e84 sp=0x1632e70 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.netpollblock(0x76041ce8, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1632e9c sp=0x1632e84 pc=0x548cc Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.runtime_pollWait(0x76041ce8, 0x72) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1632eb0 sp=0x1632e9c pc=0x8f4a8 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).wait(0x1583288, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1632ec4 sp=0x1632eb0 pc=0x10a318 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).waitRead(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*FD).RawRead(0x1583270, 0x17c17d0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1632ef0 sp=0x1632ec4 pc=0x10f788 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*rawConn).Read(0x1776d18, 0x17c17d0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1632f10 sp=0x1632ef0 pc=0x190d28 Feb 26 04:01:46 volumio go-librespot[1500]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1825260, 0x17c17a0, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1632f40 sp=0x1632f10 pc=0x548824 Feb 26 04:01:46 volumio go-librespot[1500]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Feb 26 04:01:46 volumio go-librespot[1500]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x14014d8, {0x17c4000, 0x10000, 0x10000}) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x1632f9c sp=0x1632f40 pc=0x54c80c Feb 26 04:01:46 volumio go-librespot[1500]: github.com/grandcat/zeroconf.(*Server).recv4(0x1499780, 0x14014d0) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1632fe0 sp=0x1632f9c pc=0x5a309c Feb 26 04:01:46 volumio go-librespot[1500]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1632fec sp=0x1632fe0 pc=0x5a2d54 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1632fec sp=0x1632fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 24 Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 54 gp=0x1504908 m=nil [IO wait, 21651 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e40f0, 0x76041c58, 0x2, 0x2, 0x5) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x182d65c sp=0x182d648 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.netpollblock(0x76041c48, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x182d674 sp=0x182d65c pc=0x548cc Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.runtime_pollWait(0x76041c48, 0x72) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x182d688 sp=0x182d674 pc=0x8f4a8 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).wait(0x15832d8, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x182d69c sp=0x182d688 pc=0x10a318 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).waitRead(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*FD).RawRead(0x15832c0, 0x17c0ab0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x182d6c8 sp=0x182d69c pc=0x10f788 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*rawConn).Read(0x1776d40, 0x17c0ab0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x182d6e8 sp=0x182d6c8 pc=0x190d28 Feb 26 04:01:46 volumio go-librespot[1500]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1825270, 0x17c0a80, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x182d718 sp=0x182d6e8 pc=0x548824 Feb 26 04:01:46 volumio go-librespot[1500]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Feb 26 04:01:46 volumio go-librespot[1500]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1401508, {0x18ba000, 0x10000, 0x10000}) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x182d79c sp=0x182d718 pc=0x550194 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/grandcat/zeroconf.(*Server).recv6(0x1499780, 0x1401500) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x182d7e0 sp=0x182d79c pc=0x5a3290 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x182d7ec sp=0x182d7e0 pc=0x5a2cf8 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x182d7ec sp=0x182d7ec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 24 Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 98 gp=0x18aab48 m=nil [IO wait, 21651 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e40f0, 0x76041898, 0x2, 0x2, 0x5) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x163487c sp=0x1634868 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.netpollblock(0x76041888, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1634894 sp=0x163487c pc=0x548cc Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.runtime_pollWait(0x76041888, 0x72) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x16348a8 sp=0x1634894 pc=0x8f4a8 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).wait(0x149e518, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x16348bc sp=0x16348a8 pc=0x10a318 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).waitRead(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*FD).Read(0x149e500, {0x1587000, 0x1000, 0x1000}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1634904 sp=0x16348bc pc=0x10b504 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*netFD).Read(0x149e500, {0x1587000, 0x1000, 0x1000}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1634930 sp=0x1634904 pc=0x17c5d0 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*conn).Read(0x17d8a88, {0x1587000, 0x1000, 0x1000}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x163495c sp=0x1634930 pc=0x18caec Feb 26 04:01:46 volumio go-librespot[1500]: net.(*TCPConn).Read(0x17d8a88, {0x1587000, 0x1000, 0x1000}) Feb 26 04:01:46 volumio go-librespot[1500]: :1 +0x44 fp=0x163497c sp=0x163495c pc=0x19fd40 Feb 26 04:01:46 volumio go-librespot[1500]: io.(*multiReader).Read(0x17d6af0, {0x1587000, 0x1000, 0x1000}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x16349a8 sp=0x163497c pc=0x105528 Feb 26 04:01:46 volumio go-librespot[1500]: bufio.(*Reader).fill(0x15ae150) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x16349cc sp=0x16349a8 pc=0x2bddb4 Feb 26 04:01:46 volumio go-librespot[1500]: bufio.(*Reader).ReadByte(0x15ae150) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x16349d8 sp=0x16349cc pc=0x2be638 Feb 26 04:01:46 volumio go-librespot[1500]: nhooyr.io/websocket.readFrameHeader(0x15ae150, {0x15b8038, 0x8, 0x8}) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1634a18 sp=0x16349d8 pc=0x47788c Feb 26 04:01:46 volumio go-librespot[1500]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x15b8008, {0x8874d0, 0xd0ad00}) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1634ad8 sp=0x1634a18 pc=0x47a4f0 Feb 26 04:01:46 volumio go-librespot[1500]: nhooyr.io/websocket.(*Conn).readLoop(0x15b8008, {0x8874d0, 0xd0ad00}) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1634b9c sp=0x1634ad8 pc=0x479fd8 Feb 26 04:01:46 volumio go-librespot[1500]: nhooyr.io/websocket.(*Conn).reader(0x15b8008, {0x8874d0, 0xd0ad00}) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1634c28 sp=0x1634b9c pc=0x47b1ec Feb 26 04:01:46 volumio go-librespot[1500]: nhooyr.io/websocket.(*Conn).Reader(...) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Feb 26 04:01:46 volumio go-librespot[1500]: nhooyr.io/websocket.(*Conn).Read(0x15b8008, {0x8874d0, 0xd0ad00}) Feb 26 04:01:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Feb 26 04:01:46 volumio volumio[1209]: info: Connection to go-librespot Websocket closed Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1634c50 sp=0x1634c28 pc=0x4798bc Feb 26 04:01:46 volumio go-librespot[1500]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x17e8d28}, 0x17e8c88) Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x1634ccc sp=0x1634c50 pc=0x633988 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.HandlerFunc.ServeHTTP(0x152a318, {0x886dec, 0x17e8d28}, 0x17e8c88) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1634cdc sp=0x1634ccc pc=0x33d7f4 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.(*ServeMux).ServeHTTP(0x155c500, {0x886dec, 0x17e8d28}, 0x17e8c88) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1634d18 sp=0x1634cdc pc=0x33f654 Feb 26 04:01:46 volumio go-librespot[1500]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x17e8d28}, 0x17e8c88) Feb 26 04:01:46 volumio go-librespot[1500]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1634d40 sp=0x1634d18 pc=0x633388 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.HandlerFunc.ServeHTTP(0x1514490, {0x886dec, 0x17e8d28}, 0x17e8c88) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1634d50 sp=0x1634d40 pc=0x33d7f4 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.serverHandler.ServeHTTP({0x16b03c8}, {0x886dec, 0x17e8d28}, 0x17e8c88) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1634d6c sp=0x1634d50 pc=0x340de4 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.(*conn).serve(0x17f01e0, {0x887510, 0x16be2d0}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1634fdc sp=0x1634d6c pc=0x33c034 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.(*Server).Serve.gowrap3() Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1634fec sp=0x1634fdc pc=0x341688 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1634fec sp=0x1634fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by net/http.(*Server).Serve in goroutine 5 Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:3285 +0x468 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 82 gp=0x18aafc8 m=nil [chan receive, 21651 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e3f90, 0x15a80f0, 0xe, 0x7, 0x2) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1914f48 sp=0x1914f34 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.chanrecv(0x15a80c0, 0x1914fd0, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1914f84 sp=0x1914f48 pc=0x20078 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.chanrecv2(0x15a80c0, 0x1914fd0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1914f98 sp=0x1914f84 pc=0x1fbbc Feb 26 04:01:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 04:01:46 volumio go-librespot[1500]: main.(*App).withAppPlayer.func1() Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:268 +0x68 fp=0x1914fec sp=0x1914f98 pc=0x63e794 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1914fec sp=0x1914fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by main.(*App).withAppPlayer in goroutine 1 Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:265 +0x464 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 83 gp=0x18ab0e8 m=nil [chan receive, 5350 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e3f90, 0x15a80b0, 0xe, 0x7, 0x2) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1911f40 sp=0x1911f2c pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.chanrecv(0x15a8080, 0x197dfe0, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1911f7c sp=0x1911f40 pc=0x20078 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.chanrecv2(0x15a8080, 0x197dfe0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1911f90 sp=0x1911f7c pc=0x1fbbc Feb 26 04:01:46 volumio go-librespot[1500]: main.(*App).withAppPlayer.func2() Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:284 +0x6c fp=0x1911fec sp=0x1911f90 pc=0x63e330 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1911fec sp=0x1911fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by main.(*App).withAppPlayer in goroutine 1 Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:281 +0x504 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 84 gp=0x18ab208 m=nil [IO wait, 2452 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e40f0, 0x76041d98, 0x2, 0x2, 0x5) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1915de8 sp=0x1915dd4 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.netpollblock(0x76041d88, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1915e00 sp=0x1915de8 pc=0x548cc Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.runtime_pollWait(0x76041d88, 0x72) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1915e14 sp=0x1915e00 pc=0x8f4a8 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).wait(0x1583238, 0x72, 0x0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1915e28 sp=0x1915e14 pc=0x10a318 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*pollDesc).waitRead(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 26 04:01:46 volumio go-librespot[1500]: internal/poll.(*FD).Accept(0x1583220) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1915e70 sp=0x1915e28 pc=0x10eb84 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*netFD).accept(0x1583220) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1915ed8 sp=0x1915e70 pc=0x17e4c4 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*TCPListener).accept(0x14261c8) Feb 26 04:01:46 volumio systemd[1]: go-librespot-daemon.service: Consumed 36min 23.602s CPU time. Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1915ef0 sp=0x1915ed8 pc=0x196900 Feb 26 04:01:46 volumio go-librespot[1500]: net.(*TCPListener).Accept(0x14261c8) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1915f0c sp=0x1915ef0 pc=0x19596c Feb 26 04:01:46 volumio go-librespot[1500]: net/http.(*onceCloseListener).Accept(0x188b8e0) Feb 26 04:01:46 volumio go-librespot[1500]: :1 +0x34 fp=0x1915f24 sp=0x1915f0c pc=0x362b54 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.(*Server).Serve(0x14ab0e8, {0x886cb8, 0x14261c8}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1915fbc sp=0x1915f24 pc=0x341210 Feb 26 04:01:46 volumio go-librespot[1500]: net/http.Serve(...) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/net/http/server.go:2794 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Feb 26 04:01:46 volumio go-librespot[1500]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x1915fec sp=0x1915fbc pc=0x5a8184 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1915fec sp=0x1915fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Feb 26 04:01:46 volumio go-librespot[1500]: /src/zeroconf/zeroconf.go:280 +0x160 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 774011 gp=0x247e6c8 m=nil [chan send, 14783 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e3f90, 0x185c2f0, 0xf, 0x6, 0x2) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1457e50 sp=0x1457e3c pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.chansend(0x185c2c0, 0x1457ef0, 0x1, 0x533320) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/chan.go:259 +0x378 fp=0x1457e88 sp=0x1457e50 pc=0x1f208 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.chansend1(0x185c2c0, 0x1457ef0) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x1457ea0 sp=0x1457e88 pc=0x1ee8c Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/dealer.(*Dealer).handleMessage(0x14cce78, 0x15aab40) Feb 26 04:01:46 volumio go-librespot[1500]: /src/dealer/recv.go:181 +0x4f0 fp=0x1457f2c sp=0x1457ea0 pc=0x533320 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x14cce78) Feb 26 04:01:46 volumio go-librespot[1500]: /src/dealer/dealer.go:210 +0x3fc fp=0x1457fe4 sp=0x1457f2c pc=0x532108 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Feb 26 04:01:46 volumio go-librespot[1500]: /src/dealer/dealer.go:296 +0x28 fp=0x1457fec sp=0x1457fe4 pc=0x5329bc Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1457fec sp=0x1457fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 773992 Feb 26 04:01:46 volumio go-librespot[1500]: /src/dealer/dealer.go:296 +0xf4 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 7235076 gp=0x17a67e8 m=nil [chan send (nil chan), 2 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x0, 0x0, 0x4, 0x1, 0x2) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1f05894 sp=0x1f05880 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.chansend(0x0, 0x1f05a21, 0x1, 0x645d00) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/chan.go:165 +0x598 fp=0x1f058cc sp=0x1f05894 pc=0x1f428 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.chansend1(0x0, 0x1f05a21) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x1f058e4 sp=0x1f058cc pc=0x1ee8c Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/dealer.Request.Reply(...) Feb 26 04:01:46 volumio go-librespot[1500]: /src/dealer/recv.go:39 Feb 26 04:01:46 volumio go-librespot[1500]: main.(*AppPlayer).Run(0x1963590, {0x8874f0, 0xd0ad00}, 0x15a8ac0) Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/player.go:607 +0x7b4 fp=0x1f05fd8 sp=0x1f058e4 pc=0x645d00 Feb 26 04:01:46 volumio go-librespot[1500]: main.(*App).withAppPlayer.func2.gowrap1() Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:309 +0x40 fp=0x1f05fec sp=0x1f05fd8 pc=0x63e700 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1f05fec sp=0x1f05fec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by main.(*App).withAppPlayer.func2 in goroutine 83 Feb 26 04:01:46 volumio go-librespot[1500]: /src/cmd/daemon/main.go:309 +0x2c8 Feb 26 04:01:46 volumio go-librespot[1500]: goroutine 7235075 gp=0x1974248 m=nil [select, 5350 minutes]: Feb 26 04:01:46 volumio go-librespot[1500]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1705674 sp=0x1705660 pc=0x5d0c0 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.selectgo(0x1705774, 0x170573c, 0x0, 0x0, 0x3, 0x1) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1705714 sp=0x1705674 pc=0x70e78 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1582280) Feb 26 04:01:46 volumio go-librespot[1500]: /src/player/player.go:201 +0x1fc fp=0x17057e4 sp=0x1705714 pc=0x513660 Feb 26 04:01:46 volumio go-librespot[1500]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Feb 26 04:01:46 volumio go-librespot[1500]: /src/player/player.go:183 +0x28 fp=0x17057ec sp=0x17057e4 pc=0x513240 Feb 26 04:01:46 volumio go-librespot[1500]: runtime.goexit({}) Feb 26 04:01:46 volumio go-librespot[1500]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x17057ec sp=0x17057ec pc=0x93d6c Feb 26 04:01:46 volumio go-librespot[1500]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 83 Feb 26 04:01:46 volumio go-librespot[1500]: /src/player/player.go:183 +0x218 Feb 26 04:01:46 volumio go-librespot[1499]: Aborted Feb 26 04:01:49 volumio volumio[1209]: info: Initializing connection to go-librespot Websocket Feb 26 04:01:49 volumio volumio[1209]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 04:01:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 26 04:01:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:01:49 volumio systemd[1]: go-librespot-daemon.service: Consumed 36min 23.602s CPU time. Feb 26 04:01:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:01:49 volumio go-librespot[17520]: go-librespot daemon starting... Feb 26 04:01:49 volumio go-librespot[17521]: time="2026-02-26T04:01:49+01:00" level=info msg="running go-librespot 0.4.0" Feb 26 04:01:49 volumio go-librespot[17521]: time="2026-02-26T04:01:49+01:00" level=debug msg="app state loaded" Feb 26 04:01:49 volumio go-librespot[17521]: time="2026-02-26T04:01:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 04:01:52 volumio volumio[1209]: info: Initializing connection to go-librespot Websocket Feb 26 04:01:52 volumio go-librespot[17521]: time="2026-02-26T04:01:52+01:00" level=debug msg="new websocket client" Feb 26 04:01:52 volumio volumio[1209]: info: Connection to go-librespot Websocket established Feb 26 04:01:55 volumio volumio[1209]: info: Getting Spotify volume Feb 26 04:01:55 volumio volumio[1209]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 26 04:01:56 volumio go-librespot[17521]: time="2026-02-26T04:01:56+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" Feb 26 04:01:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 04:01:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 04:01:56 volumio volumio[1209]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 04:01:56 volumio volumio[1209]: Error: socket hang up Feb 26 04:01:56 volumio volumio[1209]: at connResetException (node:internal/errors:720:14) Feb 26 04:01:56 volumio volumio[1209]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 26 04:01:56 volumio volumio[1209]: at Socket.emit (node:events:526:35) Feb 26 04:01:56 volumio volumio[1209]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 26 04:01:56 volumio volumio[1209]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 26 04:01:56 volumio volumio[1209]: code: 'ECONNRESET', Feb 26 04:01:56 volumio volumio[1209]: response: undefined Feb 26 04:01:56 volumio volumio[1209]: } Feb 26 04:01:56 volumio volumio[1209]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 04:01:56 volumio sudo[17557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 04:00' Feb 26 04:01:56 volumio sudo[17557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:01:56 volumio sudo[17557]: pam_unix(sudo:session): session closed for user root Feb 26 04:01:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 26 04:01:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:01:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:01:59 volumio go-librespot[17567]: go-librespot daemon starting... Feb 26 04:01:59 volumio go-librespot[17568]: time="2026-02-26T04:01:59+01:00" level=info msg="running go-librespot 0.4.0" Feb 26 04:01:59 volumio go-librespot[17568]: time="2026-02-26T04:01:59+01:00" level=debug msg="app state loaded" Feb 26 04:01:59 volumio go-librespot[17568]: time="2026-02-26T04:01:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 04:02:02 volumio volumio-remote-updater[642]: [2026-02-26 04:02:02] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 26 04:02:02 volumio volumio-remote-updater[642]: [2026-02-26 04:02:02] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 26 04:02:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 26 04:02:02 volumio volumio5-onboarding[1430]: time=2026-02-26T04:02:02.982+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 26 04:02:02 volumio volumio5-onboarding[1430]: time=2026-02-26T04:02:02.989+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 26 04:02:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 26 04:02:03 volumio systemd[1]: volumio.service: Consumed 2h 33min 52.045s CPU time. Feb 26 04:02:03 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 26 04:02:03 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 26 04:02:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 26 04:02:03 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 26 04:02:03 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 26 04:02:03 volumio systemd[1]: volumio.service: Consumed 2h 33min 52.045s CPU time. Feb 26 04:02:03 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 26 04:02:03 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 26 04:02:03 volumio volumio5-onboarding[1430]: time=2026-02-26T04:02:03.992+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 26 04:02:05 volumio go-librespot[17568]: time="2026-02-26T04:02: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" Feb 26 04:02:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 04:02:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 04:02:05 volumio volumio[17602]: info: ------------------------------------------- Feb 26 04:02:05 volumio volumio[17602]: info: ----- Volumio3 ---- Feb 26 04:02:05 volumio volumio[17602]: info: ------------------------------------------- Feb 26 04:02:05 volumio volumio[17602]: info: ----- System startup ---- Feb 26 04:02:05 volumio volumio[17602]: info: ------------------------------------------- Feb 26 04:02:06 volumio volumio[17602]: info: MYVOLUMIO Environment detected Feb 26 04:02:06 volumio volumio[17602]: info: Plugin folders cleanup Feb 26 04:02:06 volumio volumio[17602]: info: Scanning into folder /volumio/app/plugins/ Feb 26 04:02:06 volumio volumio[17602]: info: Scanning category audio_interface Feb 26 04:02:06 volumio volumio[17602]: info: Scanning category miscellanea Feb 26 04:02:06 volumio volumio[17602]: info: Scanning category music_service Feb 26 04:02:06 volumio volumio[17602]: info: Scanning category plugins.json Feb 26 04:02:06 volumio volumio[17602]: info: Scanning category system_controller Feb 26 04:02:06 volumio volumio[17602]: info: Scanning category user_interface Feb 26 04:02:06 volumio volumio[17602]: info: Scanning into folder /data/plugins/ Feb 26 04:02:06 volumio volumio[17602]: info: Scanning category music_service Feb 26 04:02:06 volumio volumio[17602]: info: Plugin folders cleanup completed Feb 26 04:02:06 volumio volumio[17602]: info: ------------------------------------------- Feb 26 04:02:06 volumio volumio[17602]: info: ----- Core plugins startup ---- Feb 26 04:02:06 volumio volumio[17602]: info: ------------------------------------------- Feb 26 04:02:06 volumio volumio[17602]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 04:02:06 volumio volumio[17602]: info: Adding plugin upnp to MyMusic Plugins Feb 26 04:02:06 volumio volumio[17602]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 04:02:06 volumio volumio[17602]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 04:02:06 volumio volumio[17602]: info: Loading plugins from folder /data/plugins/ Feb 26 04:02:06 volumio volumio[17602]: info: Loading plugin "system"... Feb 26 04:02:06 volumio volumio[17602]: info: Loading plugin "appearance"... Feb 26 04:02:07 volumio volumio[17602]: info: Loading plugin "network"... Feb 26 04:02:07 volumio volumio[17602]: info: Refreshing Cached IP Addresses Feb 26 04:02:07 volumio sudo[17631]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 04:02:07 volumio sudo[17631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:07 volumio sudo[17631]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:07 volumio sudo[17633]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 04:02:07 volumio sudo[17633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:07 volumio volumio[17602]: info: Loading plugin "services"... Feb 26 04:02:07 volumio volumio-remote-updater[642]: [2026-02-26 04:02:07] [connect] Successful connection Feb 26 04:02:07 volumio sudo[17633]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:07 volumio volumio[17602]: info: Loading plugin "volumio5onboarding"... Feb 26 04:02:07 volumio sudo[17641]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 04:02:08 volumio sudo[17641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:08 volumio volumio[17602]: info: Loading plugin "alsa_controller"... Feb 26 04:02:08 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 04:02:08 volumio volumio[17602]: info: Loading plugin "wizard"... Feb 26 04:02:08 volumio volumio[17602]: info: Loading plugin "networkfs"... Feb 26 04:02:08 volumio volumio[17602]: info: Starting Udev Watcher for removable devices Feb 26 04:02:08 volumio volumio[17602]: info: Ignoring mount for partition: boot Feb 26 04:02:08 volumio volumio[17602]: info: Ignoring mount for partition: volumio Feb 26 04:02:08 volumio volumio[17602]: info: Ignoring mount for partition: volumio_data Feb 26 04:02:08 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 04:02:08 volumio volumio[17602]: info: Loading plugin "volumio_command_line_client"... Feb 26 04:02:08 volumio volumio[17602]: info: Loading plugin "upnp"... Feb 26 04:02:08 volumio volumio[17602]: info: [1772074928165] Starting Upmpd Daemon Feb 26 04:02:08 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 04:02:08 volumio volumio[17602]: info: Loading plugin "my_music"... Feb 26 04:02:08 volumio volumio[17602]: info: Loading plugin "mpd"... Feb 26 04:02:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 26 04:02:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:02:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:02:08 volumio go-librespot[17661]: go-librespot daemon starting... Feb 26 04:02:08 volumio go-librespot[17662]: time="2026-02-26T04:02:08+01:00" level=info msg="running go-librespot 0.4.0" Feb 26 04:02:08 volumio go-librespot[17662]: time="2026-02-26T04:02:08+01:00" level=debug msg="app state loaded" Feb 26 04:02:08 volumio go-librespot[17662]: time="2026-02-26T04:02:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 04:02:08 volumio volumio[17602]: info: Loading plugin "upnp_browser"... Feb 26 04:02:10 volumio volumio[17602]: info: Starting UPNP Browser Feb 26 04:02:10 volumio volumio[17602]: info: Loading plugin "alarm-clock"... Feb 26 04:02:10 volumio volumio[17602]: info: Loading plugin "airplay_emulation"... Feb 26 04:02:10 volumio volumio[17602]: info: Starting Shairport Sync Feb 26 04:02:10 volumio volumio[17602]: info: Loading plugin "last_100"... Feb 26 04:02:10 volumio volumio[17602]: info: Loading plugin "webradio"... Feb 26 04:02:10 volumio volumio[17602]: info: Loading plugin "i2s_dacs"... Feb 26 04:02:10 volumio volumio[17602]: info: Loading plugin "volumiodiscovery"... Feb 26 04:02:10 volumio volumio[17602]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 04:02:10 volumio node[17602]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 04:02:10 volumio volumio[17602]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 04:02:10 volumio volumio[17602]: *** WARNING *** For more information see Feb 26 04:02:10 volumio volumio[17602]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 04:02:10 volumio volumio[17602]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 04:02:10 volumio volumio[17602]: *** WARNING *** For more information see Feb 26 04:02:10 volumio node[17602]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 04:02:10 volumio node[17602]: *** WARNING *** For more information see Feb 26 04:02:10 volumio node[17602]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 04:02:10 volumio node[17602]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 04:02:10 volumio node[17602]: *** WARNING *** For more information see Feb 26 04:02:10 volumio volumio[17602]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 04:02:10 volumio volumio[17602]: info: Discovery: Started advertising with name: Volumio Feb 26 04:02:10 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 04:02:10 volumio volumio[17602]: info: Loading plugin "spop"... Feb 26 04:02:10 volumio sudo[17641]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:12 volumio volumio[17602]: info: Loading plugin "youtube2"... Feb 26 04:02:14 volumio go-librespot[17662]: time="2026-02-26T04:02:14+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" Feb 26 04:02:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 04:02:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 04:02:14 volumio volumio5-onboarding[1430]: time=2026-02-26T04:02:14.994+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:37290->127.0.0.1:3000: i/o timeout" Feb 26 04:02:15 volumio volumio[17602]: info: Plugin ytcr is not enabled Feb 26 04:02:15 volumio volumio[17602]: info: Loading plugin "ytmusic"... Feb 26 04:02:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 26 04:02:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:02:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:02:17 volumio go-librespot[17672]: go-librespot daemon starting... Feb 26 04:02:17 volumio go-librespot[17673]: time="2026-02-26T04:02:17+01:00" level=info msg="running go-librespot 0.4.0" Feb 26 04:02:17 volumio go-librespot[17673]: time="2026-02-26T04:02:17+01:00" level=debug msg="app state loaded" Feb 26 04:02:17 volumio go-librespot[17673]: time="2026-02-26T04:02:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 04:02:19 volumio volumio[17602]: info: Loading plugin "outputs"... Feb 26 04:02:19 volumio volumio[17602]: info: Loading plugin "albumart"... Feb 26 04:02:19 volumio volumio[17602]: info: Plugin example_plugin is not enabled Feb 26 04:02:19 volumio volumio[17602]: info: Loading plugin "inputs"... Feb 26 04:02:19 volumio volumio[17602]: info: Loading plugin "updater_comm"... Feb 26 04:02:19 volumio volumio[17602]: info: Plugin mpdemulation is not enabled Feb 26 04:02:19 volumio volumio[17602]: info: Loading plugin "rest_api"... Feb 26 04:02:19 volumio volumio[17602]: info: Loading plugin "websocket"... Feb 26 04:02:19 volumio volumio[17602]: info: Starting Socket.io Server version 1.7.4 Feb 26 04:02:19 volumio volumio[17602]: info: Loading i18n strings for locale en Feb 26 04:02:19 volumio volumio[17602]: Updating browse sources language Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::initPlayerControls Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 04:02:19 volumio volumio[17602]: Express server listening on port 3000 Feb 26 04:02:19 volumio volumio[17602]: [Metrics] WebUI: 14s 920.45ms Feb 26 04:02:19 volumio volumio[17602]: info: CoreStateMachine::resetVolumioState Feb 26 04:02:19 volumio volumio[17602]: info: CoreStateMachine::getcurrentVolume Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 04:02:19 volumio volumio[17602]: info: CoreStateMachine::pushState Feb 26 04:02:19 volumio volumio[17602]: info: CorePlayQueue::getTrack 0 Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 04:02:19 volumio volumio[17602]: info: CoreCommandRouter::volumioPushState Feb 26 04:02:19 volumio volumio[17693]: Forking 3 albumart workers Feb 26 04:02:19 volumio sudo[17708]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 04:02:19 volumio sudo[17708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:19 volumio sudo[17708]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:19 volumio sudo[17712]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 04:02:19 volumio sudo[17712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:19 volumio sudo[17712]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:19 volumio volumio[17602]: info: Volumio Network Manager: Network status updated: 1 Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:20 volumio volumio[17602]: info: Reloading queue from file Feb 26 04:02:20 volumio volumio[17602]: 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 Feb 26 04:02:20 volumio volumio[17602]: info: CoreStateMachine::setRepeat true single undefined Feb 26 04:02:20 volumio volumio[17602]: info: CoreStateMachine::pushState Feb 26 04:02:20 volumio volumio[17602]: info: CorePlayQueue::getTrack 0 Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::volumioPushState Feb 26 04:02:20 volumio volumio[17602]: info: CoreStateMachine::setRandom null Feb 26 04:02:20 volumio volumio[17602]: info: CoreStateMachine::pushState Feb 26 04:02:20 volumio volumio[17602]: info: CorePlayQueue::getTrack 0 Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::volumioPushState Feb 26 04:02:20 volumio volumio[17602]: info: Setting Device type: Raspberry PI Feb 26 04:02:20 volumio volumio[17602]: info: Completed loading Core Plugins Feb 26 04:02:20 volumio volumio[17602]: info: Preparing to generate the ALSA configuration file Feb 26 04:02:20 volumio volumio[17602]: 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 Feb 26 04:02:20 volumio volumio[17602]: 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 Feb 26 04:02:20 volumio volumio[17602]: info: Asound.conf file unchanged, so no further update is needed Feb 26 04:02:20 volumio volumio[17602]: info: Output device has changed, restarting MPD Feb 26 04:02:20 volumio sudo[17754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 26 04:02:20 volumio sudo[17754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:20 volumio volumio[17602]: info: Output device has changed, restarting Shairport Sync Feb 26 04:02:20 volumio sudo[17759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:20 volumio volumio-remote-updater[642]: [2026-02-26 04:02:20] [connect] Successful connection Feb 26 04:02:20 volumio sudo[17759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:20 volumio sudo[17756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 04:02:20 volumio sudo[17756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:20 volumio sudo[17756]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:20 volumio sudo[17754]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:20 volumio volumio[17602]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 04:02:20 volumio volumio[17602]: info: ___________ START PLUGINS ___________ Feb 26 04:02:20 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 26 04:02:20 volumio upmpdcli[1361]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Feb 26 04:02:20 volumio volumio[17602]: info: ControllerMpd::onStart: Initializing MPD Feb 26 04:02:20 volumio volumio[17602]: info: Creating MPD Configuration file Feb 26 04:02:20 volumio sudo[17767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 26 04:02:20 volumio sudo[17767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 04:02:20 volumio volumio[17602]: info: [1772074940675] CoreMusicLibrary::Adding element Media Servers Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 04:02:20 volumio sudo[17769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 04:02:20 volumio sudo[17769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:20 volumio sudo[17769]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:20 volumio sudo[17772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 04:02:20 volumio sudo[17772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:20 volumio volumio[17602]: info: UPNP Browser: Client initialized successfully Feb 26 04:02:20 volumio sudo[17767]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:20 volumio volumio[17602]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:20 volumio volumio[17602]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 04:02:20 volumio volumio[17602]: info: [1772074940882] CoreMusicLibrary::Adding element Last_100 Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 04:02:20 volumio volumio[17602]: info: [1772074940889] CoreMusicLibrary::Adding element Webradio Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 04:02:20 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 04:02:20 volumio volumio[17602]: info: Initializing BBC Radios Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:21 volumio volumio[17602]: info: Creating Spotify config file Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:21 volumio volumio5-onboarding[1430]: time=2026-02-26T04:02:21.108+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Feb 26 04:02:21 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 26 04:02:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 26 04:02:21 volumio systemd[1]: mpd.service: Consumed 28.778s CPU time. Feb 26 04:02:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 26 04:02:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 26 04:02:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 26 04:02:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 26 04:02:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 04:02:21 volumio volumio[17706]: Starting albumart workers Feb 26 04:02:21 volumio sudo[17798]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 04:02:21 volumio sudo[17798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 04:02:21 volumio volumio[17602]: info: [1772074941543] CoreMusicLibrary::Adding element YouTube2 Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 04:02:21 volumio volumio[17602]: Cannot find translation for source YouTube2 Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 04:02:21 volumio volumio[17602]: info: [1772074941556] CoreMusicLibrary::Adding element YouTube Music Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 04:02:21 volumio sudo[17798]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:21 volumio volumio[17602]: Cannot find translation for source YouTube2 Feb 26 04:02:21 volumio volumio[17602]: Cannot find translation for source YouTube Music Feb 26 04:02:21 volumio volumio[17602]: info: Volumio Calling Home Feb 26 04:02:21 volumio volumio[17711]: Starting albumart workers Feb 26 04:02:21 volumio volumio[17709]: Starting albumart workers Feb 26 04:02:21 volumio volumio[17602]: 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 Feb 26 04:02:21 volumio volumio[17602]: info: Received Get System Info Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 04:02:21 volumio volumio[17602]: info: Discovery: Getting this device information Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::volumioGetState Feb 26 04:02:21 volumio volumio[17602]: info: CorePlayQueue::getTrack 0 Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 04:02:21 volumio volumio[17602]: info: MPD Permissions set Feb 26 04:02:21 volumio volumio[17602]: info: MPD Permissions set Feb 26 04:02:21 volumio volumio[17602]: info: Upmpdcli Daemon Started Feb 26 04:02:21 volumio volumio-remote-updater[642]: [2026-02-26 04:02:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1772074940 101 Feb 26 04:02:21 volumio volumio[17602]: 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: 2 Feb 26 04:02:21 volumio volumio[17602]: info: Spotify config file written Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:21 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio sudo[17805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 26 04:02:22 volumio sudo[17805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:22 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 04:02:22 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 26 04:02:22 volumio volumio[17602]: info: No need to fix Spotify hosts Feb 26 04:02:22 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 26 04:02:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:02:22 volumio volumio[17602]: info: Starting Shairport Sync Feb 26 04:02:22 volumio volumio[17602]: info: Starting Shairport Sync Feb 26 04:02:22 volumio volumio[17602]: info: Starting Shairport Sync Feb 26 04:02:22 volumio sudo[17819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 04:02:22 volumio sudo[17819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:02:22 volumio go-librespot[17817]: go-librespot daemon starting... Feb 26 04:02:22 volumio sudo[17805]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:22 volumio sudo[17821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 04:02:22 volumio sudo[17821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:22 volumio go-librespot[17824]: time="2026-02-26T04:02:22+01:00" level=info msg="running go-librespot 0.4.0" Feb 26 04:02:22 volumio go-librespot[17824]: time="2026-02-26T04:02:22+01:00" level=debug msg="app state loaded" Feb 26 04:02:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 26 04:02:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 26 04:02:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 04:02:22 volumio systemd[1]: shairport-sync.service: Consumed 1h 3min 34.310s CPU time. Feb 26 04:02:22 volumio go-librespot[17824]: time="2026-02-26T04:02:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 04:02:22 volumio sudo[17830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 04:02:22 volumio sudo[17830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 04:02:22 volumio sudo[17819]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 26 04:02:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 26 04:02:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 04:02:22 volumio volumio[17602]: info: Shairport-Sync Started Feb 26 04:02:22 volumio volumio[17602]: Error adding Membership: Error: addMembership EINVAL Feb 26 04:02:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 04:02:22 volumio sudo[17821]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:22 volumio volumio[17602]: info: Shairport-Sync Started Feb 26 04:02:22 volumio sudo[17830]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:22 volumio volumio[17602]: info: Shairport-Sync Started Feb 26 04:02:25 volumio mpd[17801]: 2026-02-26T04:02:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 04:02:25 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 26 04:02:25 volumio sudo[17759]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:25 volumio sudo[17772]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:25 volumio upmpdcli[1361]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Feb 26 04:02:25 volumio upmpdcli[1361]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Feb 26 04:02:25 volumio volumio[17602]: info: Completed starting Core Plugins Feb 26 04:02:25 volumio volumio[17602]: info: ------------------------------------------- Feb 26 04:02:25 volumio volumio[17602]: info: ----- MyVolumio plugins startup ---- Feb 26 04:02:25 volumio volumio[17602]: info: ------------------------------------------- Feb 26 04:02:25 volumio volumio[17602]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 26 04:02:25 volumio volumio[17602]: error: MPD error: The expression evaluated to a falsy value: Feb 26 04:02:25 volumio volumio[17602]: assert.ok(self.idling) Feb 26 04:02:25 volumio volumio[17602]: error: The expression evaluated to a falsy value: Feb 26 04:02:25 volumio volumio[17602]: assert.ok(self.idling) Feb 26 04:02:25 volumio volumio[17602]: error: updateQueue error: null Feb 26 04:02:25 volumio volumio[17602]: info: MPD running with PID17801 Feb 26 04:02:25 volumio volumio[17602]: ,establishing connection Feb 26 04:02:25 volumio volumio[17602]: error: updateQueue error: null Feb 26 04:02:25 volumio volumio[17602]: info: go-librespot daemon successfully initialized Feb 26 04:02:28 volumio volumio[17602]: info: Initializing connection to go-librespot Websocket Feb 26 04:02:30 volumio volumio[17602]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 26 04:02:32 volumio volumio[17602]: info: Discovery: adding 2daea153-7217-41ef-9e00-1bb115887254 Feb 26 04:02:32 volumio volumio[17602]: info: Discovery: Found device Volumio Feb 26 04:02:32 volumio volumio[17602]: info: CoreCommandRouter::volumioGetState Feb 26 04:02:32 volumio volumio[17602]: info: CorePlayQueue::getTrack 0 Feb 26 04:02:32 volumio volumio[17602]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 26 04:02:33 volumio volumio[17602]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Feb 26 04:02:34 volumio go-librespot[17824]: time="2026-02-26T04:02:34+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" Feb 26 04:02:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 04:02:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 04:02:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 26 04:02:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:02:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 04:02:37 volumio go-librespot[17886]: go-librespot daemon starting... Feb 26 04:02:37 volumio go-librespot[17887]: time="2026-02-26T04:02:37+01:00" level=info msg="running go-librespot 0.4.0" Feb 26 04:02:37 volumio go-librespot[17887]: time="2026-02-26T04:02:37+01:00" level=debug msg="app state loaded" Feb 26 04:02:37 volumio go-librespot[17887]: time="2026-02-26T04:02:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 04:02:39 volumio go-librespot[17887]: time="2026-02-26T04:02:39+01:00" level=debug msg="new websocket client" Feb 26 04:02:39 volumio volumio[17602]: info: Connection to go-librespot Websocket established Feb 26 04:02:39 volumio volumio[17602]: info: CoreCommandRouter::volumioGetState Feb 26 04:02:39 volumio volumio[17602]: info: CorePlayQueue::getTrack 0 Feb 26 04:02:41 volumio volumio[17602]: info: Discovery: this is already registered, 2daea153-7217-41ef-9e00-1bb115887254 Feb 26 04:02:41 volumio volumio[17602]: info: Discovery: Found device Volumio Feb 26 04:02:41 volumio volumio[17602]: info: CoreCommandRouter::volumioGetState Feb 26 04:02:41 volumio volumio[17602]: info: CorePlayQueue::getTrack 0 Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 26 04:02:41 volumio volumio[17602]: info: Adding plugin bluetooth to MyMusic Plugins Feb 26 04:02:41 volumio volumio[17602]: info: Adding plugin multiroom to MyMusic Plugins Feb 26 04:02:41 volumio volumio[17602]: info: Adding plugin metavolumio to MyMusic Plugins Feb 26 04:02:41 volumio volumio[17602]: info: Adding plugin cd_controller to MyMusic Plugins Feb 26 04:02:41 volumio volumio[17602]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 26 04:02:41 volumio volumio[17602]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 26 04:02:41 volumio volumio[17602]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 26 04:02:41 volumio volumio[17602]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 26 04:02:42 volumio volumio[17602]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 26 04:02:42 volumio volumio[17602]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 26 04:02:42 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:42 volumio volumio[17602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 04:02:42 volumio volumio[17602]: info: Starting MyVolumio Remote Streaming Endpoints Feb 26 04:02:42 volumio volumio[17602]: info: MyVolumio login type: Token Feb 26 04:02:42 volumio volumio[17602]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 26 04:02:42 volumio volumio[17602]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 26 04:02:43 volumio volumio[17602]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 26 04:02:43 volumio volumio[17602]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 26 04:02:43 volumio volumio[17602]: info: Streaming services startup Feb 26 04:02:43 volumio volumio[17602]: info: Starting Streaming Daemon Feb 26 04:02:43 volumio sudo[17909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 26 04:02:43 volumio sudo[17909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 04:02:43 volumio volumio[17602]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 26 04:02:43 volumio sudo[17909]: pam_unix(sudo:session): session closed for user root Feb 26 04:02:43 volumio volumio[17602]: info: Getting Spotify volume Feb 26 04:02:43 volumio volumio[17602]: error: Cannot start Volumio Streaming Daemon Feb 26 04:02:43 volumio volumio[17602]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 26 04:02:43 volumio volumio[17602]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 26 04:02:43 volumio volumio[17602]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=09ffc1fd5fe3835836264e90820c2e93" http://updates.volumio.org/downloader-v1/track-device Feb 26 04:02:43 volumio volumio[17602]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 26 04:02:43 volumio volumio[17602]: Dload Upload Total Spent Left Speed Feb 26 04:02:43 volumio volumio[17602]: [1.0K blob data] Feb 26 04:02:43 volumio volumio[17602]: retrying in 5 seconds, trial 0 Feb 26 04:02:43 volumio volumio[17602]: info: Volumio Calling Home Feb 26 04:02:43 volumio volumio[17602]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 26 04:02:43 volumio volumio[17602]: info: CoreCommandRouter::volumioGetState Feb 26 04:02:43 volumio volumio[17602]: info: CorePlayQueue::getTrack 0 Feb 26 04:02:43 volumio go-librespot[17887]: time="2026-02-26T04:02:43+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" Feb 26 04:02:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 04:02:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 04:02:43 volumio volumio[17602]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 04:02:43 volumio volumio[17602]: Error: socket hang up Feb 26 04:02:43 volumio volumio[17602]: at connResetException (node:internal/errors:720:14) Feb 26 04:02:43 volumio volumio[17602]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 26 04:02:43 volumio volumio[17602]: at Socket.emit (node:events:526:35) Feb 26 04:02:43 volumio volumio[17602]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 26 04:02:43 volumio volumio[17602]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 26 04:02:43 volumio volumio[17602]: code: 'ECONNRESET', Feb 26 04:02:43 volumio volumio[17602]: response: undefined Feb 26 04:02:43 volumio volumio[17602]: } Feb 26 04:02:43 volumio volumio[17602]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 04:02:44 volumio sudo[17932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 04:01' Feb 26 04:02:44 volumio sudo[17932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"