Mar 24 16:50:00 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:02 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:04 volumio-ltp volumio[1249]: info: FusionDsp - Clipping Monitor started Mar 24 16:50:05 volumio-ltp volumio[1249]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 24 16:50:06 volumio-ltp volumio[1249]: info: FusionDsp - Clipping Monitor started Mar 24 16:50:08 volumio-ltp volumio[1249]: info: FusionDsp - Clipping Monitor started Mar 24 16:50:10 volumio-ltp volumio[1249]: info: FusionDsp - Clipping Monitor started Mar 24 16:50:12 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:14 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:22 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:24 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:25 volumio-ltp volumio[1249]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 24 16:50:26 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:28 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:30 volumio-ltp go-librespot[3176181]: time="2026-03-24T16:50:30+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: i/o timeout" Mar 24 16:50:30 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:32 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: time="2026-03-24T16:50:32+02:00" level=error msg="did not receive last pong ack from accesspoint, 598s passed" Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: panic: runtime error: invalid memory address or nil pointer dereference Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x18 pc=0x8a63fb] Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 33 gp=0xc000002000 m=0 mp=0x1463040 [running]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: panic({0xba9580?, 0x13f6450?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/panic.go:802 +0x168 fp=0xc00006fed8 sp=0xc00006fe28 pc=0x4889e8 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.panicmem(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/panic.go:262 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.sigpanic() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/signal_unix.go:925 +0x359 fp=0xc00006ff38 sp=0xc00006fed8 pc=0x48afb9 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0xc0003060f0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:380 +0x1fb fp=0xc00006ffc8 sp=0xc00006ff38 pc=0x8a63fb Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap2() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:277 +0x25 fp=0xc00006ffe0 sp=0xc00006ffc8 pc=0x8a5905 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00006ffe8 sp=0xc00006ffe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 75 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:277 +0xff Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 1 gp=0xc000002380 m=nil [select, 23852 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xc000047c60?, 0x2?, 0x50?, 0xf7?, 0xc000047bfc?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0002b1a70 sp=0xc0002b1a50 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.selectgo(0xc0002b1c60, 0xc000047bf8, 0x0?, 0x0, 0xbe7660?, 0x1) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0002b1bb0 sp=0xc0002b1a70 pc=0x467bd7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0xc0006101e0, 0xc0002b1d80) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/zeroconf/zeroconf.go:283 +0x29a fp=0xc0002b1cf8 sp=0xc0002b1bb0 pc=0x9a379a Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*App).withAppPlayer(0xc000104d00, {0xe0b228, 0x1483020}, 0xc0001ee8a0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:340 +0x590 fp=0xc0002b1e10 sp=0xc0002b1cf8 pc=0xa30810 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*App).withCredentials(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:209 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*App).SpotifyToken(0xc000104d00, {0xe0b228, 0x1483020}, {0xc000014e38?, 0xce6b7b?}, {0xc0000ea360?, 0x2697?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:201 +0xc5 fp=0xc0002b1e68 sp=0xc0002b1e10 pc=0xa2fcc5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.main() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:593 +0x745 fp=0xc0002b1f50 sp=0xc0002b1e68 pc=0xa32965 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.main() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:285 +0x29d fp=0xc0002b1fe0 sp=0xc0002b1f50 pc=0x45509d Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0002b1fe8 sp=0xc0002b1fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 2 gp=0xc000002e00 m=nil [force gc (idle), 3 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xf17cd87e58a62?, 0xef83ba9b36b30?, 0x0?, 0x0?, 0x1?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005efa8 sp=0xc00005ef88 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goparkunlock(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:466 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.forcegchelper() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:373 +0xb3 fp=0xc00005efe0 sp=0xc00005efa8 pc=0x4553d3 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005efe8 sp=0xc00005efe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.init.7 in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:361 +0x1a Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 3 gp=0xc000003340 m=nil [GC sweep wait]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x1460901?, 0x1460960?, 0xc?, 0x9?, 0x1?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005f780 sp=0xc00005f760 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goparkunlock(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:466 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.bgsweep(0xc000088000) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgcsweep.go:323 +0xdf fp=0xc00005f7c8 sp=0xc00005f780 pc=0x43f29f Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcenable.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:212 +0x25 fp=0xc00005f7e0 sp=0xc00005f7c8 pc=0x4332a5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005f7e8 sp=0xc00005f7e0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.gcenable in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:212 +0x66 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 4 gp=0xc000003500 m=nil [GC scavenge wait]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x171a1cb?, 0x1699be3?, 0x0?, 0x0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005ff78 sp=0xc00005ff58 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goparkunlock(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:466 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.(*scavengerState).park(0x1461b00) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc00005ffa8 sp=0xc00005ff78 pc=0x43cd29 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.bgscavenge(0xc000088000) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc00005ffc8 sp=0xc00005ffa8 pc=0x43d2d9 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcenable.gowrap2() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:213 +0x25 fp=0xc00005ffe0 sp=0xc00005ffc8 pc=0x433245 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005ffe8 sp=0xc00005ffe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.gcenable in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:213 +0xa5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 5 gp=0xc000003a40 m=nil [GOMAXPROCS updater (idle), 23852 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000060788 sp=0xc000060768 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goparkunlock(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:466 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.updateMaxProcsGoroutine() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:6720 +0xe7 fp=0xc0000607e0 sp=0xc000060788 pc=0x4632c7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0000607e8 sp=0xc0000607e0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:6708 +0x37 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 6 gp=0xc0000ac000 m=nil [finalizer wait, 23852 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x464295?, 0x42ac45?, 0xb8?, 0x1?, 0xc000002380?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005e620 sp=0xc00005e600 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.runFinalizers() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mfinal.go:210 +0x107 fp=0xc00005e7e0 sp=0xc00005e620 pc=0x4321a7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005e7e8 sp=0xc00005e7e0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.createfing in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mfinal.go:172 +0x3d Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 7 gp=0xc0000ace00 m=nil [cleanup wait, 7 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x7ff1e4f18e00?, 0xc000060f90?, 0xb5?, 0x7a?, 0x7ff1e4f18e00?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000060f68 sp=0xc000060f48 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goparkunlock(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:466 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.(*cleanupQueue).dequeue(0x1461f40) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mcleanup.go:439 +0xc5 fp=0xc000060fa0 sp=0xc000060f68 pc=0x42f3c5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.runCleanups() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mcleanup.go:635 +0x45 fp=0xc000060fe0 sp=0xc000060fa0 pc=0x42fa85 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000060fe8 sp=0xc000060fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mcleanup.go:589 +0xa5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 10 gp=0xc0000ad180 m=nil [IO wait, 23851 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000073a58 sp=0xc000073a38 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.netpollblock(0xc000073aa8?, 0x41f886?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000073a90 sp=0xc000073a58 pc=0x44d977 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.runtime_pollWait(0x7ff1e6f59e00, 0x72) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000073ab0 sp=0xc000073a90 pc=0x4880e5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).wait(0xc000102900?, 0x900000036?, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000073ad8 sp=0xc000073ab0 pc=0x50a827 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).waitRead(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*FD).Accept(0xc000102900) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x28c fp=0xc000073b80 sp=0xc000073ad8 pc=0x50fc4c Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*netFD).accept(0xc000102900) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/fd_unix.go:161 +0x29 fp=0xc000073c38 sp=0xc000073b80 pc=0x57c449 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*TCPListener).accept(0xc00008b300) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1b fp=0xc000073c88 sp=0xc000073c38 pc=0x591bbb Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*TCPListener).Accept(0xc00008b300) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0xc000073cb8 sp=0xc000073c88 pc=0x590d90 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.(*onceCloseListener).Accept(0xc00022e240?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: :1 +0x24 fp=0xc000073cd0 sp=0xc000073cb8 pc=0x73aaa4 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.(*Server).Serve(0xc000104e00, {0xe0a190, 0xc00008b300}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:3463 +0x30c fp=0xc000073e00 sp=0xc000073cd0 pc=0x712a0c Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.Serve(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:2971 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*ConcreteApiServer).serve(0xc0001c83f0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/api_server.go:666 +0x90c fp=0xc000073fc8 sp=0xc000073e00 pc=0xa25c4c Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.NewApiServer.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/api_server.go:332 +0x25 fp=0xc000073fe0 sp=0xc000073fc8 pc=0xa24985 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000073fe8 sp=0xc000073fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by main.NewApiServer in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/api_server.go:332 +0x2ff Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 75 gp=0xc00024e380 m=nil [runnable]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.sellock({0xc000047ed0, 0xa, 0xa?}, {0xc0000476c0, 0xa, 0xa?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/select.go:34 +0xba fp=0xc000047318 sp=0xc000047310 pc=0x46715a Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.selectgo(0xc000047ed0, 0xc0000476ac, 0x0?, 0x0, 0xa?, 0x1) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/select.go:252 +0x3f9 fp=0xc000047458 sp=0xc000047318 pc=0x467719 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*AppPlayer).Run(0xc000102800, {0xe0b228, 0x1483020}, 0xc000231340, 0xc0002313b0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/player.go:661 +0x427 fp=0xc000047fa8 sp=0xc000047458 pc=0xa38667 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*App).withAppPlayer.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:274 +0x30 fp=0xc000047fe0 sp=0xc000047fa8 pc=0xa31430 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000047fe8 sp=0xc000047fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by main.(*App).withAppPlayer in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:274 +0x3c5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 34 gp=0xc0003c2c40 m=nil [GC worker (idle), 359 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xf0466f08e76e4?, 0x1?, 0x7c?, 0xba?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005af38 sp=0xc00005af18 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcBgMarkWorker(0xc0003c8c40) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc00005afc8 sp=0xc00005af38 pc=0x43598b Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc00005afe0 sp=0xc00005afc8 pc=0x435865 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005afe8 sp=0xc00005afe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.gcBgMarkStartWorkers in goroutine 11 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 35 gp=0xc0003c2e00 m=nil [GC worker (idle), 528 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xefb2e09317861?, 0x1?, 0xff?, 0x3f?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005b738 sp=0xc00005b718 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcBgMarkWorker(0xc0003c8c40) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc00005b7c8 sp=0xc00005b738 pc=0x43598b Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc00005b7e0 sp=0xc00005b7c8 pc=0x435865 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005b7e8 sp=0xc00005b7e0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.gcBgMarkStartWorkers in goroutine 11 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 36 gp=0xc0003c2fc0 m=nil [GC worker (idle), 3 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xf17cd881e4d03?, 0x1?, 0x4e?, 0xb?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000075f38 sp=0xc000075f18 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcBgMarkWorker(0xc0003c8c40) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc000075fc8 sp=0xc000075f38 pc=0x43598b Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc000075fe0 sp=0xc000075fc8 pc=0x435865 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000075fe8 sp=0xc000075fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.gcBgMarkStartWorkers in goroutine 11 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 37 gp=0xc0003c3180 m=nil [GC worker (idle), 15504 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xbc9da92efa1e0?, 0x3?, 0x9a?, 0xbe?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000477f38 sp=0xc000477f18 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcBgMarkWorker(0xc0003c8c40) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc000477fc8 sp=0xc000477f38 pc=0x43598b Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc000477fe0 sp=0xc000477fc8 pc=0x435865 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000477fe8 sp=0xc000477fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by runtime.gcBgMarkStartWorkers in goroutine 11 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 50 gp=0xc0003c2000 m=nil [IO wait]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xc00006ed58?, 0x486a25?, 0x20?, 0xe1?, 0x14c?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00006ed28 sp=0xc00006ed08 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.netpollblock(0xe01da0?, 0x1411cb0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc00006ed60 sp=0xc00006ed28 pc=0x44d977 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.runtime_pollWait(0x7ff1e6f59800, 0x72) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc00006ed80 sp=0xc00006ed60 pc=0x4880e5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).wait(0xc000102700?, 0x7ff1e468c908?, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006eda8 sp=0xc00006ed80 pc=0x50a827 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).waitRead(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*FD).RawRead(0xc000102700, 0xc0003140f0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x125 fp=0xc00006ee08 sp=0xc00006eda8 pc=0x5108c5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*rawConn).Read(0xc0004ae030, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc00006ee40 sp=0xc00006ee08 pc=0x58ccf6 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc00004c660, 0xc0006031a0, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x125 fp=0xc00006eea0 sp=0xc00006ee40 pc=0x941a85 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0xc00023c290, {0xc000210000, 0x10000, 0x10000}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv4/payload_cmsg.go:31 +0x225 fp=0xc00006ef48 sp=0xc00006eea0 pc=0x945265 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/grandcat/zeroconf.(*Server).recv4(0xc000602180, 0xc00023c280) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xc7 fp=0xc00006efc0 sp=0xc00006ef48 pc=0x99e627 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x25 fp=0xc00006efe0 sp=0xc00006efc0 pc=0x99e2c5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00006efe8 sp=0xc00006efe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 15 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x78 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 51 gp=0xc0003c21c0 m=nil [IO wait, 23852 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x43c21c?, 0x14848c0?, 0x0?, 0x60?, 0xc000475530?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000531ce0 sp=0xc000531cc0 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.netpollblock(0xe01da0?, 0x1411cb0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000531d18 sp=0xc000531ce0 pc=0x44d977 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.runtime_pollWait(0x7ff1e6f59600, 0x72) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000531d38 sp=0xc000531d18 pc=0x4880e5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).wait(0xc000102780?, 0x7ff1e4f0fb68?, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000531d60 sp=0xc000531d38 pc=0x50a827 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).waitRead(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*FD).RawRead(0xc000102780, 0xc000018000) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x125 fp=0xc000531dc0 sp=0xc000531d60 pc=0x5108c5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*rawConn).Read(0xc0004ae040, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc000531df8 sp=0xc000531dc0 pc=0x58ccf6 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc00004c780, 0xc000524000, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x125 fp=0xc000531e58 sp=0xc000531df8 pc=0x941a85 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0xc00023c2e0, {0xc000200000, 0x10000, 0x10000}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv6/payload_cmsg.go:31 +0x265 fp=0xc000531f48 sp=0xc000531e58 pc=0x948725 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/grandcat/zeroconf.(*Server).recv6(0xc000602180, 0xc00023c2d0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xc7 fp=0xc000531fc0 sp=0xc000531f48 pc=0x99e7e7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x25 fp=0xc000531fe0 sp=0xc000531fc0 pc=0x99e265 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000531fe8 sp=0xc000531fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 15 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xd9 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 74 gp=0xc00024f180 m=nil [select, 23852 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xc00005c720?, 0x3?, 0x0?, 0x0?, 0xc00005c6da?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005c560 sp=0xc00005c540 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.selectgo(0xc00005c720, 0xc00005c6d4, 0x0?, 0x0, 0x0?, 0x1) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc00005c6a0 sp=0xc00005c560 pc=0x467bd7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0xc000414580) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/player/player.go:215 +0x205 fp=0xc00005c7c8 sp=0xc00005c6a0 pc=0x911e65 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/player/player.go:197 +0x25 fp=0xc00005c7e0 sp=0xc00005c7c8 pc=0x911a25 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005c7e8 sp=0xc00005c7e0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/player/player.go:197 +0x1e5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 76 gp=0xc00024f6c0 m=nil [chan receive, 23851 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xc000602f60?, 0xc0002f9080?, 0x0?, 0x0?, 0xc00005cee8?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005ce98 sp=0xc00005ce78 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.chanrecv(0xc000096310, 0xc00005cf98, 0x1) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc00005cf10 sp=0xc00005ce98 pc=0x422773 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.chanrecv2(0x0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/chan.go:514 +0x12 fp=0xc00005cf38 sp=0xc00005cf10 pc=0x4222f2 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*App).withAppPlayer.func1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:284 +0x72 fp=0xc00005cfe0 sp=0xc00005cf38 pc=0xa31372 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005cfe8 sp=0xc00005cfe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by main.(*App).withAppPlayer in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:281 +0x45f Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 77 gp=0xc00024f880 m=nil [chan receive, 23852 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005d698 sp=0xc00005d678 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.chanrecv(0xc0000962a0, 0xc00005d7b8, 0x1) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc00005d710 sp=0xc00005d698 pc=0x422773 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.chanrecv2(0x0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/chan.go:514 +0x12 fp=0xc00005d738 sp=0xc00005d710 pc=0x4222f2 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*App).withAppPlayer.func2() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:300 +0x87 fp=0xc00005d7e0 sp=0xc00005d738 pc=0xa30fa7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005d7e8 sp=0xc00005d7e0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by main.(*App).withAppPlayer in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/main.go:297 +0x51f Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 78 gp=0xc00024fa40 m=nil [IO wait, 29 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000536be0 sp=0xc000536bc0 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.netpollblock(0xc000536c30?, 0x41f886?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000536c18 sp=0xc000536be0 pc=0x44d977 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.runtime_pollWait(0x7ff1e6f59a00, 0x72) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000536c38 sp=0xc000536c18 pc=0x4880e5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).wait(0xc000102400?, 0x900429897?, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000536c60 sp=0xc000536c38 pc=0x50a827 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).waitRead(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*FD).Accept(0xc000102400) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x28c fp=0xc000536d08 sp=0xc000536c60 pc=0x50fc4c Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*netFD).accept(0xc000102400) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/fd_unix.go:161 +0x29 fp=0xc000536dc0 sp=0xc000536d08 pc=0x57c449 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*TCPListener).accept(0xc00008adc0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1b fp=0xc000536e10 sp=0xc000536dc0 pc=0x591bbb Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*TCPListener).Accept(0xc00008adc0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0xc000536e40 sp=0xc000536e10 pc=0x590d90 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.(*onceCloseListener).Accept(0xc00023a090?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: :1 +0x24 fp=0xc000536e58 sp=0xc000536e40 pc=0x73aaa4 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.(*Server).Serve(0xc00042c900, {0xe0a190, 0xc00008adc0}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:3463 +0x30c fp=0xc000536f88 sp=0xc000536e58 pc=0x712a0c Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.Serve(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:2971 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/zeroconf/zeroconf.go:280 +0x85 fp=0xc000536fe0 sp=0xc000536f88 pc=0x9a38a5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000536fe8 sp=0xc000536fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/zeroconf/zeroconf.go:280 +0x156 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 2518 gp=0xc0000ad500 m=nil [IO wait]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0x40?, 0xc000065808?, 0x40?, 0x4?, 0xc0002b2fd8?) Mar 24 16:50:32 volumio-ltp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 24 16:50:32 volumio-ltp volumio[1249]: info: Connection to go-librespot Websocket closed Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0002b2f90 sp=0xc0002b2f70 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.netpollblock(0xc0002b3000?, 0x45f23f?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc0002b2fc8 sp=0xc0002b2f90 pc=0x44d977 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.runtime_pollWait(0x7ff1e4630800, 0x77) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc0002b2fe8 sp=0xc0002b2fc8 pc=0x4880e5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).wait(0xc0002eb980?, 0x0?, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0002b3010 sp=0xc0002b2fe8 pc=0x50a827 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).waitWrite(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*FD).WaitWrite(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_unix.go:685 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*netFD).connect(0xc0002eb980, {0xe0b308, 0xc0001d6a80}, {0x42a214?, 0xc000000022?}, {0xe01e20?, 0xc00023e140?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/fd_unix.go:130 +0x6b6 fp=0xc0002b3188 sp=0xc0002b3010 pc=0x57bd56 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*netFD).dial(0xc0002eb980, {0xe0b308, 0xc0001d6a80}, {0xe0d4b8?, 0x0?}, {0xe0d4b8, 0xc0001cb230}, 0xc00029fdc0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/sock_posix.go:124 +0x3bc fp=0xc0002b3260 sp=0xc0002b3188 pc=0x58df1c Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.socket({0xe0b308, 0xc0001d6a80}, {0xce44e8, 0x3}, 0x2, 0x1, 0xc0002b3620?, 0x0, {0xe0d4b8, 0x0}, ...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/sock_posix.go:70 +0x29b fp=0xc0002b3308 sp=0xc0002b3260 pc=0x58da5b Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.internetSocket({0xe0b308, 0xc0001d6a80}, {0xce44e8, 0x3}, {0xe0d4b8, 0x0}, {0xe0d4b8, 0xc0001cb230}, 0x1, 0x0, ...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/ipsock_posix.go:167 +0xf8 fp=0xc0002b3380 sp=0xc0002b3308 pc=0x585f58 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*sysDialer).doDialTCPProto(0xc000604000, {0xe0b308, 0xc0001d6a80}, 0x0, 0xc0001cb230, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/tcpsock_posix.go:85 +0xe7 fp=0xc0002b3430 sp=0xc0002b3380 pc=0x5916e7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*sysDialer).doDialTCP(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/tcpsock_posix.go:75 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*sysDialer).dialTCP(0x13f53c0?, {0xe0b308?, 0xc0001d6a80?}, 0xc0002b3668?, 0xc0002b3508?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/tcpsock_posix.go:71 +0x69 fp=0xc0002b3470 sp=0xc0002b3430 pc=0x591589 Mar 24 16:50:32 volumio-ltp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*sysDialer).dialSingle(0xc000604000, {0xe0b308, 0xc0001d6a80}, {0xe08180, 0xc0001cb230}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/dial.go:721 +0x3ce fp=0xc0002b3540 sp=0xc0002b3470 pc=0x572bee Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*sysDialer).dialSerial(0xc000604000, {0xe0b308, 0xc0001d6a80}, {0xc0003fc100?, 0x1, 0x5856be?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/dial.go:686 +0x248 fp=0xc0002b3648 sp=0xc0002b3540 pc=0x5723c8 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*sysDialer).dialParallel(0x0?, {0xe0b308?, 0xc0001d6a80?}, {0xc0003fc100?, 0xbc55c0?, 0xce48f8?}, {0x0?, 0xce44e8?, 0x0?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/dial.go:587 +0x30a fp=0xc0002b3860 sp=0xc0002b3648 pc=0x571aaa Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*Dialer).DialContext(0xc0002b3a08, {0xe0b308, 0xc0001d6a80}, {0xce44e8, 0x3}, {0xc000182228, 0x16}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/dial.go:578 +0x6a9 fp=0xc0002b39d0 sp=0xc0002b3860 pc=0x571389 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/proxy.direct.DialContext(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/proxy.(*direct).DialContext(0x13fc020?, {0xe0b308?, 0xc0001d6a80?}, {0xce44e8?, 0x1483020?}, {0xc000182228?, 0x6fc23ac00?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: :1 +0x85 fp=0xc0002b3aa8 sp=0xc0002b39d0 pc=0x8a1765 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: golang.org/x/net/proxy.Dial({0xe0b308, 0xc0001d6a80}, {0xce44e8, 0x3}, {0xc000182228, 0x16}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0x98 fp=0xc0002b3b08 sp=0xc0002b3aa8 pc=0x89f658 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0xc0003060f0, {0xe0b228, 0x1483020}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:95 +0x248 fp=0xc0002b3be0 sp=0xc0002b3b08 pc=0x8a3e68 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0xc0003060f0, {0xe0b228, 0x1483020}, 0xc0003cf630) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:205 +0xc8 fp=0xc0002b3c58 sp=0xc0002b3be0 pc=0x8a4f48 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0xc0003060f0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:394 +0xc7 fp=0xc0002b3ca8 sp=0xc0002b3c58 pc=0x8a6527 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: :1 +0x25 fp=0xc0002b3cc0 sp=0xc0002b3ca8 pc=0x8aa185 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Mar 24 16:50:32 volumio-ltp systemd[1]: go-librespot-daemon.service: Consumed 4h 52min 36.786s CPU time. Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x13 fp=0xc0002b3cd0 sp=0xc0002b3cc0 pc=0x899b73 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0xc0002b3df8?, {0xe08da8, 0xc000250f60}, 0x0, {0x0, 0x0?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0x11c fp=0xc0002b3dc0 sp=0xc0002b3cd0 pc=0x899f5c Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x0?, {0xe08da8?, 0xc000250f60?}, 0x0?, {0x0?, 0x0?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x56 fp=0xc0002b3e18 sp=0xc0002b3dc0 pc=0x899af6 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/cenkalti/backoff/v4.RetryNotify(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/cenkalti/backoff/v4.Retry(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0xc0003060f0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:335 +0x451 fp=0xc0002b3fc8 sp=0xc0002b3e18 pc=0x8a5df1 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:403 +0x25 fp=0xc0002b3fe0 sp=0xc0002b3fc8 pc=0x8a6665 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0002b3fe8 sp=0xc0002b3fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 2530 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/ap/ap.go:403 +0x108 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 110 gp=0xc0000adc00 m=nil [IO wait, 23852 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xc0002b5310?, 0x925374?, 0x45?, 0x66?, 0xb?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0002b52e0 sp=0xc0002b52c0 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.netpollblock(0x4ac5d8?, 0x41f886?, 0x0?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc0002b5318 sp=0xc0002b52e0 pc=0x44d977 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.runtime_pollWait(0x7ff1e4630400, 0x72) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc0002b5338 sp=0xc0002b5318 pc=0x4880e5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).wait(0xc0001f5200?, 0xc000188000?, 0x0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0002b5360 sp=0xc0002b5338 pc=0x50a827 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*pollDesc).waitRead(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: internal/poll.(*FD).Read(0xc0001f5200, {0xc000188000, 0x1000, 0x1000}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x279 fp=0xc0002b53f8 sp=0xc0002b5360 pc=0x50bb19 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*netFD).Read(0xc0001f5200, {0xc000188000?, 0x488f0e?, 0x1?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/fd_posix.go:68 +0x25 fp=0xc0002b5440 sp=0xc0002b53f8 pc=0x57a5a5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*conn).Read(0xc000062468, {0xc000188000?, 0x2?, 0x45ec3f?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/net.go:196 +0x45 fp=0xc0002b5488 sp=0xc0002b5440 pc=0x588ac5 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: io.(*multiReader).Read(0xc000466168, {0xc000188000, 0x1000, 0x1000}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc0002b54d0 sp=0xc0002b5488 pc=0x4c9a73 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: bufio.(*Reader).fill(0xc0006037a0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/bufio/bufio.go:113 +0x103 fp=0xc0002b5508 sp=0xc0002b54d0 pc=0x68e1c3 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: bufio.(*Reader).ReadByte(0xc0006037a0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/bufio/bufio.go:273 +0x27 fp=0xc0002b5528 sp=0xc0002b5508 pc=0x68e8e7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: nhooyr.io/websocket.readFrameHeader(0xc0006037a0, {0xc000308548, 0x8, 0x8}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x85 fp=0xc0002b55b8 sp=0xc0002b5528 pc=0x84aa65 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc0003084e0, {0xe0b1f0, 0x1483020}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xd6 fp=0xc0002b56f8 sp=0xc0002b55b8 pc=0x84c6b6 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: nhooyr.io/websocket.(*Conn).readLoop(0xc0003084e0, {0xe0b1f0, 0x1483020}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x65 fp=0xc0002b5858 sp=0xc0002b56f8 pc=0x84c025 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: nhooyr.io/websocket.(*Conn).reader(0xc0003084e0, {0xe0b1f0, 0x1483020}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0x10f fp=0xc0002b5948 sp=0xc0002b5858 pc=0x84d4af Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: nhooyr.io/websocket.(*Conn).Reader(...) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: nhooyr.io/websocket.(*Conn).Read(0xc0001c8468?, {0xe0b1f0?, 0x1483020?}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x1e fp=0xc0002b5978 sp=0xc0002b5948 pc=0x84b67e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*ConcreteApiServer).serve.func17({0xe0a310, 0xc0003061e0}, 0xc000606840?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/cmd/daemon/api_server.go:636 +0x3ac fp=0xc0002b5a68 sp=0xc0002b5978 pc=0xa2640c Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.HandlerFunc.ServeHTTP(0xc0000c86c0?, {0xe0a310?, 0xc0003061e0?}, 0x2000000000000?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:2322 +0x29 fp=0xc0002b5a90 sp=0xc0002b5a68 pc=0x70f109 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.(*ServeMux).ServeHTTP(0xc0001d55f0?, {0xe0a310, 0xc0003061e0}, 0xc0000ffa40) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:2861 +0x1c7 fp=0xc0002b5ae0 sp=0xc0002b5a90 pc=0x710fe7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xe0a310, 0xc0003061e0}, 0xc0000ffa40) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x164 fp=0xc0002b5b38 sp=0xc0002b5ae0 pc=0xa25e84 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.HandlerFunc.ServeHTTP(0x485cb9?, {0xe0a310?, 0xc0003061e0?}, 0xc00006eb68?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:2322 +0x29 fp=0xc0002b5b60 sp=0xc0002b5b38 pc=0x70f109 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.serverHandler.ServeHTTP({0xc0000c1380?}, {0xe0a310?, 0xc0003061e0?}, 0x1?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:3340 +0x8e fp=0xc0002b5b90 sp=0xc0002b5b60 pc=0x72e58e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.(*conn).serve(0xc00022e000, {0xe0b260, 0xc0001f2e70}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:2109 +0x665 fp=0xc0002b5fb8 sp=0xc0002b5b90 pc=0x70d205 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net/http.(*Server).Serve.gowrap3() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:3493 +0x28 fp=0xc0002b5fe0 sp=0xc0002b5fb8 pc=0x712e08 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0002b5fe8 sp=0xc0002b5fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by net/http.(*Server).Serve in goroutine 10 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/http/server.go:3493 +0x485 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 80 gp=0xc0003c3340 m=nil [select, 23852 minutes]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xc000475740?, 0x5?, 0xf0?, 0x55?, 0xc0004756e6?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000475568 sp=0xc000475548 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.selectgo(0xc000475740, 0xc0004756dc, 0x486405?, 0x0, 0xaf6301?, 0x1) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0004756a8 sp=0xc000475568 pc=0x467bd7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc0003084e0) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x196 fp=0xc0004757c8 sp=0xc0004756a8 pc=0x847556 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: nhooyr.io/websocket.newConn.gowrap1() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x25 fp=0xc0004757e0 sp=0xc0004757c8 pc=0x847165 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0004757e8 sp=0xc0004757e0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by nhooyr.io/websocket.newConn in goroutine 110 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x4c8 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: goroutine 2511 gp=0xc00001f6c0 m=nil [select]: Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.gopark(0xc0003e4fa0?, 0x2?, 0xb9?, 0x2?, 0xc0003e4f7c?) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0003e4e08 sp=0xc0003e4de8 pc=0x488f0e Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.selectgo(0xc0003e4fa0, 0xc0003e4f78, 0x3e00a8f045e06d2?, 0x0, 0x105076606710b87?, 0x1) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0003e4f48 sp=0xc0003e4e08 pc=0x467bd7 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: net.(*netFD).connect.func2() Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/fd_unix.go:107 +0x7a fp=0xc0003e4fe0 sp=0xc0003e4f48 pc=0x57c1da Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: runtime.goexit({}) Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0003e4fe8 sp=0xc0003e4fe0 pc=0x490a41 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: created by net.(*netFD).connect in goroutine 2518 Mar 24 16:50:32 volumio-ltp go-librespot[3176181]: /usr/local/go/src/net/fd_unix.go:106 +0x345 Mar 24 16:50:32 volumio-ltp go-librespot[3176172]: Aborted Mar 24 16:50:34 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:35 volumio-ltp volumio[1249]: info: Initializing connection to go-librespot Websocket Mar 24 16:50:35 volumio-ltp volumio[1249]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 16:50:35 volumio-ltp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Mar 24 16:50:35 volumio-ltp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 16:50:35 volumio-ltp systemd[1]: go-librespot-daemon.service: Consumed 4h 52min 36.786s CPU time. Mar 24 16:50:35 volumio-ltp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 16:50:35 volumio-ltp go-librespot[894891]: go-librespot daemon starting... Mar 24 16:50:35 volumio-ltp go-librespot[894900]: time="2026-03-24T16:50:35+02:00" level=info msg="running go-librespot 0.6.2" Mar 24 16:50:35 volumio-ltp go-librespot[894900]: time="2026-03-24T16:50:35+02:00" level=debug msg="app state loaded" Mar 24 16:50:35 volumio-ltp go-librespot[894900]: time="2026-03-24T16:50:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 16:50:36 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:38 volumio-ltp volumio[1249]: info: FusionDsp - Clipping Monitor started Mar 24 16:50:38 volumio-ltp volumio[1249]: info: Initializing connection to go-librespot Websocket Mar 24 16:50:38 volumio-ltp go-librespot[894900]: time="2026-03-24T16:50:38+02:00" level=debug msg="new websocket client" Mar 24 16:50:38 volumio-ltp volumio[1249]: info: Connection to go-librespot Websocket established Mar 24 16:50:40 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:41 volumio-ltp volumio[1249]: info: Getting Spotify volume Mar 24 16:50:41 volumio-ltp volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 24 16:50:41 volumio-ltp volumio[1249]: info: CoreCommandRouter::volumioGetState Mar 24 16:50:42 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:44 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:45 volumio-ltp volumio[1249]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 24 16:50:46 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:48 volumio-ltp volumio[1249]: info: FusionDsp - Clipping Monitor started Mar 24 16:50:50 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:52 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:54 volumio-ltp volumio[1249]: info: FusionDsp - Clipping Monitor started Mar 24 16:50:56 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:50:58 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:51:00 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:51:02 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:51:04 volumio-ltp volumio[1249]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 16:51:05 volumio-ltp volumio[1249]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 24 16:51:06 volumio-ltp go-librespot[894900]: time="2026-03-24T16:51:06+02: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\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Mar 24 16:51:06 volumio-ltp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 16:51:06 volumio-ltp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 16:51:06 volumio-ltp volumio[1249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 16:51:06 volumio-ltp volumio[1249]: Error: socket hang up Mar 24 16:51:06 volumio-ltp volumio[1249]: at connResetException (node:internal/errors:720:14) Mar 24 16:51:06 volumio-ltp volumio[1249]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 24 16:51:06 volumio-ltp volumio[1249]: at Socket.emit (node:events:526:35) Mar 24 16:51:06 volumio-ltp volumio[1249]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 24 16:51:06 volumio-ltp volumio[1249]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 24 16:51:06 volumio-ltp volumio[1249]: code: 'ECONNRESET', Mar 24 16:51:06 volumio-ltp volumio[1249]: response: undefined Mar 24 16:51:06 volumio-ltp volumio[1249]: } Mar 24 16:51:06 volumio-ltp volumio[1249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 16:51:06 volumio-ltp sudo[905662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 16:50' Mar 24 16:51:06 volumio-ltp sudo[905662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:06 volumio-ltp sudo[905662]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:06 volumio-ltp volumio-remote-updater[849]: [2026-03-24 16:51:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 24 16:51:06 volumio-ltp volumio-remote-updater[849]: [2026-03-24 16:51:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 24 16:51:06 volumio-ltp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 24 16:51:06 volumio-ltp systemd[1]: upmpdcli.service: Deactivated successfully. Mar 24 16:51:06 volumio-ltp systemd[1]: upmpdcli.service: Consumed 3min 15.516s CPU time. Mar 24 16:51:06 volumio-ltp systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 24 16:51:06 volumio-ltp systemd[1]: volumio.service: Consumed 1month 3w 4d 2h 40min 44.700s CPU time. Mar 24 16:51:06 volumio-ltp systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 24 16:51:06 volumio-ltp systemd[1]: dynamicswap.service: Deactivated successfully. Mar 24 16:51:06 volumio-ltp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 24 16:51:06 volumio-ltp systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 24 16:51:06 volumio-ltp systemd[1]: Stopped volumio.service - Volumio Backend Module. Mar 24 16:51:06 volumio-ltp systemd[1]: volumio.service: Consumed 1month 3w 4d 2h 40min 44.700s CPU time. Mar 24 16:51:06 volumio-ltp systemd[1]: Started volumio.service - Volumio Backend Module. Mar 24 16:51:06 volumio-ltp systemd[1]: dynamicswap.service: Deactivated successfully. Mar 24 16:51:07 volumio-ltp volumio[905704]: info: ------------------------------------------- Mar 24 16:51:07 volumio-ltp volumio[905704]: info: ----- Volumio3 ---- Mar 24 16:51:07 volumio-ltp volumio[905704]: info: ------------------------------------------- Mar 24 16:51:07 volumio-ltp volumio[905704]: info: ----- System startup ---- Mar 24 16:51:07 volumio-ltp volumio[905704]: info: ------------------------------------------- Mar 24 16:51:07 volumio-ltp volumio[905704]: info: MYVOLUMIO Environment detected Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Plugin folders cleanup Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning into folder /volumio/app/plugins/ Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category audio_interface Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category miscellanea Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category music_service Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category plugins.json Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category system_controller Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category user_interface Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning into folder /data/plugins/ Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category audio_interface Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category music_service Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category system_hardware Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Scanning category user_interface Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Plugin folders cleanup completed Mar 24 16:51:07 volumio-ltp volumio[905704]: info: ------------------------------------------- Mar 24 16:51:07 volumio-ltp volumio[905704]: info: ----- Core plugins startup ---- Mar 24 16:51:07 volumio-ltp volumio[905704]: info: ------------------------------------------- Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugins from folder /volumio/app/plugins/ Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Adding plugin upnp to MyMusic Plugins Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugins from folder /data/plugins/ Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "system"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "appearance"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "network"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Refreshing Cached IP Addresses Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "services"... Mar 24 16:51:07 volumio-ltp sudo[905732]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 16:51:07 volumio-ltp sudo[905732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "alsa_controller"... Mar 24 16:51:07 volumio-ltp sudo[905734]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 16:51:07 volumio-ltp sudo[905734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:07 volumio-ltp sudo[905734]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:07 volumio-ltp sudo[905732]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:07 volumio-ltp volumio[905704]: warn: Unable to locate the audio output device X-P700. Please configure a valid output device. Mar 24 16:51:07 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "wizard"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "networkfs"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Starting Udev Watcher for removable devices Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Ignoring mount for partition: boot Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Ignoring mount for partition: volumio Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Ignoring mount for partition: volumio_data Mar 24 16:51:07 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "volumio_command_line_client"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "upnp"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: [1774363867775] Starting Upmpd Daemon Mar 24 16:51:07 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "my_music"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "mpd"... Mar 24 16:51:07 volumio-ltp volumio[905704]: info: Loading plugin "upnp_browser"... Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Starting UPNP Browser Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Loading plugin "alarm-clock"... Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Loading plugin "airplay_emulation"... Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Starting Shairport Sync Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Loading plugin "last_100"... Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Loading plugin "webradio"... Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Loading plugin "i2s_dacs"... Mar 24 16:51:08 volumio-ltp volumio[905704]: info: I2S DAC not set, start Auto-detection Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Loading plugin "volumiodiscovery"... Mar 24 16:51:08 volumio-ltp volumio[905704]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 16:51:08 volumio-ltp volumio[905704]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 16:51:08 volumio-ltp volumio[905704]: *** WARNING *** For more information see Mar 24 16:51:08 volumio-ltp volumio[905704]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 16:51:08 volumio-ltp volumio[905704]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 16:51:08 volumio-ltp node[905704]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 16:51:08 volumio-ltp volumio[905704]: *** WARNING *** For more information see Mar 24 16:51:08 volumio-ltp node[905704]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 16:51:08 volumio-ltp node[905704]: *** WARNING *** For more information see Mar 24 16:51:08 volumio-ltp node[905704]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 24 16:51:08 volumio-ltp node[905704]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 16:51:08 volumio-ltp node[905704]: *** WARNING *** For more information see Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Discovery: Started advertising with name: Volumio Ltp Mar 24 16:51:08 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Loading plugin "spop"... Mar 24 16:51:08 volumio-ltp volumio[905704]: info: Loading plugin "youtube2"... Mar 24 16:51:09 volumio-ltp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Mar 24 16:51:09 volumio-ltp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 16:51:09 volumio-ltp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 16:51:09 volumio-ltp go-librespot[905763]: go-librespot daemon starting... Mar 24 16:51:09 volumio-ltp go-librespot[905764]: time="2026-03-24T16:51:09+02:00" level=info msg="running go-librespot 0.6.2" Mar 24 16:51:09 volumio-ltp go-librespot[905764]: time="2026-03-24T16:51:09+02:00" level=debug msg="app state loaded" Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "now_playing"... Mar 24 16:51:09 volumio-ltp go-librespot[905764]: time="2026-03-24T16:51:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "outputs"... Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "albumart"... Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Plugin example_plugin is not enabled Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "inputs"... Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "updater_comm"... Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Plugin mpdemulation is not enabled Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "rest_api"... Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "websocket"... Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Starting Socket.io Server version 1.7.4 Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "fusiondsp"... Mar 24 16:51:09 volumio-ltp volumio[905772]: Forking 3 albumart workers Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Applying required configuration parameters for plugin fusiondsp Mar 24 16:51:09 volumio-ltp volumio[905704]: info: Loading plugin "Bluetoothremote"... Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Applying required configuration parameters for plugin Bluetoothremote Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Loading plugin "music_services_shield"... Mar 24 16:51:10 volumio-ltp volumio[905784]: Starting albumart workers Mar 24 16:51:10 volumio-ltp volumio[905783]: Starting albumart workers Mar 24 16:51:10 volumio-ltp volumio[905782]: Starting albumart workers Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Applying required configuration parameters for plugin music_services_shield Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Loading plugin "Systeminfo"... Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Loading i18n strings for locale gr Mar 24 16:51:10 volumio-ltp volumio[905704]: Updating browse sources language Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::initPlayerControls Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: Express server listening on port 3000 Mar 24 16:51:10 volumio-ltp volumio[905704]: [Metrics] WebUI: 3s 772.85ms Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Setting Device type: x86 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreStateMachine::resetVolumioState Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreStateMachine::getcurrentVolume Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreStateMachine::pushState Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioPushState Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Completed loading Core Plugins Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Preparing to generate the ALSA configuration file Mar 24 16:51:10 volumio-ltp volumio[905704]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Reading ALSA contributions from plugins. Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Volumio Network Manager: Network status updated: 1 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 24 16:51:10 volumio-ltp volumio[905704]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 24 16:51:10 volumio-ltp volumio[905704]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Reloading queue from file Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreStateMachine::setRepeat null single undefined Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreStateMachine::pushState Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioPushState Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreStateMachine::setRandom null Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreStateMachine::pushState Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioPushState Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Asound.conf file unchanged, so no further update is needed Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Output device has changed, restarting MPD Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Output device has changed, restarting Shairport Sync Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:10 volumio-ltp sudo[905839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 16:51:10 volumio-ltp sudo[905839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:10 volumio-ltp sudo[905839]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:10 volumio-ltp sudo[905841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 16:51:10 volumio-ltp sudo[905841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: ___________ START PLUGINS ___________ Mar 24 16:51:10 volumio-ltp volumio[905704]: info: ControllerMpd::onStart: Initializing MPD Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Creating MPD Configuration file Mar 24 16:51:10 volumio-ltp systemd[1]: musicservicesshield.service: Deactivated successfully. Mar 24 16:51:10 volumio-ltp systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 24 16:51:10 volumio-ltp systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 24 16:51:10 volumio-ltp systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 24 16:51:10 volumio-ltp sudo[905849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 16:51:10 volumio-ltp sudo[905849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:10 volumio-ltp sudo[905849]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 16:51:10 volumio-ltp volumio[905704]: info: [1774363870782] CoreMusicLibrary::Adding element Media Servers Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 16:51:10 volumio-ltp volumio[905704]: info: UPNP Browser: Client initialized successfully Mar 24 16:51:10 volumio-ltp sudo[905852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 16:51:10 volumio-ltp sudo[905852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:10 volumio-ltp systemd[1]: mpd.service: Deactivated successfully. Mar 24 16:51:10 volumio-ltp systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 24 16:51:10 volumio-ltp systemd[1]: mpd.service: Consumed 2min 34.559s CPU time. Mar 24 16:51:10 volumio-ltp systemd[1]: mpd.socket: Deactivated successfully. Mar 24 16:51:10 volumio-ltp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 24 16:51:10 volumio-ltp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 16:51:10 volumio-ltp volumio[905704]: info: [1774363870822] CoreMusicLibrary::Adding element Last_100 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 16:51:10 volumio-ltp volumio[905704]: info: [1774363870824] CoreMusicLibrary::Adding element Webradio Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Initializing BBC Radios Mar 24 16:51:10 volumio-ltp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 24 16:51:10 volumio-ltp systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 16:51:10 volumio-ltp systemd[1]: mpd.service: Deactivated successfully. Mar 24 16:51:10 volumio-ltp systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 24 16:51:10 volumio-ltp systemd[1]: mpd.socket: Deactivated successfully. Mar 24 16:51:10 volumio-ltp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 24 16:51:10 volumio-ltp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 24 16:51:10 volumio-ltp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 24 16:51:10 volumio-ltp systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:10 volumio-ltp volumio[905704]: info: Creating Spotify config file Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:10 volumio-ltp sudo[905876]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 16:51:10 volumio-ltp sudo[905876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 16:51:10 volumio-ltp sudo[905876]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 16:51:10 volumio-ltp volumio[905704]: info: [1774363870990] CoreMusicLibrary::Adding element YouTube2 Mar 24 16:51:10 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 16:51:10 volumio-ltp volumio[905704]: Cannot find translation for source YouTube2 Mar 24 16:51:11 volumio-ltp volumio[905704]: info: [now-playing] ConfigUpdater: config version is older than plugin version (1.0.3 < 1.0.5). Will check and apply config updates. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: [now-playing] ConfigUpdater: no config updaters found. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: [now-playing] ConfigUpdater: updated config version to 1.0.5 Mar 24 16:51:11 volumio-ltp volumio[905704]: info: FusionDsp - CamillaDSP binary found. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Loading i18n strings for locale gr Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: FusionDsp - mixtype--------------------- None Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Loading i18n strings for locale gr Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Volumio Calling Home Mar 24 16:51:11 volumio-ltp sudo[905915]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 24 16:51:11 volumio-ltp sudo[905915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Preparing to generate the ALSA configuration file Mar 24 16:51:11 volumio-ltp volumio[905704]: info: [now-playing] App is listening on port 4004. Mar 24 16:51:11 volumio-ltp volumio[905704]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Mar 24 16:51:11 volumio-ltp volumio[905704]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Reading ALSA contributions from plugins. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: MPD Permissions set Mar 24 16:51:11 volumio-ltp volumio[905704]: info: MPD Permissions set Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Spotify config file written Mar 24 16:51:11 volumio-ltp volumio[905704]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp sudo[905929]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 24 16:51:11 volumio-ltp sudo[905929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 24 16:51:11 volumio-ltp systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 24 16:51:11 volumio-ltp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 24 16:51:11 volumio-ltp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 16:51:11 volumio-ltp go-librespot[905940]: go-librespot daemon starting... Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: No need to fix Spotify hosts Mar 24 16:51:11 volumio-ltp volumio-remote-updater[849]: [2026-03-24 16:51:11] [connect] Successful connection Mar 24 16:51:11 volumio-ltp sudo[905929]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:11 volumio-ltp volumio[905704]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 24 16:51:11 volumio-ltp go-librespot[905947]: time="2026-03-24T16:51:11+02:00" level=info msg="running go-librespot 0.6.2" Mar 24 16:51:11 volumio-ltp go-librespot[905947]: time="2026-03-24T16:51:11+02:00" level=debug msg="app state loaded" Mar 24 16:51:11 volumio-ltp go-librespot[905947]: time="2026-03-24T16:51:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 16:51:11 volumio-ltp volumio-remote-updater[849]: [2026-03-24 16:51:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774363871 101 Mar 24 16:51:11 volumio-ltp volumio[905704]: 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: 5 Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Starting Shairport Sync Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Starting Shairport Sync Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Starting Shairport Sync Mar 24 16:51:11 volumio-ltp sudo[905963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 16:51:11 volumio-ltp sudo[905966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 16:51:11 volumio-ltp sudo[905963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:11 volumio-ltp sudo[905966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Asound.conf file unchanged, so no further update is needed Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Output device has changed, restarting MPD Mar 24 16:51:11 volumio-ltp sudo[905964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 16:51:11 volumio-ltp sudo[905964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Output device has changed, restarting Shairport Sync Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:11 volumio-ltp sudo[905970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 16:51:11 volumio-ltp sudo[905970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:11 volumio-ltp sudo[905970]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:11 volumio-ltp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 24 16:51:11 volumio-ltp systemd[1]: shairport-sync.service: Deactivated successfully. Mar 24 16:51:11 volumio-ltp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 16:51:11 volumio-ltp systemd[1]: shairport-sync.service: Consumed 12h 35min 28.096s CPU time. Mar 24 16:51:11 volumio-ltp sudo[905972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 16:51:11 volumio-ltp sudo[905972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:11 volumio-ltp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 16:51:11 volumio-ltp volumio[905704]: info: MPD Permissions set Mar 24 16:51:11 volumio-ltp sudo[905963]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Shairport-Sync Started Mar 24 16:51:11 volumio-ltp sudo[905966]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:11 volumio-ltp volumio[905704]: Error adding Membership: Error: addMembership EINVAL Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 24 16:51:11 volumio-ltp systemd[1]: mpd.service: Deactivated successfully. Mar 24 16:51:11 volumio-ltp systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp systemd[1]: shairport-sync.service: Deactivated successfully. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp systemd[1]: mpd.socket: Deactivated successfully. Mar 24 16:51:11 volumio-ltp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 24 16:51:11 volumio-ltp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 24 16:51:11 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:11 volumio-ltp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Shairport-Sync Started Mar 24 16:51:11 volumio-ltp systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Starting Shairport Sync Mar 24 16:51:11 volumio-ltp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 16:51:11 volumio-ltp sudo[905964]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Shairport-Sync Started Mar 24 16:51:11 volumio-ltp sudo[905995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 16:51:11 volumio-ltp sudo[905995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:11 volumio-ltp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 24 16:51:11 volumio-ltp systemd[1]: shairport-sync.service: Deactivated successfully. Mar 24 16:51:11 volumio-ltp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 16:51:11 volumio-ltp sudo[905992]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 16:51:11 volumio-ltp sudo[905992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 16:51:11 volumio-ltp sudo[905992]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:11 volumio-ltp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 16:51:11 volumio-ltp sudo[905995]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:11 volumio-ltp volumio[905704]: info: Shairport-Sync Started Mar 24 16:51:12 volumio-ltp sudo[905915]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:12 volumio-ltp sudo[906046]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Mar 24 16:51:12 volumio-ltp sudo[906046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:12 volumio-ltp mpd[906013]: 2026-03-24T16:51:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 16:51:12 volumio-ltp systemd[1]: Started mpd.service - Music Player Daemon. Mar 24 16:51:12 volumio-ltp sudo[905852]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:12 volumio-ltp sudo[905972]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:12 volumio-ltp sudo[905841]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:12 volumio-ltp volumio[905704]: info: Completed starting Core Plugins Mar 24 16:51:12 volumio-ltp volumio[905704]: info: ------------------------------------------- Mar 24 16:51:12 volumio-ltp volumio[905704]: info: ----- MyVolumio plugins startup ---- Mar 24 16:51:12 volumio-ltp volumio[905704]: info: ------------------------------------------- Mar 24 16:51:12 volumio-ltp volumio[905704]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 24 16:51:12 volumio-ltp systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 24 16:51:12 volumio-ltp volumio[905704]: error: MPD error: The expression evaluated to a falsy value: Mar 24 16:51:12 volumio-ltp volumio[905704]: assert.ok(self.idling) Mar 24 16:51:12 volumio-ltp volumio[905704]: error: The expression evaluated to a falsy value: Mar 24 16:51:12 volumio-ltp volumio[905704]: assert.ok(self.idling) Mar 24 16:51:12 volumio-ltp volumio[905704]: error: MPD error: The expression evaluated to a falsy value: Mar 24 16:51:12 volumio-ltp volumio[905704]: assert.ok(self.idling) Mar 24 16:51:12 volumio-ltp volumio[905704]: error: The expression evaluated to a falsy value: Mar 24 16:51:12 volumio-ltp volumio[905704]: assert.ok(self.idling) Mar 24 16:51:12 volumio-ltp volumio[905704]: info: MPD running with PID906013 Mar 24 16:51:12 volumio-ltp volumio[905704]: ,establishing connection Mar 24 16:51:12 volumio-ltp volumio[905704]: error: updateQueue error: null Mar 24 16:51:12 volumio-ltp volumio[905704]: error: updateQueue error: null Mar 24 16:51:12 volumio-ltp bash[906064]: mount: /cpusets: unknown filesystem type 'cpuset'. Mar 24 16:51:12 volumio-ltp bash[906064]: dmesg(1) may have more information after failed mount system call. Mar 24 16:51:12 volumio-ltp bash[906071]: mount: /cpusets: unknown filesystem type 'cpuset'. Mar 24 16:51:12 volumio-ltp bash[906071]: dmesg(1) may have more information after failed mount system call. Mar 24 16:51:13 volumio-ltp volumio[905704]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 24 16:51:13 volumio-ltp volumio[905704]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Mar 24 16:51:13 volumio-ltp volumio[905704]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 24 16:51:13 volumio-ltp volumio[905704]: info: camilladsp service started and running in background, instance 1 Mar 24 16:51:13 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:13 volumio-ltp volumio[905704]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for XP700 Mar 24 16:51:13 volumio-ltp volumio[905704]: cannot open device 'volumioHw': No such device Mar 24 16:51:13 volumio-ltp volumio[905704]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Mar 24 16:51:13 volumio-ltp volumio[905704]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for XP700 Mar 24 16:51:13 volumio-ltp volumio[905704]: cannot open device 'volumioHw': No such device Mar 24 16:51:13 volumio-ltp bash[906087]: mount: /cpusets: unknown filesystem type 'cpuset'. Mar 24 16:51:13 volumio-ltp bash[906087]: dmesg(1) may have more information after failed mount system call. Mar 24 16:51:13 volumio-ltp bash[906075]: cset: **> mount of cpuset filesystem failed, do you have permission? Mar 24 16:51:13 volumio-ltp volumio[905704]: info: FusionDsp loaded Mar 24 16:51:13 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 16:51:13 volumio-ltp volumio[905704]: error: FusionDsp - Socket connection not established Mar 24 16:51:13 volumio-ltp volumio[905704]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 24 16:51:13 volumio-ltp volumio[905704]: info: Adding Signal Path Element [object Object] Mar 24 16:51:13 volumio-ltp volumio[905704]: info: Adding fusiondspeq DSP Signal Path Element Mar 24 16:51:13 volumio-ltp volumio[905704]: info: FusionDsp - ---- installed callbackRead Mar 24 16:51:13 volumio-ltp volumio[905704]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 16:51:13 volumio-ltp sudo[906093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 24 16:51:13 volumio-ltp sudo[906093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:13 volumio-ltp sudo[906093]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:13 volumio-ltp systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 24 16:51:13 volumio-ltp systemd[1]: Reloading. Mar 24 16:51:13 volumio-ltp volumio[905704]: info: Discovery: adding 56687777-c033-48d4-a3ae-04e39f1b6747 Mar 24 16:51:13 volumio-ltp volumio[905704]: info: Discovery: Found device Volumio Ltp Mar 24 16:51:13 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetState Mar 24 16:51:13 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:13 volumio-ltp volumio[905704]: info: Discovery: this is already registered, 56687777-c033-48d4-a3ae-04e39f1b6747 Mar 24 16:51:13 volumio-ltp volumio[905704]: info: Discovery: Found device Volumio Ltp Mar 24 16:51:13 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetState Mar 24 16:51:13 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:13 volumio-ltp volumio[905704]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 24 16:51:13 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetState Mar 24 16:51:13 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:13 volumio-ltp systemd[1]: /etc/systemd/system/musicservicesshield.service:14: Failed to parse output specifier, ignoring: jouurnal Mar 24 16:51:13 volumio-ltp systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 24 16:51:13 volumio-ltp systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 24 16:51:13 volumio-ltp sudo[906046]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:13 volumio-ltp volumio[905704]: info: succeeded Mar 24 16:51:14 volumio-ltp volumio[905704]: info: go-librespot daemon successfully initialized Mar 24 16:51:14 volumio-ltp volumio[905704]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetState Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetState Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 16:51:14 volumio-ltp volumio[905704]: info: Received Get System Info Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 16:51:14 volumio-ltp volumio[905704]: info: Discovery: Getting this device information Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetState Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetState Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:14 volumio-ltp volumio[905704]: info: Listing playlists Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 16:51:14 volumio-ltp volumio[905704]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 16:51:17 volumio-ltp volumio[905704]: info: Initializing connection to go-librespot Websocket Mar 24 16:51:17 volumio-ltp go-librespot[905947]: time="2026-03-24T16:51:17+02:00" level=debug msg="new websocket client" Mar 24 16:51:17 volumio-ltp volumio[905704]: info: Connection to go-librespot Websocket established Mar 24 16:51:17 volumio-ltp volumio[905704]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 24 16:51:17 volumio-ltp sudo[906146]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 16:51:17 volumio-ltp sudo[906146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:17 volumio-ltp sudo[906148]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 16:51:17 volumio-ltp sudo[906148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:17 volumio-ltp sudo[906146]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:17 volumio-ltp sudo[906148]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:17 volumio-ltp sudo[906152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 24 16:51:17 volumio-ltp sudo[906152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 16:51:20 volumio-ltp volumio[905704]: info: Getting Spotify volume Mar 24 16:51:20 volumio-ltp volumio[905704]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 24 16:51:20 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumioGetState Mar 24 16:51:20 volumio-ltp volumio[905704]: info: CorePlayQueue::getTrack 0 Mar 24 16:51:21 volumio-ltp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Mar 24 16:51:21 volumio-ltp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 16:51:21 volumio-ltp systemd[1]: upmpdcli.service: Consumed 3min 15.516s CPU time. Mar 24 16:51:21 volumio-ltp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 16:51:21 volumio-ltp sudo[906152]: pam_unix(sudo:session): session closed for user root Mar 24 16:51:21 volumio-ltp volumio[905704]: info: Upmpdcli Daemon Started Mar 24 16:51:21 volumio-ltp upmpdcli[906172]: writing RSA key Mar 24 16:51:32 volumio-ltp volumio[905704]: error: MyVolumio Plugin failed to start in a timely fashion Mar 24 16:51:32 volumio-ltp volumio[905704]: [Metrics] CommandRouter: 25s 493.99ms Mar 24 16:51:32 volumio-ltp volumio[905704]: info: CoreCommandRouter::volumiosetStartupVolume Mar 24 16:51:32 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 16:51:32 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 16:51:32 volumio-ltp volumio[905704]: info: CoreCommandRouter::Close All Modals sent Mar 24 16:51:32 volumio-ltp volumio[905704]: info: CoreCommandRouter::Close All Modals sent Mar 24 16:51:32 volumio-ltp volumio[905704]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 24 16:51:32 volumio-ltp volumio[905704]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 24 16:51:32 volumio-ltp volumio[905704]: info: camilladsp stopping service pid 906083... Mar 24 16:51:32 volumio-ltp volumio[905704]: info: camilladsp service terminated, instance 1 Mar 24 16:51:32 volumio-ltp volumio[905704]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 16:51:32 volumio-ltp volumio[905704]: info: camilladsp service started and running in background, instance 1 Mar 24 16:51:33 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 24 16:51:33 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 16:51:33 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 24 16:51:33 volumio-ltp volumio[905704]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 24 16:51:33 volumio-ltp go-librespot[905947]: time="2026-03-24T16:51:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Mar 24 16:51:33 volumio-ltp volumio[905704]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 16:51:33 volumio-ltp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 16:51:33 volumio-ltp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 16:51:33 volumio-ltp volumio[905704]: Error: socket hang up Mar 24 16:51:33 volumio-ltp volumio[905704]: at connResetException (node:internal/errors:720:14) Mar 24 16:51:33 volumio-ltp volumio[905704]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 24 16:51:33 volumio-ltp volumio[905704]: at Socket.emit (node:events:526:35) Mar 24 16:51:33 volumio-ltp volumio[905704]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 24 16:51:33 volumio-ltp volumio[905704]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 24 16:51:33 volumio-ltp volumio[905704]: code: 'ECONNRESET', Mar 24 16:51:33 volumio-ltp volumio[905704]: response: undefined Mar 24 16:51:33 volumio-ltp volumio[905704]: } Mar 24 16:51:33 volumio-ltp volumio[905704]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 16:51:34 volumio-ltp sudo[906769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 16:50' Mar 24 16:51:34 volumio-ltp sudo[906769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"