May 30 15:38:00 terrasse go-librespot[28767]: time="2026-05-30T15:38:00+02:00" level=trace msg="received dealer pong" May 30 15:38:03 terrasse systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1128. May 30 15:38:03 terrasse systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:38:03 terrasse systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:38:03 terrasse upmpdcli[30755]: Could not open config: /tmp/upmpdcli.conf May 30 15:38:03 terrasse systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 15:38:03 terrasse systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 15:38:18 terrasse systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1129. May 30 15:38:18 terrasse systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:38:18 terrasse systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:38:18 terrasse upmpdcli[30784]: Could not open config: /tmp/upmpdcli.conf May 30 15:38:18 terrasse systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 15:38:18 terrasse systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 15:38:20 terrasse go-librespot[28767]: time="2026-05-30T15:38:20+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.0.182:54314->34.158.1.133:80: read: connection timed out" May 30 15:38:22 terrasse volumio[28569]: info: Discovery: Disconnected from remote: 192.168.0.181 May 30 15:38:22 terrasse volumio[28569]: info: Discovery: Disconnected from remote: 192.168.0.181 May 30 15:38:22 terrasse volumio[28569]: info: Discovery: Disconnected from remote: 192.168.0.181 May 30 15:38:22 terrasse volumio[28569]: info: Discovery: Disconnected from remote: 192.168.0.181 May 30 15:38:22 terrasse volumio[28569]: info: Discovery: Disconnected from remote: 192.168.0.181 May 30 15:38:22 terrasse volumio[28569]: info: Discovery: Disconnected from remote: 192.168.0.181 May 30 15:38:22 terrasse volumio[28569]: info: Discovery: Disconnected from remote: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:28 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:29 terrasse go-librespot[28767]: time="2026-05-30T15:38:29+02:00" level=error msg="did not receive last pong ack from accesspoint, 240s passed" May 30 15:38:29 terrasse go-librespot[28767]: time="2026-05-30T15:38:29+02:00" level=trace msg="sent dealer ping" May 30 15:38:29 terrasse go-librespot[28767]: panic: runtime error: invalid memory address or nil pointer dereference May 30 15:38:29 terrasse go-librespot[28767]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f7ef0] May 30 15:38:29 terrasse go-librespot[28767]: goroutine 32 gp=0x2083208 m=8 mp=0x2081008 [running]: May 30 15:38:29 terrasse go-librespot[28767]: panic({0x841d88, 0xfa0250}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x24fef20 sp=0x24feecc pc=0xa92ec May 30 15:38:29 terrasse go-librespot[28767]: runtime.panicmem(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/panic.go:262 May 30 15:38:29 terrasse go-librespot[28767]: runtime.sigpanic() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x24fef50 sp=0x24fef20 pc=0xabca4 May 30 15:38:29 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x249e2c8) May 30 15:38:29 terrasse go-librespot[28767]: /src/ap/ap.go:380 +0x284 fp=0x24fefe4 sp=0x24fef54 pc=0x4f7ef0 May 30 15:38:29 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() May 30 15:38:29 terrasse go-librespot[28767]: /src/ap/ap.go:277 +0x28 fp=0x24fefec sp=0x24fefe4 pc=0x4f73c4 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24fefec sp=0x24fefec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 82 May 30 15:38:29 terrasse go-librespot[28767]: /src/ap/ap.go:277 +0x15c May 30 15:38:29 terrasse go-librespot[28767]: goroutine 1 gp=0x2002128 m=nil [select, 20 minutes]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2056ce0 sp=0x2056ccc pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.selectgo(0x2056df0, 0x228bdbc, 0x0, 0x0, 0x2, 0x1) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2056d90 sp=0x2056ce0 pc=0x85000 May 30 15:38:29 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x20caf00, 0x2056e90) May 30 15:38:29 terrasse go-librespot[28767]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x2056e38 sp=0x2056d90 pc=0x62f43c May 30 15:38:29 terrasse go-librespot[28767]: main.(*App).withAppPlayer(0x2248400, {0xa3da1c, 0x1005090}, 0x2213540) May 30 15:38:29 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x2056ed0 sp=0x2056e38 pc=0x6bcc60 May 30 15:38:29 terrasse go-librespot[28767]: main.(*App).withCredentials(...) May 30 15:38:29 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:209 May 30 15:38:29 terrasse go-librespot[28767]: main.(*App).SpotifyToken(0x2248400, {0xa3da1c, 0x1005090}, {0x222ef48, 0x6}, {0x226c120, 0x120}) May 30 15:38:29 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x2056f00 sp=0x2056ed0 pc=0x6bc02c May 30 15:38:29 terrasse go-librespot[28767]: main.main() May 30 15:38:29 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:620 +0x660 fp=0x2056fa8 sp=0x2056f00 pc=0x6bf56c May 30 15:38:29 terrasse go-librespot[28767]: runtime.main() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x2056fec sp=0x2056fa8 pc=0x6f390 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2056fec sp=0x2056fec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: goroutine 2 gp=0x20027e8 m=nil [force gc (idle), 2 minutes]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2048fd4 sp=0x2048fc0 pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goparkunlock(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:466 May 30 15:38:29 terrasse go-librespot[28767]: runtime.forcegchelper() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2048fec sp=0x2048fd4 pc=0x6f7f4 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2048fec sp=0x2048fec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by runtime.init.6 in goroutine 1 May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:361 +0x1c May 30 15:38:29 terrasse go-librespot[28767]: goroutine 3 gp=0x2002b48 m=nil [GC sweep wait]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20497c4 sp=0x20497b0 pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goparkunlock(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:466 May 30 15:38:29 terrasse go-librespot[28767]: runtime.bgsweep(0x202e050) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x20497e4 sp=0x20497c4 pc=0x5768c May 30 15:38:29 terrasse go-librespot[28767]: runtime.gcenable.gowrap1() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x20497ec sp=0x20497e4 pc=0x46c3c May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20497ec sp=0x20497ec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by runtime.gcenable in goroutine 1 May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:212 +0x74 May 30 15:38:29 terrasse go-librespot[28767]: goroutine 4 gp=0x2002c68 m=nil [sleep]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x97746c, 0xffa170, 0x14, 0xe, 0x2) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2049f68 sp=0x2049f54 pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goparkunlock(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:466 May 30 15:38:29 terrasse go-librespot[28767]: runtime.(*scavengerState).sleep(0xffa170, 0x4118092400000000) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgcscavenge.go:504 +0x168 fp=0x2049fc8 sp=0x2049f68 pc=0x54c78 May 30 15:38:29 terrasse go-librespot[28767]: runtime.bgscavenge(0x202e050) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgcscavenge.go:662 +0x90 fp=0x2049fe4 sp=0x2049fc8 pc=0x5519c May 30 15:38:29 terrasse go-librespot[28767]: runtime.gcenable.gowrap2() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2049fec sp=0x2049fe4 pc=0x46be8 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2049fec sp=0x2049fec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by runtime.gcenable in goroutine 1 May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:213 +0xbc May 30 15:38:29 terrasse go-librespot[28767]: goroutine 5 gp=0x2002fc8 m=nil [GOMAXPROCS updater (idle), 20 minutes]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x204a7a4 sp=0x204a790 pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goparkunlock(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:466 May 30 15:38:29 terrasse go-librespot[28767]: runtime.updateMaxProcsGoroutine() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x204a7ec sp=0x204a7a4 pc=0x7f174 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x204a7ec sp=0x204a7ec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:6708 +0x40 May 30 15:38:29 terrasse go-librespot[28767]: goroutine 6 gp=0x2003328 m=nil [finalizer wait, 17 minutes]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x204af88 sp=0x204af74 pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.runFinalizers() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x204afec sp=0x204af88 pc=0x45888 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x204afec sp=0x204afec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by runtime.createfing in goroutine 1 May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c May 30 15:38:29 terrasse go-librespot[28767]: goroutine 18 gp=0x2218908 m=nil [cleanup wait, 20 minutes]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20447a4 sp=0x2044790 pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goparkunlock(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:466 May 30 15:38:29 terrasse go-librespot[28767]: runtime.(*cleanupQueue).dequeue(0xffa2e8) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x20447c4 sp=0x20447a4 pc=0x41cb0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.runCleanups() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x20447ec sp=0x20447c4 pc=0x4284c May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20447ec sp=0x20447ec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by runtime.(*cleanupQueue).createGs in goroutine 1 May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 May 30 15:38:29 terrasse go-librespot[28767]: goroutine 21 gp=0x2219b08 m=nil [IO wait, 20 minutes]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x97745c, 0x75948e10, 0x2, 0x2, 0x5) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20454f0 sp=0x20454dc pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.netpollblock(0x75948e00, 0x72, 0x0) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2045508 sp=0x20454f0 pc=0x675a0 May 30 15:38:29 terrasse go-librespot[28767]: internal/poll.runtime_pollWait(0x75948e00, 0x72) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x204551c sp=0x2045508 pc=0xa8864 May 30 15:38:29 terrasse go-librespot[28767]: internal/poll.(*pollDesc).wait(0x2227378, 0x72, 0x0) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2045530 sp=0x204551c pc=0x136044 May 30 15:38:29 terrasse go-librespot[28767]: internal/poll.(*pollDesc).waitRead(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 30 15:38:29 terrasse go-librespot[28767]: internal/poll.(*FD).Accept(0x2227360) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2045578 sp=0x2045530 pc=0x13a800 May 30 15:38:29 terrasse go-librespot[28767]: net.(*netFD).accept(0x2227360) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x20455e0 sp=0x2045578 pc=0x1abc28 May 30 15:38:29 terrasse go-librespot[28767]: net.(*TCPListener).accept(0x2234420) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2045634 sp=0x20455e0 pc=0x1c3554 May 30 15:38:29 terrasse go-librespot[28767]: net.(*TCPListener).Accept(0x2234420) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x2045650 sp=0x2045634 pc=0x1c259c May 30 15:38:29 terrasse go-librespot[28767]: net/http.(*onceCloseListener).Accept(0x206ea00) May 30 15:38:29 terrasse go-librespot[28767]: :1 +0x34 fp=0x2045668 sp=0x2045650 pc=0x367f9c May 30 15:38:29 terrasse go-librespot[28767]: net/http.(*Server).Serve(0x216a168, {0xa3d218, 0x2234420}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x2045700 sp=0x2045668 pc=0x341154 May 30 15:38:29 terrasse go-librespot[28767]: net/http.Serve(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:2971 May 30 15:38:29 terrasse go-librespot[28767]: main.(*ConcreteApiServer).serve(0x22365a0) May 30 15:38:29 terrasse go-librespot[28767]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x20457e4 sp=0x2045700 pc=0x6b17bc May 30 15:38:29 terrasse go-librespot[28767]: main.NewApiServer.gowrap1() May 30 15:38:29 terrasse go-librespot[28767]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x20457ec sp=0x20457e4 pc=0x6b04f4 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20457ec sp=0x20457ec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by main.NewApiServer in goroutine 1 May 30 15:38:29 terrasse go-librespot[28767]: /src/cmd/daemon/api_server.go:332 +0x310 May 30 15:38:29 terrasse go-librespot[28767]: goroutine 52 gp=0x2219c28 m=nil [chan receive, 20 minutes]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x9772f4, 0x207bcf8, 0xe, 0x7, 0x2) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2047f78 sp=0x2047f64 pc=0xa97c0 May 30 15:38:29 terrasse go-librespot[28767]: runtime.chanrecv(0x207bcc0, 0x0, 0x1) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2047fc0 sp=0x2047f78 pc=0x3414c May 30 15:38:29 terrasse go-librespot[28767]: runtime.chanrecv1(0x207bcc0, 0x0) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x2047fd4 sp=0x2047fc0 pc=0x33bcc May 30 15:38:29 terrasse go-librespot[28767]: github.com/godbus/dbus/v5.newConn.func1() May 30 15:38:29 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x2047fec sp=0x2047fd4 pc=0x4aa8ec May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2047fec sp=0x2047fec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 May 30 15:38:29 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c May 30 15:38:29 terrasse go-librespot[28767]: goroutine 448 gp=0x22c8008 m=3 mp=0x204d008 [syscall]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.cgocall(0x75ea90, 0x22a76fc) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/cgocall.go:167 +0x50 fp=0x22a76e4 sp=0x22a76cc pc=0xa628c May 30 15:38:29 terrasse go-librespot[28767]: net._C2func_getaddrinfo(0x22406f0, 0x0, 0x2318480, 0x22ac280) May 30 15:38:29 terrasse go-librespot[28767]: _cgo_gotypes.go:105 +0x44 fp=0x22a76f8 sp=0x22a76e4 pc=0x1caa18 May 30 15:38:29 terrasse go-librespot[28767]: net._C_getaddrinfo.func1(...) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/cgo_unix_cgo.go:84 May 30 15:38:29 terrasse go-librespot[28767]: net._C_getaddrinfo(0x22406f0, 0x0, 0x2318480, 0x22ac280) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/cgo_unix_cgo.go:84 +0x80 fp=0x22a7718 sp=0x22a76f8 pc=0x1cacdc May 30 15:38:29 terrasse go-librespot[28767]: net.cgoLookupHostIP({0x919486, 0x3}, {0x2308e10, 0x13}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/cgo_unix.go:174 +0x1f4 fp=0x22a778c sp=0x22a7718 pc=0x19cfe0 May 30 15:38:29 terrasse go-librespot[28767]: net.cgoLookupIP.func1() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/cgo_unix.go:229 +0x34 fp=0x22a77b4 sp=0x22a778c pc=0x19d604 May 30 15:38:29 terrasse go-librespot[28767]: net.doBlockingWithCtx[...].func1() May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/cgo_unix.go:67 +0x54 fp=0x22a77ec sp=0x22a77b4 pc=0x1cdac8 May 30 15:38:29 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22a77ec sp=0x22a77ec pc=0xb0874 May 30 15:38:29 terrasse go-librespot[28767]: created by net.doBlockingWithCtx[...] in goroutine 447 May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/net/cgo_unix.go:64 +0x204 May 30 15:38:29 terrasse go-librespot[28767]: goroutine 8 gp=0x2169448 m=nil [GC worker (idle), 20 minutes]: May 30 15:38:29 terrasse go-librespot[28767]: runtime.gopark(0x977320, 0x24ac000, 0x1c, 0xa, 0x0) May 30 15:38:29 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2048788 sp=0x2048774 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.gcBgMarkWorker(0x207a9c0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x20487e4 sp=0x2048788 pc=0x49f34 May 30 15:38:30 terrasse go-librespot[28767]: runtime.gcBgMarkStartWorkers.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x20487ec sp=0x20487e4 pc=0x49e14 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20487ec sp=0x20487ec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by runtime.gcBgMarkStartWorkers in goroutine 50 May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c May 30 15:38:30 terrasse go-librespot[28767]: goroutine 9 gp=0x2169568 m=nil [GC worker (idle)]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x977320, 0x24ac200, 0x1c, 0xa, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24fcf88 sp=0x24fcf74 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.gcBgMarkWorker(0x207a9c0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x24fcfe4 sp=0x24fcf88 pc=0x49f34 May 30 15:38:30 terrasse go-librespot[28767]: runtime.gcBgMarkStartWorkers.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x24fcfec sp=0x24fcfe4 pc=0x49e14 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24fcfec sp=0x24fcfec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by runtime.gcBgMarkStartWorkers in goroutine 50 May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c May 30 15:38:30 terrasse go-librespot[28767]: goroutine 10 gp=0x2169688 m=nil [GC worker (idle), 2 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x977320, 0x24ac400, 0x1c, 0xa, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x22a4f88 sp=0x22a4f74 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.gcBgMarkWorker(0x207a9c0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x22a4fe4 sp=0x22a4f88 pc=0x49f34 May 30 15:38:30 terrasse go-librespot[28767]: runtime.gcBgMarkStartWorkers.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x22a4fec sp=0x22a4fe4 pc=0x49e14 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22a4fec sp=0x22a4fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by runtime.gcBgMarkStartWorkers in goroutine 50 May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c May 30 15:38:30 terrasse go-librespot[28767]: goroutine 11 gp=0x21697a8 m=nil [GC worker (idle), 20 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x977320, 0x24ac600, 0x1c, 0xa, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24b2788 sp=0x24b2774 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.gcBgMarkWorker(0x207a9c0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x24b27e4 sp=0x24b2788 pc=0x49f34 May 30 15:38:30 terrasse volumio[28569]: info: Connection to go-librespot Websocket closed May 30 15:38:30 terrasse systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a May 30 15:38:30 terrasse go-librespot[28767]: runtime.gcBgMarkStartWorkers.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x24b27ec sp=0x24b27e4 pc=0x49e14 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24b27ec sp=0x24b27ec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by runtime.gcBgMarkStartWorkers in goroutine 50 May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c May 30 15:38:30 terrasse go-librespot[28767]: goroutine 12 gp=0x2310128 m=nil [IO wait, 20 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x97745c, 0x75948810, 0x2, 0x2, 0x5) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2059cbc sp=0x2059ca8 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.netpollblock(0x75948800, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2059cd4 sp=0x2059cbc pc=0x675a0 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.runtime_pollWait(0x75948800, 0x72) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2059ce8 sp=0x2059cd4 pc=0xa8864 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).wait(0x229fb98, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2059cfc sp=0x2059ce8 pc=0x136044 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).waitRead(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*FD).ReadMsg(0x229fb80, {0x21ee680, 0x10, 0x10}, {0x20fb918, 0x1000, 0x1000}, 0x40000000) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x2059d6c sp=0x2059cfc pc=0x1381bc May 30 15:38:30 terrasse go-librespot[28767]: net.(*netFD).readMsg(0x229fb80, {0x21ee680, 0x10, 0x10}, {0x20fb918, 0x1000, 0x1000}, 0x40000000) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x2059dc8 sp=0x2059d6c pc=0x1aa2c8 May 30 15:38:30 terrasse go-librespot[28767]: net.(*UnixConn).readMsg(0x22aca78, {0x21ee680, 0x10, 0x10}, {0x20fb918, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x2059e28 sp=0x2059dc8 pc=0x1c9d80 May 30 15:38:30 terrasse go-librespot[28767]: net.(*UnixConn).ReadMsgUnix(0x22aca78, {0x21ee680, 0x10, 0x10}, {0x20fb918, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x2059e78 sp=0x2059e28 pc=0x1c820c May 30 15:38:30 terrasse go-librespot[28767]: github.com/godbus/dbus/v5.(*oobReader).Read(0x20fb908, {0x21ee680, 0x10, 0x10}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x2059ec8 sp=0x2059e78 pc=0x4c2af0 May 30 15:38:30 terrasse go-librespot[28767]: io.ReadAtLeast({0xa39d58, 0x20fb908}, {0x21ee680, 0x10, 0x10}, 0x10) May 30 15:38:30 terrasse systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2059ef4 sp=0x2059ec8 pc=0xee954 May 30 15:38:30 terrasse go-librespot[28767]: io.ReadFull(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/io/io.go:354 May 30 15:38:30 terrasse go-librespot[28767]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x21dfa00) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x2059fa0 sp=0x2059ef4 pc=0x4c32c4 May 30 15:38:30 terrasse go-librespot[28767]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x2126500) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x2059fe4 sp=0x2059fa0 pc=0x4aaef4 May 30 15:38:30 terrasse go-librespot[28767]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x2059fec sp=0x2059fe4 pc=0x4a8318 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2059fec sp=0x2059fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c May 30 15:38:30 terrasse go-librespot[28767]: goroutine 83 gp=0x21699e8 m=nil [chan receive, 20 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x9772f4, 0x2274178, 0xe, 0x7, 0x2) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x21a473c sp=0x21a4728 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.chanrecv(0x2274140, 0x21a47d0, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x21a4784 sp=0x21a473c pc=0x3414c May 30 15:38:30 terrasse go-librespot[28767]: runtime.chanrecv2(0x2274140, 0x21a47d0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x21a4798 sp=0x21a4784 pc=0x33bf0 May 30 15:38:30 terrasse go-librespot[28767]: main.(*App).withAppPlayer.func1() May 30 15:38:30 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:284 +0x68 fp=0x21a47ec sp=0x21a4798 pc=0x6bd918 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x21a47ec sp=0x21a47ec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by main.(*App).withAppPlayer in goroutine 1 May 30 15:38:30 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:281 +0x4f8 May 30 15:38:30 terrasse go-librespot[28767]: goroutine 82 gp=0x23107e8 m=nil [select, 2 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2537930 sp=0x253791c pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.selectgo(0x2537f70, 0x2537b20, 0x0, 0x0, 0xa, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x25379e0 sp=0x2537930 pc=0x85000 May 30 15:38:30 terrasse go-librespot[28767]: main.(*AppPlayer).Run(0x25002d0, {0xa3da1c, 0x1005090}, 0x243d640, 0x243d680) May 30 15:38:30 terrasse systemd[1]: go-librespot-daemon.service: Consumed 2.811s CPU time. May 30 15:38:30 terrasse go-librespot[28767]: /src/cmd/daemon/player.go:661 +0x368 fp=0x2537fd4 sp=0x25379e0 pc=0x6c5950 May 30 15:38:30 terrasse go-librespot[28767]: main.(*App).withAppPlayer.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:274 +0x48 fp=0x2537fec sp=0x2537fd4 pc=0x6bd9f0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2537fec sp=0x2537fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by main.(*App).withAppPlayer in goroutine 1 May 30 15:38:30 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:274 +0x46c May 30 15:38:30 terrasse go-librespot[28767]: goroutine 461 gp=0x23106c8 m=nil [IO wait, 2 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x97745c, 0x75948410, 0x2, 0x2, 0x5) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x228aaf4 sp=0x228aae0 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.netpollblock(0x75948400, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x228ab0c sp=0x228aaf4 pc=0x675a0 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.runtime_pollWait(0x75948400, 0x72) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x228ab20 sp=0x228ab0c pc=0xa8864 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).wait(0x23166a8, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x228ab34 sp=0x228ab20 pc=0x136044 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).waitRead(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*FD).Read(0x2316690, {0x2252000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x228ab7c sp=0x228ab34 pc=0x1371c4 May 30 15:38:30 terrasse go-librespot[28767]: net.(*netFD).Read(0x2316690, {0x2252000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x228aba8 sp=0x228ab7c pc=0x1a9e78 May 30 15:38:30 terrasse go-librespot[28767]: net.(*conn).Read(0x2214990, {0x2252000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x228abd4 sp=0x228aba8 pc=0x1b967c May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*atLeastReader).Read(0x23140f0, {0x2252000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x228ac00 sp=0x228abd4 pc=0x2674c0 May 30 15:38:30 terrasse go-librespot[28767]: bytes.(*Buffer).ReadFrom(0x2120a8c, {0xa3a068, 0x23140f0}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x228ac3c sp=0x228ac00 pc=0x162058 May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*Conn).readFromUntil(0x2120908, {0xa38fa8, 0x2214990}, 0x5) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x228ac64 sp=0x228ac3c pc=0x26771c May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*Conn).readRecordOrCCS(0x2120908, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x228adc4 sp=0x228ac64 pc=0x264e40 May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*Conn).readRecord(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:589 May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*Conn).Read(0x2120908, {0x226e000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x228adf4 sp=0x228adc4 pc=0x26ac04 May 30 15:38:30 terrasse go-librespot[28767]: bufio.(*Reader).Read(0x25609c0, {0x20e42a4, 0x9, 0x9}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x228ae18 sp=0x228adf4 pc=0x2b72a0 May 30 15:38:30 terrasse go-librespot[28767]: io.ReadAtLeast({0xa391f8, 0x25609c0}, {0x20e42a4, 0x9, 0x9}, 0x9) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x228ae44 sp=0x228ae18 pc=0xee954 May 30 15:38:30 terrasse go-librespot[28767]: io.ReadFull(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/io/io.go:354 May 30 15:38:30 terrasse go-librespot[28767]: net/http.http2readFrameHeader({0x20e42a4, 0x9, 0x9}, {0xa391f8, 0x25609c0}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x228ae6c sp=0x228ae44 pc=0x2f86ac May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*http2Framer).ReadFrame(0x20e4280) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x228aef4 sp=0x228ae6c pc=0x2f8e04 May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*http2clientConnReadLoop).run(0x228afdc) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x228afa4 sp=0x228aef4 pc=0x31d33c May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*http2ClientConn).readLoop(0x2218b48) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x228afe4 sp=0x228afa4 pc=0x31c604 May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*http2Transport).newClientConn.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x228afec sp=0x228afe4 pc=0x316960 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x228afec sp=0x228afec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by net/http.(*http2Transport).newClientConn in goroutine 460 May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 May 30 15:38:30 terrasse go-librespot[28767]: goroutine 65 gp=0x2310a28 m=nil [select, 20 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x21a7e74 sp=0x21a7e60 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.selectgo(0x21a7f80, 0x21a7f4c, 0x0, 0x0, 0x3, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x21a7f24 sp=0x21a7e74 pc=0x85000 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x2227e50) May 30 15:38:30 terrasse go-librespot[28767]: /src/player/player.go:215 +0x1f4 fp=0x21a7fe4 sp=0x21a7f24 pc=0x582954 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /src/player/player.go:197 +0x28 fp=0x21a7fec sp=0x21a7fe4 pc=0x58253c May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x21a7fec sp=0x21a7fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 May 30 15:38:30 terrasse go-librespot[28767]: /src/player/player.go:197 +0x220 May 30 15:38:30 terrasse go-librespot[28767]: goroutine 348 gp=0x2310b48 m=nil [select]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x22e3868 sp=0x22e3854 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.selectgo(0x22e39d8, 0x22e3944, 0x0, 0x0, 0x2, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x22e3918 sp=0x22e3868 pc=0x85000 May 30 15:38:30 terrasse go-librespot[28767]: net.(*Resolver).lookupIPAddr(0xff9700, {0xa3da8c, 0x23168c0}, {0x919486, 0x3}, {0x2308e10, 0x13}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/lookup.go:343 +0x394 fp=0x22e3a20 sp=0x22e3918 pc=0x1b7b00 May 30 15:38:30 terrasse go-librespot[28767]: net.(*Resolver).internetAddrList(0xff9700, {0xa3da8c, 0x23168c0}, {0x919486, 0x3}, {0x2308e10, 0x18}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/ipsock.go:289 +0x6b0 fp=0x22e3ab4 sp=0x22e3a20 pc=0x1b5abc May 30 15:38:30 terrasse go-librespot[28767]: net.(*Resolver).resolveAddrList(0xff9700, {0xa3da8c, 0x23168c0}, {0x9198a7, 0x4}, {0x919486, 0x3}, {0x2308e10, 0x18}, {0x0, ...}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/dial.go:353 +0x510 fp=0x22e3b3c sp=0x22e3ab4 pc=0x1a03f8 May 30 15:38:30 terrasse go-librespot[28767]: net.(*Dialer).DialContext(0x22e3c68, {0xa3da8c, 0x23168c0}, {0x919486, 0x3}, {0x2308e10, 0x18}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/dial.go:560 +0x43c fp=0x22e3c38 sp=0x22e3b3c pc=0x1a1334 May 30 15:38:30 terrasse go-librespot[28767]: golang.org/x/net/proxy.direct.DialContext(...) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 May 30 15:38:30 terrasse go-librespot[28767]: golang.org/x/net/proxy.(*direct).DialContext(0x1005090, {0xa3da8c, 0x23168c0}, {0x919486, 0x3}, {0x2308e10, 0x18}) May 30 15:38:30 terrasse go-librespot[28767]: :1 +0x74 fp=0x22e3ccc sp=0x22e3c38 pc=0x4f2fcc May 30 15:38:30 terrasse go-librespot[28767]: golang.org/x/net/proxy.Dial({0xa3da8c, 0x23168c0}, {0x919486, 0x3}, {0x2308e10, 0x18}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0xac fp=0x22e3d08 sp=0x22e3ccc pc=0x4f0e90 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0x249e2c8, {0xa3da1c, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/ap/ap.go:95 +0x2a8 fp=0x22e3d80 sp=0x22e3d08 pc=0x4f5950 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0x249e2c8, {0xa3da1c, 0x1005090}, 0x23f2120) May 30 15:38:30 terrasse go-librespot[28767]: /src/ap/ap.go:205 +0xd4 fp=0x22e3de4 sp=0x22e3d80 pc=0x4f6a58 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0x249e2c8) May 30 15:38:30 terrasse go-librespot[28767]: /src/ap/ap.go:394 +0xcc fp=0x22e3e0c sp=0x22e3de4 pc=0x4f8018 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() May 30 15:38:30 terrasse go-librespot[28767]: :1 +0x28 fp=0x22e3e1c sp=0x22e3e0c pc=0x4fc270 May 30 15:38:30 terrasse go-librespot[28767]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x22e3e28 sp=0x22e3e1c pc=0x4eb1c8 May 30 15:38:30 terrasse go-librespot[28767]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x22e3ecc, {0xa3c8c0, 0x23265a0}, 0x0, {0x0, 0x0}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x22e3ea4 sp=0x22e3e28 pc=0x4eb644 May 30 15:38:30 terrasse go-librespot[28767]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x22e3f5c, {0xa3c8c0, 0x23265a0}, 0x0, {0x0, 0x0}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x22e3ed4 sp=0x22e3ea4 pc=0x4eb180 May 30 15:38:30 terrasse go-librespot[28767]: github.com/cenkalti/backoff/v4.RetryNotify(...) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 May 30 15:38:30 terrasse go-librespot[28767]: github.com/cenkalti/backoff/v4.Retry(...) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x249e2c8) May 30 15:38:30 terrasse go-librespot[28767]: /src/ap/ap.go:335 +0x434 fp=0x22e3fe4 sp=0x22e3ed4 pc=0x4f7878 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /src/ap/ap.go:403 +0x28 fp=0x22e3fec sp=0x22e3fe4 pc=0x4f81cc May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22e3fec sp=0x22e3fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 355 May 30 15:38:30 terrasse go-librespot[28767]: /src/ap/ap.go:403 +0x120 May 30 15:38:30 terrasse go-librespot[28767]: goroutine 84 gp=0x22c8488 m=nil [chan receive, 20 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x9772f4, 0x2274138, 0xe, 0x7, 0x2) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24b1740 sp=0x24b172c pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.chanrecv(0x2274100, 0x24b17e0, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x24b1788 sp=0x24b1740 pc=0x3414c May 30 15:38:30 terrasse go-librespot[28767]: runtime.chanrecv2(0x2274100, 0x24b17e0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x24b179c sp=0x24b1788 pc=0x33bf0 May 30 15:38:30 terrasse go-librespot[28767]: main.(*App).withAppPlayer.func2() May 30 15:38:30 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:300 +0x6c fp=0x24b17ec sp=0x24b179c pc=0x6bd47c May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24b17ec sp=0x24b17ec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by main.(*App).withAppPlayer in goroutine 1 May 30 15:38:30 terrasse go-librespot[28767]: /src/cmd/daemon/main.go:297 +0x598 May 30 15:38:30 terrasse go-librespot[28767]: goroutine 85 gp=0x22c85a8 m=nil [IO wait, 20 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x97745c, 0x75948a10, 0x2, 0x2, 0x5) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24b1dac sp=0x24b1d98 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.netpollblock(0x75948a00, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x24b1dc4 sp=0x24b1dac pc=0x675a0 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.runtime_pollWait(0x75948a00, 0x72) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x24b1dd8 sp=0x24b1dc4 pc=0xa8864 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).wait(0x229fb48, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24b1dec sp=0x24b1dd8 pc=0x136044 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).waitRead(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*FD).Accept(0x229fb30) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x24b1e34 sp=0x24b1dec pc=0x13a800 May 30 15:38:30 terrasse go-librespot[28767]: net.(*netFD).accept(0x229fb30) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x24b1e9c sp=0x24b1e34 pc=0x1abc28 May 30 15:38:30 terrasse go-librespot[28767]: net.(*TCPListener).accept(0x22b8750) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x24b1ef0 sp=0x24b1e9c pc=0x1c3554 May 30 15:38:30 terrasse go-librespot[28767]: net.(*TCPListener).Accept(0x22b8750) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x24b1f0c sp=0x24b1ef0 pc=0x1c259c May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*onceCloseListener).Accept(0x2096ce0) May 30 15:38:30 terrasse go-librespot[28767]: :1 +0x34 fp=0x24b1f24 sp=0x24b1f0c pc=0x367f9c May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*Server).Serve(0x249e638, {0xa3d218, 0x22b8750}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x24b1fbc sp=0x24b1f24 pc=0x341154 May 30 15:38:30 terrasse go-librespot[28767]: net/http.Serve(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:2971 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() May 30 15:38:30 terrasse go-librespot[28767]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x24b1fec sp=0x24b1fbc pc=0x62f538 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24b1fec sp=0x24b1fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 May 30 15:38:30 terrasse go-librespot[28767]: /src/zeroconf/zeroconf.go:297 +0x160 May 30 15:38:30 terrasse go-librespot[28767]: goroutine 86 gp=0x2310fc8 m=nil [IO wait, 20 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x97745c, 0x74f34410, 0x2, 0x2, 0x5) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2385908 sp=0x23858f4 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.netpollblock(0x74f34400, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2385920 sp=0x2385908 pc=0x675a0 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.runtime_pollWait(0x74f34400, 0x72) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2385934 sp=0x2385920 pc=0xa8864 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).wait(0x23162e8, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2385948 sp=0x2385934 pc=0x136044 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).waitRead(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*FD).Read(0x23162d0, {0x214f000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x2385990 sp=0x2385948 pc=0x1371c4 May 30 15:38:30 terrasse go-librespot[28767]: net.(*netFD).Read(0x23162d0, {0x214f000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x23859bc sp=0x2385990 pc=0x1a9e78 May 30 15:38:30 terrasse go-librespot[28767]: net.(*conn).Read(0x23ee410, {0x214f000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x23859e8 sp=0x23859bc pc=0x1b967c May 30 15:38:30 terrasse go-librespot[28767]: io.(*multiReader).Read(0x2212f90, {0x214f000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x2385a14 sp=0x23859e8 pc=0xefb88 May 30 15:38:30 terrasse go-librespot[28767]: bufio.(*Reader).fill(0x243ba10) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2385a38 sp=0x2385a14 pc=0x2b6c1c May 30 15:38:30 terrasse go-librespot[28767]: bufio.(*Reader).ReadByte(0x243ba10) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2385a44 sp=0x2385a38 pc=0x2b7498 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.readFrameHeader(0x243ba10, {0x2158ab8, 0x8, 0x8}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x2385a84 sp=0x2385a44 pc=0x372a08 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).readFrameHeader(0x2158a88, {0xa3da00, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x2385b08 sp=0x2385a84 pc=0x375bd4 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).readLoop(0x2158a88, {0xa3da00, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x2385bcc sp=0x2385b08 pc=0x375390 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).reader(0x2158a88, {0xa3da00, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x2385c50 sp=0x2385bcc pc=0x376798 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).Reader(...) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).Read(0x2158a88, {0xa3da00, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x2385c78 sp=0x2385c50 pc=0x374944 May 30 15:38:30 terrasse go-librespot[28767]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x249e8f8}, 0x249e798) May 30 15:38:30 terrasse go-librespot[28767]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x2385cfc sp=0x2385c78 pc=0x6b2040 May 30 15:38:30 terrasse go-librespot[28767]: net/http.HandlerFunc.ServeHTTP(0x2032348, {0xa3d2d8, 0x249e8f8}, 0x249e798) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2385d0c sp=0x2385cfc pc=0x33d7d8 May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*ServeMux).ServeHTTP(0x2126300, {0xa3d2d8, 0x249e8f8}, 0x249e798) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x2385d4c sp=0x2385d0c pc=0x33f6c8 May 30 15:38:30 terrasse go-librespot[28767]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x249e8f8}, 0x249e798) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x2385d74 sp=0x2385d4c pc=0x6b19b8 May 30 15:38:30 terrasse go-librespot[28767]: net/http.HandlerFunc.ServeHTTP(0x2010370, {0xa3d2d8, 0x249e8f8}, 0x249e798) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2385d84 sp=0x2385d74 pc=0x33d7d8 May 30 15:38:30 terrasse go-librespot[28767]: net/http.serverHandler.ServeHTTP({0x216a168}, {0xa3d2d8, 0x249e8f8}, 0x249e798) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x2385da0 sp=0x2385d84 pc=0x35cb44 May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*conn).serve(0x20ca900, {0xa3da38, 0x2028390}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x2385fdc sp=0x2385da0 pc=0x33b894 May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*Server).Serve.gowrap3() May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x2385fec sp=0x2385fdc pc=0x3415cc May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2385fec sp=0x2385fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by net/http.(*Server).Serve in goroutine 21 May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/server.go:3493 +0x42c May 30 15:38:30 terrasse go-librespot[28767]: goroutine 98 gp=0x2083448 m=nil [select]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24fae84 sp=0x24fae70 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.selectgo(0x24fafd4, 0x24faf5c, 0x0, 0x0, 0x2, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x24faf34 sp=0x24fae84 pc=0x85000 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x249e588) May 30 15:38:30 terrasse go-librespot[28767]: /src/dealer/dealer.go:138 +0x8c fp=0x24fafe4 sp=0x24faf34 pc=0x5a1d90 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() May 30 15:38:30 terrasse go-librespot[28767]: /src/dealer/dealer.go:129 +0x28 fp=0x24fafec sp=0x24fafe4 pc=0x5a3cd4 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24fafec sp=0x24fafec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 82 May 30 15:38:30 terrasse go-librespot[28767]: /src/dealer/dealer.go:129 +0x15c May 30 15:38:30 terrasse go-librespot[28767]: goroutine 447 gp=0x22c8a28 m=nil [select]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24f8b60 sp=0x24f8b4c pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.selectgo(0x24f8c68, 0x24f8c38, 0x0, 0x0, 0x2, 0x1) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x24f8c10 sp=0x24f8b60 pc=0x85000 May 30 15:38:30 terrasse go-librespot[28767]: net.doBlockingWithCtx[...]({0xa3da54, 0x23f2180}, {0x2308e10, 0x13}, 0x2228600) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/cgo_unix.go:71 +0x280 fp=0x24f8c90 sp=0x24f8c10 pc=0x1cd880 May 30 15:38:30 terrasse go-librespot[28767]: net.cgoLookupIP({0xa3da54, 0x23f2180}, {0x919486, 0x3}, {0x2308e10, 0x13}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/cgo_unix.go:228 +0x98 fp=0x24f8cc0 sp=0x24f8c90 pc=0x19d584 May 30 15:38:30 terrasse go-librespot[28767]: net.(*Resolver).lookupIP(0xff9700, {0xa3da54, 0x23f2180}, {0x919486, 0x3}, {0x2308e10, 0x13}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/lookup_unix.go:64 +0x90 fp=0x24f8efc sp=0x24f8cc0 pc=0x1b8c5c May 30 15:38:30 terrasse go-librespot[28767]: net.(*Resolver).lookupIP-fm({0xa3da54, 0x23f2180}, {0x919486, 0x3}, {0x2308e10, 0x13}) May 30 15:38:30 terrasse go-librespot[28767]: :1 +0x58 fp=0x24f8f30 sp=0x24f8efc pc=0x1d3574 May 30 15:38:30 terrasse go-librespot[28767]: net.init.func1({0xa3da54, 0x23f2180}, 0x22ac268, {0x919486, 0x3}, {0x2308e10, 0x13}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/hook.go:21 +0x4c fp=0x24f8f60 sp=0x24f8f30 pc=0x19abf8 May 30 15:38:30 terrasse go-librespot[28767]: net.(*Resolver).lookupIPAddr.func1() May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/lookup.go:335 +0x58 fp=0x24f8f9c sp=0x24f8f60 pc=0x1b849c May 30 15:38:30 terrasse go-librespot[28767]: internal/singleflight.(*Group).doCall(0xff9708, 0x23f21b0, {0x2240690, 0x17}, 0x2318460) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x24f8fd4 sp=0x24f8f9c pc=0x198e98 May 30 15:38:30 terrasse go-librespot[28767]: internal/singleflight.(*Group).DoChan.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x24f8fec sp=0x24f8fd4 pc=0x198e50 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24f8fec sp=0x24f8fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by internal/singleflight.(*Group).DoChan in goroutine 348 May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x304 May 30 15:38:30 terrasse go-librespot[28767]: goroutine 457 gp=0x22c8ea8 m=nil [IO wait, 2 minutes]: May 30 15:38:30 terrasse go-librespot[28767]: runtime.gopark(0x97745c, 0x75948c10, 0x2, 0x2, 0x5) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x22e7998 sp=0x22e7984 pc=0xa97c0 May 30 15:38:30 terrasse go-librespot[28767]: runtime.netpollblock(0x75948c00, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x22e79b0 sp=0x22e7998 pc=0x675a0 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.runtime_pollWait(0x75948c00, 0x72) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x22e79c4 sp=0x22e79b0 pc=0xa8864 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).wait(0x2316568, 0x72, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x22e79d8 sp=0x22e79c4 pc=0x136044 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*pollDesc).waitRead(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 May 30 15:38:30 terrasse go-librespot[28767]: internal/poll.(*FD).Read(0x2316550, {0x2394000, 0x1500, 0x1500}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x22e7a20 sp=0x22e79d8 pc=0x1371c4 May 30 15:38:30 terrasse go-librespot[28767]: net.(*netFD).Read(0x2316550, {0x2394000, 0x1500, 0x1500}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x22e7a4c sp=0x22e7a20 pc=0x1a9e78 May 30 15:38:30 terrasse go-librespot[28767]: net.(*conn).Read(0x22144f0, {0x2394000, 0x1500, 0x1500}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x22e7a78 sp=0x22e7a4c pc=0x1b967c May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*atLeastReader).Read(0x2314180, {0x2394000, 0x1500, 0x1500}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x22e7aa4 sp=0x22e7a78 pc=0x2674c0 May 30 15:38:30 terrasse go-librespot[28767]: bytes.(*Buffer).ReadFrom(0x212018c, {0xa3a068, 0x2314180}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x22e7ae0 sp=0x22e7aa4 pc=0x162058 May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*Conn).readFromUntil(0x2120008, {0xa38fa8, 0x22144f0}, 0x5) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x22e7b08 sp=0x22e7ae0 pc=0x26771c May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*Conn).readRecordOrCCS(0x2120008, 0x0) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x22e7c68 sp=0x22e7b08 pc=0x264e40 May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*Conn).readRecord(...) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:589 May 30 15:38:30 terrasse go-librespot[28767]: crypto/tls.(*Conn).Read(0x2120008, {0x2154000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x22e7c98 sp=0x22e7c68 pc=0x26ac04 May 30 15:38:30 terrasse go-librespot[28767]: net/http.(*readWriteCloserBody).Read(0x2314070, {0x2154000, 0x1000, 0x1000}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/net/http/transport.go:2578 +0xec fp=0x22e7cb8 sp=0x22e7c98 pc=0x358048 May 30 15:38:30 terrasse go-librespot[28767]: bufio.(*Reader).fill(0x21fd230) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x22e7cdc sp=0x22e7cb8 pc=0x2b6c1c May 30 15:38:30 terrasse go-librespot[28767]: bufio.(*Reader).ReadByte(0x21fd230) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x22e7ce8 sp=0x22e7cdc pc=0x2b7498 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.readFrameHeader(0x21fd230, {0x2158c38, 0x8, 0x8}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x22e7d28 sp=0x22e7ce8 pc=0x372a08 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).readFrameHeader(0x2158c08, {0xa3da00, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x22e7dac sp=0x22e7d28 pc=0x375bd4 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).readLoop(0x2158c08, {0xa3da00, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x22e7e70 sp=0x22e7dac pc=0x375390 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).reader(0x2158c08, {0xa3da00, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x22e7ef4 sp=0x22e7e70 pc=0x376798 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).Reader(...) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 May 30 15:38:30 terrasse go-librespot[28767]: github.com/coder/websocket.(*Conn).Read(0x2158c08, {0xa3da00, 0x1005090}) May 30 15:38:30 terrasse go-librespot[28767]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x22e7f1c sp=0x22e7ef4 pc=0x374944 May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x249e588) May 30 15:38:30 terrasse go-librespot[28767]: /src/dealer/dealer.go:188 +0x58 fp=0x22e7fe4 sp=0x22e7f1c pc=0x5a222c May 30 15:38:30 terrasse go-librespot[28767]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() May 30 15:38:30 terrasse go-librespot[28767]: /src/dealer/dealer.go:296 +0x28 fp=0x22e7fec sp=0x22e7fe4 pc=0x5a2e88 May 30 15:38:30 terrasse go-librespot[28767]: runtime.goexit({}) May 30 15:38:30 terrasse go-librespot[28767]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22e7fec sp=0x22e7fec pc=0xb0874 May 30 15:38:30 terrasse go-librespot[28767]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 437 May 30 15:38:30 terrasse go-librespot[28767]: /src/dealer/dealer.go:296 +0xf8 May 30 15:38:30 terrasse go-librespot[28764]: Aborted May 30 15:38:33 terrasse volumio[28569]: info: Initializing connection to go-librespot Websocket May 30 15:38:33 terrasse volumio[28569]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 30 15:38:33 terrasse systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 30 15:38:33 terrasse systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 30 15:38:33 terrasse systemd[1]: go-librespot-daemon.service: Consumed 2.811s CPU time. May 30 15:38:33 terrasse systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 15:38:33 terrasse go-librespot[30800]: go-librespot daemon starting... May 30 15:38:33 terrasse go-librespot[30801]: time="2026-05-30T15:38:33+02:00" level=info msg="running go-librespot 0.7.1" May 30 15:38:33 terrasse go-librespot[30801]: time="2026-05-30T15:38:33+02:00" level=debug msg="app state loaded" May 30 15:38:33 terrasse go-librespot[30801]: time="2026-05-30T15:38:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 15:38:34 terrasse systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1130. May 30 15:38:34 terrasse systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:38:34 terrasse systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:38:34 terrasse upmpdcli[30809]: Could not open config: /tmp/upmpdcli.conf May 30 15:38:34 terrasse systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 15:38:34 terrasse systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:34 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:36 terrasse volumio[28569]: info: Initializing connection to go-librespot Websocket May 30 15:38:36 terrasse go-librespot[30801]: time="2026-05-30T15:38:36+02:00" level=debug msg="new websocket client" May 30 15:38:36 terrasse volumio[28569]: info: Connection to go-librespot Websocket established May 30 15:38:39 terrasse volumio[28569]: info: Getting Spotify volume May 30 15:38:39 terrasse volumio[28569]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 30 15:38:39 terrasse volumio[28569]: info: CoreCommandRouter::volumioGetState May 30 15:38:39 terrasse volumio[28569]: info: CorePlayQueue::getTrack 0 May 30 15:38:39 terrasse volumio[28569]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection error: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:44 terrasse volumio[28569]: info: Discovery: Remote connection timeout: 192.168.0.181 May 30 15:38:45 terrasse go-librespot[30801]: time="2026-05-30T15:38:45+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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 30 15:38:45 terrasse systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 30 15:38:45 terrasse systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 30 15:38:45 terrasse volumio[28569]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 15:38:45 terrasse volumio[28569]: Error: socket hang up May 30 15:38:45 terrasse volumio[28569]: at connResetException (node:internal/errors:720:14) May 30 15:38:45 terrasse volumio[28569]: at Socket.socketOnEnd (node:_http_client:519:23) May 30 15:38:45 terrasse volumio[28569]: at Socket.emit (node:events:526:35) May 30 15:38:45 terrasse volumio[28569]: at endReadableNT (node:internal/streams/readable:1376:12) May 30 15:38:45 terrasse volumio[28569]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 30 15:38:45 terrasse volumio[28569]: code: 'ECONNRESET', May 30 15:38:45 terrasse volumio[28569]: response: undefined May 30 15:38:45 terrasse volumio[28569]: } May 30 15:38:45 terrasse volumio[28569]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 15:38:46 terrasse sudo[30839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 15:37' May 30 15:38:46 terrasse sudo[30839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:38:47 terrasse sudo[30839]: pam_unix(sudo:session): session closed for user root May 30 15:38:47 terrasse volumio5-onboarding[1207]: time=2026-05-30T15:38:47.308+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" May 30 15:38:47 terrasse volumio-remote-updater[594]: [2026-05-30 15:38:47] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) May 30 15:38:47 terrasse volumio-remote-updater[594]: [2026-05-30 15:38:47] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] May 30 15:38:47 terrasse systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 30 15:38:47 terrasse volumio5-onboarding[1207]: time=2026-05-30T15:38:47.342+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" May 30 15:38:47 terrasse systemd[1]: volumio.service: Failed with result 'exit-code'. May 30 15:38:47 terrasse systemd[1]: volumio.service: Consumed 1min 22.337s CPU time. May 30 15:38:47 terrasse systemd[1]: Started dynamicswap.service - dynamicswap service. May 30 15:38:47 terrasse systemd[1]: dynamicswap.service: Deactivated successfully. May 30 15:38:47 terrasse systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. May 30 15:38:47 terrasse systemd[1]: Started dynamicswap.service - dynamicswap service. May 30 15:38:47 terrasse systemd[1]: Stopped volumio.service - Volumio Backend Module. May 30 15:38:47 terrasse systemd[1]: volumio.service: Consumed 1min 22.337s CPU time. May 30 15:38:47 terrasse systemd[1]: Started volumio.service - Volumio Backend Module. May 30 15:38:47 terrasse systemd[1]: dynamicswap.service: Deactivated successfully. May 30 15:38:48 terrasse volumio5-onboarding[1207]: time=2026-05-30T15:38:48.345+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" May 30 15:38:48 terrasse systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 30 15:38:48 terrasse systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 30 15:38:48 terrasse systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 15:38:48 terrasse go-librespot[30898]: go-librespot daemon starting... May 30 15:38:48 terrasse go-librespot[30899]: time="2026-05-30T15:38:48+02:00" level=info msg="running go-librespot 0.7.1" May 30 15:38:48 terrasse go-librespot[30899]: time="2026-05-30T15:38:48+02:00" level=debug msg="app state loaded" May 30 15:38:48 terrasse go-librespot[30899]: time="2026-05-30T15:38:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 15:38:49 terrasse systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1131. May 30 15:38:49 terrasse systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:38:49 terrasse systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:38:49 terrasse volumio5-onboarding[1207]: time=2026-05-30T15:38:49.348+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" May 30 15:38:49 terrasse upmpdcli[30907]: Could not open config: /tmp/upmpdcli.conf May 30 15:38:49 terrasse systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 15:38:49 terrasse systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 15:38:51 terrasse volumio[30883]: info: ------------------------------------------- May 30 15:38:51 terrasse volumio[30883]: info: ----- Volumio3 ---- May 30 15:38:51 terrasse volumio[30883]: info: ------------------------------------------- May 30 15:38:51 terrasse volumio[30883]: info: ----- System startup ---- May 30 15:38:51 terrasse volumio[30883]: info: ------------------------------------------- May 30 15:38:52 terrasse volumio-remote-updater[594]: [2026-05-30 15:38:52] [connect] Successful connection May 30 15:38:52 terrasse volumio[30883]: info: MYVOLUMIO Environment detected May 30 15:38:52 terrasse volumio[30883]: info: Plugin folders cleanup May 30 15:38:52 terrasse volumio[30883]: info: Scanning into folder /volumio/app/plugins/ May 30 15:38:52 terrasse volumio[30883]: info: Scanning category audio_interface May 30 15:38:52 terrasse volumio[30883]: info: Scanning category miscellanea May 30 15:38:52 terrasse volumio[30883]: info: Scanning category music_service May 30 15:38:52 terrasse volumio[30883]: info: Scanning category plugins.json May 30 15:38:52 terrasse volumio[30883]: info: Scanning category system_controller May 30 15:38:52 terrasse volumio[30883]: info: Scanning category user_interface May 30 15:38:52 terrasse volumio[30883]: info: Scanning into folder /data/plugins/ May 30 15:38:52 terrasse volumio[30883]: info: Scanning category music_service May 30 15:38:52 terrasse volumio[30883]: info: Plugin folders cleanup completed May 30 15:38:52 terrasse volumio[30883]: info: ------------------------------------------- May 30 15:38:52 terrasse volumio[30883]: info: ----- Core plugins startup ---- May 30 15:38:52 terrasse volumio[30883]: info: ------------------------------------------- May 30 15:38:52 terrasse volumio[30883]: info: Loading plugins from folder /volumio/app/plugins/ May 30 15:38:52 terrasse volumio[30883]: info: Adding plugin upnp to MyMusic Plugins May 30 15:38:52 terrasse volumio[30883]: info: Adding plugin airplay_emulation to MyMusic Plugins May 30 15:38:52 terrasse volumio[30883]: info: Adding plugin upnp_browser to MyMusic Plugins May 30 15:38:52 terrasse volumio[30883]: info: Loading plugins from folder /data/plugins/ May 30 15:38:52 terrasse volumio[30883]: info: Loading plugin "system"... May 30 15:38:52 terrasse volumio[30883]: info: Loading plugin "appearance"... May 30 15:38:54 terrasse volumio[30883]: info: Loading plugin "network"... May 30 15:38:54 terrasse volumio[30883]: info: Refreshing Cached IP Addresses May 30 15:38:54 terrasse sudo[30921]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 15:38:54 terrasse sudo[30921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:38:54 terrasse volumio[30883]: info: Loading plugin "services"... May 30 15:38:54 terrasse sudo[30923]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 15:38:54 terrasse sudo[30923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:38:54 terrasse volumio[30883]: info: Loading plugin "volumio5onboarding"... May 30 15:38:54 terrasse sudo[30921]: pam_unix(sudo:session): session closed for user root May 30 15:38:55 terrasse volumio[30883]: info: Loading plugin "alsa_controller"... May 30 15:38:55 terrasse sudo[30930]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 30 15:38:55 terrasse sudo[30923]: pam_unix(sudo:session): session closed for user root May 30 15:38:55 terrasse sudo[30930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:38:55 terrasse sudo[30930]: pam_unix(sudo:session): session closed for user root May 30 15:38:55 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 15:38:55 terrasse volumio[30883]: info: Loading plugin "wizard"... May 30 15:38:55 terrasse volumio[30883]: info: Loading plugin "networkfs"... May 30 15:38:55 terrasse volumio[30883]: info: Starting Udev Watcher for removable devices May 30 15:38:55 terrasse volumio[30883]: info: Ignoring mount for partition: boot May 30 15:38:55 terrasse volumio[30883]: info: Ignoring mount for partition: volumio May 30 15:38:55 terrasse volumio[30883]: info: Ignoring mount for partition: volumio_data May 30 15:38:55 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 15:38:55 terrasse volumio[30883]: info: Loading plugin "volumio_command_line_client"... May 30 15:38:55 terrasse volumio[30883]: info: Plugin upnp is not enabled May 30 15:38:55 terrasse volumio[30883]: info: Loading plugin "my_music"... May 30 15:38:55 terrasse volumio[30883]: info: Loading plugin "mpd"... May 30 15:38:56 terrasse volumio[30883]: info: Plugin upnp_browser is not enabled May 30 15:38:56 terrasse volumio[30883]: info: Loading plugin "alarm-clock"... May 30 15:38:56 terrasse volumio[30883]: info: Plugin airplay_emulation is not enabled May 30 15:38:56 terrasse volumio[30883]: info: Loading plugin "last_100"... May 30 15:38:56 terrasse volumio[30883]: info: Loading plugin "webradio"... May 30 15:38:58 terrasse volumio[30883]: info: Loading plugin "i2s_dacs"... May 30 15:38:58 terrasse volumio[30883]: info: Loading plugin "volumiodiscovery"... May 30 15:38:58 terrasse volumio[30883]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 30 15:38:58 terrasse node[30883]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 30 15:38:58 terrasse volumio[30883]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 15:38:58 terrasse node[30883]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 15:38:58 terrasse volumio[30883]: *** WARNING *** For more information see May 30 15:38:58 terrasse node[30883]: *** WARNING *** For more information see May 30 15:38:58 terrasse volumio[30883]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 30 15:38:58 terrasse node[30883]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 30 15:38:58 terrasse volumio[30883]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 15:38:58 terrasse volumio[30883]: *** WARNING *** For more information see May 30 15:38:58 terrasse node[30883]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 15:38:58 terrasse node[30883]: *** WARNING *** For more information see May 30 15:38:58 terrasse volumio[30883]: info: Applying required configuration parameters for plugin volumiodiscovery May 30 15:38:58 terrasse volumio[30883]: info: Discovery: Started advertising with name: Terrasse May 30 15:38:58 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 15:38:58 terrasse volumio[30883]: info: Loading plugin "spop"... May 30 15:39:00 terrasse volumio5-onboarding[1207]: time=2026-05-30T15:39:00.352+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:55192->127.0.0.1:3000: i/o timeout" May 30 15:39:00 terrasse volumio[30883]: info: Loading plugin "squeezelite_mc"... May 30 15:39:01 terrasse go-librespot[30899]: time="2026-05-30T15:39:01+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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 30 15:39:01 terrasse systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 30 15:39:01 terrasse systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 30 15:39:01 terrasse volumio[30883]: info: Loading plugin "outputs"... May 30 15:39:01 terrasse volumio[30883]: info: Loading plugin "albumart"... May 30 15:39:01 terrasse volumio[30883]: info: Plugin example_plugin is not enabled May 30 15:39:01 terrasse volumio[30883]: info: Loading plugin "inputs"... May 30 15:39:02 terrasse volumio[30883]: info: Loading plugin "updater_comm"... May 30 15:39:02 terrasse volumio[30883]: info: Plugin mpdemulation is not enabled May 30 15:39:02 terrasse volumio[30883]: info: Loading plugin "rest_api"... May 30 15:39:02 terrasse volumio[30883]: info: Loading plugin "websocket"... May 30 15:39:02 terrasse volumio[30883]: info: Starting Socket.io Server version 1.7.4 May 30 15:39:02 terrasse volumio[30883]: info: Loading i18n strings for locale de May 30 15:39:02 terrasse volumio[30883]: Updating browse sources language May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::initPlayerControls May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 15:39:02 terrasse volumio[30883]: Express server listening on port 3000 May 30 15:39:02 terrasse volumio[30883]: [Metrics] WebUI: 12s 383.38ms May 30 15:39:02 terrasse volumio[30883]: info: CoreStateMachine::resetVolumioState May 30 15:39:02 terrasse volumio[30883]: info: CoreStateMachine::getcurrentVolume May 30 15:39:02 terrasse volumio[30883]: info: CoreCommandRouter::volumioRetrievevolume May 30 15:39:02 terrasse volumio[30883]: info: Cannot read play queue from file May 30 15:39:02 terrasse volumio[30883]: info: Volumio Network Manager: Network status updated: 1 May 30 15:39:03 terrasse volumio[30961]: Forking 3 albumart workers May 30 15:39:03 terrasse volumio[30883]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 30 15:39:03 terrasse volumio[30883]: wlan0 Interface doesn't support scanning. May 30 15:39:03 terrasse volumio[30883]: info: Cannot use regular scanning, forcing with ap-force May 30 15:39:03 terrasse sudo[30980]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 30 15:39:03 terrasse sudo[30980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:03 terrasse sudo[30980]: pam_unix(sudo:session): session closed for user root May 30 15:39:03 terrasse volumio[30883]: command failed: No such device (-19) May 30 15:39:03 terrasse volumio[30883]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 30 15:39:03 terrasse volumio[30883]: command failed: No such device (-19) May 30 15:39:03 terrasse volumio[30883]: info: VolumeController:: Volume=0 Mute =true May 30 15:39:03 terrasse volumio[30883]: info: CoreStateMachine::pushState May 30 15:39:03 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:03 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:39:03 terrasse volumio[30883]: info: CoreCommandRouter::volumioPushState May 30 15:39:03 terrasse volumio[30883]: info: CoreStateMachine::updateTrackBlock May 30 15:39:03 terrasse volumio[30883]: info: CorePlayQueue::getTrackBlock May 30 15:39:03 terrasse volumio[30883]: info: CoreCommandRouter::volumioRetrievevolume May 30 15:39:03 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 15:39:03 terrasse volumio[30883]: info: Executing start script for DAC IQaudIO DAC Plus May 30 15:39:03 terrasse volumio[30883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 May 30 15:39:03 terrasse volumio[30883]: info: CoreStateMachine::setRepeat null single undefined May 30 15:39:03 terrasse volumio[30883]: info: CoreStateMachine::pushState May 30 15:39:03 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:03 terrasse volumio[30883]: info: CoreCommandRouter::volumioPushState May 30 15:39:03 terrasse volumio[30883]: info: CoreStateMachine::setRandom null May 30 15:39:03 terrasse volumio[30883]: info: CoreStateMachine::pushState May 30 15:39:03 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:03 terrasse volumio[30883]: info: CoreCommandRouter::volumioPushState May 30 15:39:03 terrasse volumio[30883]: info: Setting Device type: Raspberry PI May 30 15:39:03 terrasse volumio[30883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 May 30 15:39:03 terrasse volumio[30883]: info: DAC script executed May 30 15:39:03 terrasse volumio[30883]: info: Completed loading Core Plugins May 30 15:39:03 terrasse volumio[30883]: info: Preparing to generate the ALSA configuration file May 30 15:39:04 terrasse volumio[30883]: info: VolumeController:: Volume=0 Mute =true May 30 15:39:04 terrasse volumio[30883]: info: CoreStateMachine::pushState May 30 15:39:04 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:04 terrasse volumio[30883]: info: CoreCommandRouter::volumioPushState May 30 15:39:04 terrasse volumio[30883]: info: Asound.conf file unchanged, so no further update is needed May 30 15:39:04 terrasse volumio[30883]: info: Output device has changed, restarting MPD May 30 15:39:04 terrasse volumio[30883]: info: ___________ START PLUGINS ___________ May 30 15:39:04 terrasse systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. May 30 15:39:04 terrasse sudo[31028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 15:39:04 terrasse systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 30 15:39:04 terrasse sudo[31028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:04 terrasse sudo[31028]: pam_unix(sudo:session): session closed for user root May 30 15:39:04 terrasse volumio[30883]: info: ControllerMpd::onStart: Initializing MPD May 30 15:39:04 terrasse volumio[30883]: info: Creating MPD Configuration file May 30 15:39:04 terrasse sudo[31030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 15:39:04 terrasse systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 15:39:04 terrasse go-librespot[31032]: go-librespot daemon starting... May 30 15:39:04 terrasse sudo[31030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:04 terrasse volumio[30883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 15:39:04 terrasse volumio[30883]: info: [1780148344448] CoreMusicLibrary::Adding element Last_100 May 30 15:39:04 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 15:39:04 terrasse sudo[31034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 30 15:39:04 terrasse sudo[31034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:04 terrasse volumio[30883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 15:39:04 terrasse volumio[30883]: info: [1780148344481] CoreMusicLibrary::Adding element Webradio May 30 15:39:04 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 15:39:04 terrasse go-librespot[31037]: time="2026-05-30T15:39:04+02:00" level=info msg="running go-librespot 0.7.1" May 30 15:39:04 terrasse go-librespot[31037]: time="2026-05-30T15:39:04+02:00" level=debug msg="app state loaded" May 30 15:39:04 terrasse sudo[31036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 15:39:04 terrasse sudo[31036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:04 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 15:39:04 terrasse systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1132. May 30 15:39:04 terrasse sudo[31036]: pam_unix(sudo:session): session closed for user root May 30 15:39:04 terrasse go-librespot[31037]: time="2026-05-30T15:39:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 15:39:04 terrasse volumio[30883]: info: Initializing BBC Radios May 30 15:39:04 terrasse systemd[1]: Stopping mpd.service - Music Player Daemon... May 30 15:39:04 terrasse systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:39:04 terrasse sudo[31045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 15:39:04 terrasse sudo[31045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:04 terrasse systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:39:04 terrasse systemd[1]: mpd.service: Deactivated successfully. May 30 15:39:04 terrasse systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 15:39:04 terrasse systemd[1]: mpd.service: Consumed 6.966s CPU time. May 30 15:39:04 terrasse systemd[1]: mpd.socket: Deactivated successfully. May 30 15:39:04 terrasse systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 15:39:04 terrasse sudo[31034]: pam_unix(sudo:session): session closed for user root May 30 15:39:04 terrasse systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 15:39:04 terrasse systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 15:39:04 terrasse systemd[1]: Starting mpd.service - Music Player Daemon... May 30 15:39:04 terrasse upmpdcli[31051]: Could not open config: /tmp/upmpdcli.conf May 30 15:39:04 terrasse volumio-remote-updater[594]: [2026-05-30 15:39:04] [connect] Successful connection May 30 15:39:04 terrasse systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 15:39:04 terrasse systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 15:39:04 terrasse systemd[1]: mpd.service: Deactivated successfully. May 30 15:39:04 terrasse systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 15:39:04 terrasse systemd[1]: mpd.socket: Deactivated successfully. May 30 15:39:04 terrasse systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 15:39:04 terrasse systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 15:39:04 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 15:39:04 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 15:39:04 terrasse systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 15:39:05 terrasse volumio[30883]: info: Creating Spotify config file May 30 15:39:05 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:05 terrasse systemd[1]: Starting mpd.service - Music Player Daemon... May 30 15:39:05 terrasse sudo[31070]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 15:39:05 terrasse sudo[31070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 15:39:05 terrasse sudo[31070]: pam_unix(sudo:session): session closed for user root May 30 15:39:06 terrasse volumio[30883]: info: [squeezelite_mc] Starting proxy server... May 30 15:39:06 terrasse volumio[30883]: info: Volumio Calling Home May 30 15:39:06 terrasse volumio[30883]: info: [squeezelite_mc] Proxy server started on port 34263 May 30 15:39:06 terrasse volumio[30977]: Starting albumart workers May 30 15:39:06 terrasse volumio[30883]: info: CoreCommandRouter::volumioRetrievevolume May 30 15:39:07 terrasse volumio[30981]: Starting albumart workers May 30 15:39:07 terrasse volumio[30883]: info: MPD Permissions set May 30 15:39:07 terrasse volumio[30883]: info: MPD Permissions set May 30 15:39:07 terrasse volumio-remote-updater[594]: [2026-05-30 15:39:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1780148344 101 May 30 15:39:07 terrasse volumio[30883]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 May 30 15:39:07 terrasse volumio[30883]: info: Spotify config file written May 30 15:39:07 terrasse volumio[30883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 30 15:39:07 terrasse volumio[30883]: info: VolumeController:: Volume=0 Mute =true May 30 15:39:07 terrasse volumio[30883]: info: CoreStateMachine::pushState May 30 15:39:07 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::volumioPushState May 30 15:39:07 terrasse volumio[30982]: Starting albumart workers May 30 15:39:07 terrasse sudo[31093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 30 15:39:07 terrasse sudo[31093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:07 terrasse volumio[30883]: info: [squeezelite_mc] Server discovery started May 30 15:39:07 terrasse volumio[30883]: info: [squeezelite_mc] Player finder started May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: [squeezelite_mc] Executing aplay -D hw:3 --nonblock -f MPEG /dev/zero 2>&1 || true May 30 15:39:07 terrasse systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... May 30 15:39:07 terrasse systemd[1]: go-librespot-daemon.service: Deactivated successfully. May 30 15:39:07 terrasse systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 15:39:07 terrasse go-librespot[31097]: go-librespot daemon starting... May 30 15:39:07 terrasse sudo[31093]: pam_unix(sudo:session): session closed for user root May 30 15:39:07 terrasse go-librespot[31100]: time="2026-05-30T15:39:07+02:00" level=info msg="running go-librespot 0.7.1" May 30 15:39:07 terrasse go-librespot[31100]: time="2026-05-30T15:39:07+02:00" level=debug msg="app state loaded" May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse go-librespot[31100]: time="2026-05-30T15:39:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:07 terrasse volumio[30883]: info: No need to fix Spotify hosts May 30 15:39:08 terrasse volumio[30883]: info: [squeezelite_mc] Card 3 supports the following ALSA formats: ["S16_LE","S24_LE","S32_LE"] May 30 15:39:08 terrasse volumio[30883]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"terrasse","dsdFormat":null,"card":"3","mixerType":"Hardware","mixer":"Analogue"} May 30 15:39:08 terrasse volumio[30883]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 30 15:39:08 terrasse sudo[31118]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 30 15:39:08 terrasse sudo[31118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:08 terrasse sudo[31118]: pam_unix(sudo:session): session closed for user root May 30 15:39:08 terrasse volumio[30883]: info: [squeezelite_mc] Executing alsactl -L -R nrestore May 30 15:39:08 terrasse sudo[31122]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore May 30 15:39:08 terrasse sudo[31122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:08 terrasse sudo[31122]: pam_unix(sudo:session): session closed for user root May 30 15:39:08 terrasse volumio[30883]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 30 15:39:08 terrasse sudo[31126]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 30 15:39:08 terrasse sudo[31126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:08 terrasse sudo[31126]: pam_unix(sudo:session): session closed for user root May 30 15:39:08 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true May 30 15:39:09 terrasse sudo[31135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 30 15:39:09 terrasse sudo[31135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:09 terrasse systemd[1]: Reloading. May 30 15:39:11 terrasse volumio[30883]: info: go-librespot daemon successfully initialized May 30 15:39:11 terrasse 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. May 30 15:39:11 terrasse 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. May 30 15:39:11 terrasse sudo[31135]: pam_unix(sudo:session): session closed for user root May 30 15:39:11 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 30 15:39:12 terrasse sudo[31167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 30 15:39:12 terrasse sudo[31167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:12 terrasse sudo[31167]: pam_unix(sudo:session): session closed for user root May 30 15:39:12 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true May 30 15:39:12 terrasse sudo[31170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite May 30 15:39:12 terrasse sudo[31170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:12 terrasse systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... May 30 15:39:12 terrasse mpd[31086]: 2026-05-30T15:39:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 30 15:39:12 terrasse systemd[1]: Started mpd.service - Music Player Daemon. May 30 15:39:12 terrasse sudo[31045]: pam_unix(sudo:session): session closed for user root May 30 15:39:12 terrasse sudo[31030]: pam_unix(sudo:session): session closed for user root May 30 15:39:12 terrasse systemd[1]: squeezelite.service: Deactivated successfully. May 30 15:39:12 terrasse systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. May 30 15:39:12 terrasse systemd[1]: squeezelite.service: Consumed 1.293s CPU time. May 30 15:39:12 terrasse sudo[31170]: pam_unix(sudo:session): session closed for user root May 30 15:39:12 terrasse volumio[30883]: error: MPD error: The expression evaluated to a falsy value: May 30 15:39:12 terrasse volumio[30883]: assert.ok(self.idling) May 30 15:39:12 terrasse volumio[30883]: error: The expression evaluated to a falsy value: May 30 15:39:12 terrasse volumio[30883]: assert.ok(self.idling) May 30 15:39:12 terrasse volumio[30883]: error: updateQueue error: null May 30 15:39:12 terrasse volumio[30883]: info: MPD running with PID31086 May 30 15:39:12 terrasse volumio[30883]: ,establishing connection May 30 15:39:12 terrasse volumio[30883]: error: updateQueue error: null May 30 15:39:13 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 30 15:39:13 terrasse sudo[31178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 30 15:39:13 terrasse sudo[31178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:13 terrasse sudo[31178]: pam_unix(sudo:session): session closed for user root May 30 15:39:13 terrasse volumio[30883]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log May 30 15:39:13 terrasse sudo[31182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log May 30 15:39:13 terrasse sudo[31182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:13 terrasse sudo[31182]: pam_unix(sudo:session): session closed for user root May 30 15:39:13 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true May 30 15:39:13 terrasse sudo[31185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite May 30 15:39:13 terrasse sudo[31185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:13 terrasse systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... May 30 15:39:13 terrasse systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. May 30 15:39:13 terrasse sudo[31185]: pam_unix(sudo:session): session closed for user root May 30 15:39:14 terrasse volumio[30883]: info: Initializing connection to go-librespot Websocket May 30 15:39:14 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 30 15:39:14 terrasse sudo[31192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 30 15:39:14 terrasse sudo[31192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:14 terrasse sudo[31192]: pam_unix(sudo:session): session closed for user root May 30 15:39:14 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 30 15:39:14 terrasse sudo[31195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 30 15:39:14 terrasse sudo[31195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:14 terrasse sudo[31195]: pam_unix(sudo:session): session closed for user root May 30 15:39:15 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 30 15:39:15 terrasse sudo[31212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 30 15:39:15 terrasse sudo[31212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:15 terrasse sudo[31212]: pam_unix(sudo:session): session closed for user root May 30 15:39:16 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 30 15:39:16 terrasse sudo[31215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 30 15:39:16 terrasse sudo[31215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:16 terrasse sudo[31215]: pam_unix(sudo:session): session closed for user root May 30 15:39:16 terrasse volumio[30883]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 30 15:39:16 terrasse sudo[31218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 30 15:39:16 terrasse sudo[31218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:16 terrasse volumio[30883]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io May 30 15:39:16 terrasse sudo[31218]: pam_unix(sudo:session): session closed for user root May 30 15:39:16 terrasse volumio[30883]: info: Completed starting Core Plugins May 30 15:39:16 terrasse volumio[30883]: info: ------------------------------------------- May 30 15:39:16 terrasse volumio[30883]: info: ----- MyVolumio plugins startup ---- May 30 15:39:16 terrasse volumio[30883]: info: ------------------------------------------- May 30 15:39:16 terrasse volumio[30883]: info: [MyVolumio PluginManager] Fetching plans data.... May 30 15:39:17 terrasse volumio[30883]: verbose: New Socket.io Connection to 192.168.0.182:3000 from 192.168.0.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 30 15:39:17 terrasse volumio[30883]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 30 15:39:19 terrasse go-librespot[31100]: time="2026-05-30T15:39:19+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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 30 15:39:19 terrasse systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 30 15:39:19 terrasse systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 30 15:39:20 terrasse systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1133. May 30 15:39:20 terrasse systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:39:20 terrasse systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:39:20 terrasse upmpdcli[31220]: Could not open config: /tmp/upmpdcli.conf May 30 15:39:20 terrasse systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 15:39:20 terrasse systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 15:39:21 terrasse volumio[30883]: verbose: New Socket.io Connection to 192.168.0.182:3000 from 192.168.0.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 30 15:39:23 terrasse systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 30 15:39:23 terrasse systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 30 15:39:23 terrasse systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 15:39:23 terrasse go-librespot[31221]: go-librespot daemon starting... May 30 15:39:23 terrasse volumio[30883]: info: Discovery: adding f3d1f87f-51d5-484b-934b-2515db04a35c May 30 15:39:23 terrasse volumio[30883]: info: Discovery: Found device Terrasse May 30 15:39:23 terrasse volumio[30883]: info: CoreCommandRouter::volumioGetState May 30 15:39:23 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:23 terrasse volumio[30883]: info: Discovery: adding c892e03f-0f2f-4b0d-8a5e-5abb4ac349c1 May 30 15:39:23 terrasse volumio[30883]: info: Discovery: Found device OpenLounge May 30 15:39:23 terrasse volumio[30883]: info: Discovery: Connecting to remote: 192.168.0.181 May 30 15:39:23 terrasse go-librespot[31222]: time="2026-05-30T15:39:23+02:00" level=info msg="running go-librespot 0.7.1" May 30 15:39:23 terrasse go-librespot[31222]: time="2026-05-30T15:39:23+02:00" level=debug msg="app state loaded" May 30 15:39:23 terrasse volumio[30883]: info: Discovery: this is already registered, f3d1f87f-51d5-484b-934b-2515db04a35c May 30 15:39:23 terrasse volumio[30883]: info: Discovery: Found device Terrasse May 30 15:39:23 terrasse volumio[30883]: info: CoreCommandRouter::volumioGetState May 30 15:39:23 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:23 terrasse volumio[30883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 30 15:39:23 terrasse go-librespot[31222]: time="2026-05-30T15:39:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 15:39:23 terrasse volumio[30883]: info: CoreCommandRouter::volumioGetState May 30 15:39:23 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:23 terrasse volumio[30883]: info: Discovery: Connected to remote: 192.168.0.181 May 30 15:39:26 terrasse volumio[30883]: info: Initializing connection to go-librespot Websocket May 30 15:39:26 terrasse go-librespot[31222]: time="2026-05-30T15:39:26+02:00" level=debug msg="new websocket client" May 30 15:39:26 terrasse volumio[30883]: info: Connection to go-librespot Websocket established May 30 15:39:26 terrasse volumio[30883]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=0c5105e38787e9becee8f277a1083452" http://updates.volumio.org/downloader-v1/track-device May 30 15:39:26 terrasse volumio[30883]: % Total % Received % Xferd Average Speed Time Time Time Current May 30 15:39:26 terrasse volumio[30883]: Dload Upload Total Spent Left Speed May 30 15:39:26 terrasse volumio[30883]: [922B blob data] May 30 15:39:26 terrasse volumio[30883]: retrying in 5 seconds, trial 0 May 30 15:39:26 terrasse volumio[30883]: info: Volumio Calling Home May 30 15:39:29 terrasse volumio[30883]: info: Getting Spotify volume May 30 15:39:29 terrasse volumio[30883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 30 15:39:29 terrasse volumio[30883]: info: CoreCommandRouter::volumioGetState May 30 15:39:29 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:35 terrasse systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1134. May 30 15:39:35 terrasse systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:39:35 terrasse systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:39:35 terrasse upmpdcli[31249]: Could not open config: /tmp/upmpdcli.conf May 30 15:39:35 terrasse systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 15:39:35 terrasse systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 15:39:36 terrasse volumio[30883]: error: MyVolumio Plugin failed to start in a timely fashion May 30 15:39:36 terrasse volumio[30883]: [Metrics] CommandRouter: 45s 673.80ms May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::volumiosetStartupVolume May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 15:39:36 terrasse volumio[30883]: info: VolumeController:: Setting startup Volume 40 May 30 15:39:36 terrasse volumio[30883]: info: VolumeController::SetAlsaVolume40 May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::Close All Modals sent May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::Close All Modals sent May 30 15:39:36 terrasse volumio[30883]: info: CoreStateMachine::pushState May 30 15:39:36 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::volumioPushState May 30 15:39:36 terrasse volumio[30883]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 30 15:39:36 terrasse volumio[30883]: SPOTIFY: SPOTIFY VOLUME undefined May 30 15:39:36 terrasse volumio[30883]: SPOTIFY: VOLUMIO VOLUME 40 May 30 15:39:36 terrasse volumio[30883]: info: Aligning Spotify Volume to Volumio Volume May 30 15:39:36 terrasse volumio[30883]: info: CoreCommandRouter::volumioGetState May 30 15:39:36 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:36 terrasse volumio[30883]: info: Setting Spotify Volume from Volumio: 40 May 30 15:39:37 terrasse volumio[30883]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.0.38","name":"Music Assistant","ver":"7.999.999","uuid":"slimproto","jsonPort":"9000","cliPort":"9090"} May 30 15:39:37 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 30 15:39:37 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 15:39:37 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 30 15:39:38 terrasse volumio[30883]: SPOTIFY: SETTING SPOTIFY VOLUME 40 May 30 15:39:38 terrasse volumio[30883]: info: Sending Spotify command with payload to local API: /player/volume May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 30 15:39:38 terrasse volumio[30883]: info: Adding plugin bluetooth to MyMusic Plugins May 30 15:39:38 terrasse volumio[30883]: info: Adding plugin multiroom to MyMusic Plugins May 30 15:39:38 terrasse volumio[30883]: info: Adding plugin metavolumio to MyMusic Plugins May 30 15:39:38 terrasse volumio[30883]: info: Adding plugin cd_controller to MyMusic Plugins May 30 15:39:38 terrasse volumio[30883]: info: Adding plugin qobuzconnect to MyMusic Plugins May 30 15:39:38 terrasse volumio[30883]: info: Adding plugin smart_inputs to MyMusic Plugins May 30 15:39:38 terrasse volumio[30883]: info: Adding plugin tidalconnect to MyMusic Plugins May 30 15:39:38 terrasse volumio[30883]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 30 15:39:39 terrasse go-librespot[31222]: time="2026-05-30T15:39:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" May 30 15:39:39 terrasse go-librespot[31222]: time="2026-05-30T15:39:39+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" May 30 15:39:39 terrasse go-librespot[31222]: time="2026-05-30T15:39:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" May 30 15:39:39 terrasse go-librespot[31222]: time="2026-05-30T15:39:39+02:00" level=info msg="zeroconf server listening on port 38617" May 30 15:39:39 terrasse go-librespot[31222]: time="2026-05-30T15:39:39+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" May 30 15:39:39 terrasse go-librespot[31222]: time="2026-05-30T15:39:39+02:00" level=debug msg="obtained new client token: AADc167+4YhTcdKXC5MbJKJIxAhibHRH+L18ZuUb/Jv8TslAPD4mSlJm5zm3B477KUxS5I7ekXXbTxIQe7SmjsmnLUoqsQneOc7sic+HvXIy0Nl7luL31UM9yj5Gx+pB9Ul6mT86MYyv3Wl1uhV/hbuzg5/Nr1yvxTNgsCGz/dHxbJmq20kaxvTeQIbolQjAGqT6+ovOeM78CfA6fV6akMRv9Hp9/ihXYeAM6O6Jl95mQ/ud6hsd2mI=" May 30 15:39:39 terrasse go-librespot[31222]: time="2026-05-30T15:39:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 30 15:39:39 terrasse go-librespot[31222]: time="2026-05-30T15:39:39+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.182:38384->34.158.1.133:4070: read: connection reset by peer" May 30 15:39:40 terrasse volumio[30883]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 30 15:39:40 terrasse volumio[30883]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 30 15:39:40 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 15:39:40 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 15:39:40 terrasse volumio[30883]: info: Starting MyVolumio Remote Streaming Endpoints May 30 15:39:40 terrasse volumio[30883]: info: MyVolumio login type: Token May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=debug msg="completed keyexchange" May 30 15:39:40 terrasse volumio[30883]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=debug msg="completed challenge" May 30 15:39:40 terrasse volumio[30883]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=info msg="authenticated AP" username="ra**z7" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=info msg="authenticated Login5" username="ra**z7" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=debug msg="initializing zeroconf session" username="ra**z7" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=debug msg="dealer connection opened" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=trace msg="starting accesspoint recv loop" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=trace msg="starting dealer recv loop" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=trace msg="received accesspoint ping" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 30 15:39:40 terrasse go-librespot[31222]: time="2026-05-30T15:39:40+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 30 15:39:41 terrasse go-librespot[31222]: time="2026-05-30T15:39:41+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 30 15:39:41 terrasse go-librespot[31222]: time="2026-05-30T15:39:41+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 30 15:39:41 terrasse go-librespot[31222]: time="2026-05-30T15:39:41+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 30 15:39:41 terrasse go-librespot[31222]: time="2026-05-30T15:39:41+02:00" level=debug msg="received connection id: NWRjYTQ4ZTktZWJm...RjdDRTVCRTM3Mw==" May 30 15:39:41 terrasse go-librespot[31222]: time="2026-05-30T15:39:41+02:00" level=trace msg="received accesspoint pong ack" May 30 15:39:42 terrasse volumio[30883]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 30 15:39:42 terrasse volumio[30883]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 30 15:39:42 terrasse volumio[30883]: info: Streaming services startup May 30 15:39:42 terrasse volumio[30883]: info: Starting Streaming Daemon May 30 15:39:42 terrasse sudo[31269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 30 15:39:42 terrasse volumio[30883]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 30 15:39:42 terrasse sudo[31269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 15:39:42 terrasse sudo[31269]: pam_unix(sudo:session): session closed for user root May 30 15:39:42 terrasse volumio[30883]: info: Spotify volume: 100 May 30 15:39:42 terrasse volumio[30883]: error: Cannot start Volumio Streaming Daemon May 30 15:39:42 terrasse volumio[30883]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 30 15:39:42 terrasse volumio[30883]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 30 15:39:42 terrasse volumio[30883]: info: Volumio called home May 30 15:39:42 terrasse volumio[30883]: verbose: New Socket.io Connection to 192.168.0.182:3000 from 192.168.0.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 30 15:39:43 terrasse volumio[30883]: info: [squeezelite_mc] Notification listener started May 30 15:39:43 terrasse volumio[30883]: info: [squeezelite_mc] Getting players connected to Music Assistant (192.168.0.38) May 30 15:39:43 terrasse volumio[30883]: info: BOOT COMPLETED May 30 15:39:44 terrasse volumio[30883]: info: [squeezelite_mc] Players connected to Music Assistant (192.168.0.38): [{"id":"b8:27:eb:93:ba:5a","uuid":null,"ip":"192.168.0.181","name":"openlounge","server":{"ip":"192.168.0.38","name":"Music Assistant","ver":"7.999.999","uuid":"slimproto","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:25:61:84","uuid":null,"ip":"192.168.0.182","name":"terrasse","server":{"ip":"192.168.0.38","name":"Music Assistant","ver":"7.999.999","uuid":"slimproto","jsonPort":"9000","cliPort":"9090"}}] May 30 15:39:44 terrasse volumio[30883]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:25:61:84","uuid":null,"ip":"192.168.0.182","name":"terrasse","server":{"ip":"192.168.0.38","name":"Music Assistant","ver":"7.999.999","uuid":"slimproto","jsonPort":"9000","cliPort":"9090"}} May 30 15:39:44 terrasse volumio[30883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 30 15:39:44 terrasse volumio[30883]: info: CoreCommandRouter::volumioGetState May 30 15:39:44 terrasse volumio[30883]: info: CorePlayQueue::getTrack 0 May 30 15:39:49 terrasse volumio[30883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 15:39:49 terrasse volumio[30883]: NotificationListenerError: Failed to send server command 'subscribe play,stop,pause,playlist,mixer,sync'. May 30 15:39:49 terrasse volumio[30883]: at CommandQueue._CommandQueue_shiftAndExec (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/utils/CommandQueue.js:91:26) { May 30 15:39:49 terrasse volumio[30883]: code: -20, May 30 15:39:49 terrasse volumio[30883]: cause: Error: response not received May 30 15:39:49 terrasse volumio[30883]: at Timeout._onTimeout (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:297:48) May 30 15:39:49 terrasse volumio[30883]: at listOnTimeout (node:internal/timers:573:17) May 30 15:39:49 terrasse volumio[30883]: at process.processTimers (node:internal/timers:514:7) May 30 15:39:49 terrasse volumio[30883]: } May 30 15:39:49 terrasse volumio[30883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 15:39:50 terrasse systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1135. May 30 15:39:50 terrasse systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:39:50 terrasse systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 15:39:50 terrasse upmpdcli[31302]: Could not open config: /tmp/upmpdcli.conf May 30 15:39:50 terrasse systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 15:39:50 terrasse systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 15:39:50 terrasse sudo[31305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 15:38' May 30 15:39:50 terrasse sudo[31305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"