Aug 27 10:37:00 volumiokueche kernel: usb 1-1-port2: over-current change #24193295 Aug 27 10:37:00 volumiokueche kernel: usb 1-1-port2: over-current change #24193296 Aug 27 10:37:00 volumiokueche kernel: usb 1-1-port2: over-current change #24193297 Aug 27 10:37:00 volumiokueche kernel: usb 1-1-port2: over-current change #24193298 Aug 27 10:37:01 volumiokueche kernel: usb 1-1-port2: over-current change #24193299 Aug 27 10:37:01 volumiokueche kernel: usb 1-1-port2: over-current change #24193300 Aug 27 10:37:01 volumiokueche kernel: usb 1-1-port2: over-current change #24193301 Aug 27 10:37:01 volumiokueche kernel: usb 1-1-port2: over-current change #24193302 Aug 27 10:37:02 volumiokueche kernel: usb 1-1-port2: over-current change #24193303 Aug 27 10:37:02 volumiokueche kernel: usb 1-1-port2: over-current change #24193304 Aug 27 10:37:02 volumiokueche kernel: usb 1-1-port2: over-current change #24193305 Aug 27 10:37:02 volumiokueche kernel: usb 1-1-port2: over-current change #24193306 Aug 27 10:37:03 volumiokueche kernel: usb 1-1-port2: over-current change #24193307 Aug 27 10:37:03 volumiokueche kernel: usb 1-1-port2: over-current change #24193308 Aug 27 10:37:03 volumiokueche kernel: usb 1-1-port2: over-current change #24193309 Aug 27 10:37:03 volumiokueche go-librespot[8697]: time="2026-08-27T10:37:03+02:00" level=error msg="did not receive last pong ack from accesspoint, 303s passed" Aug 27 10:37:03 volumiokueche go-librespot[8697]: panic: runtime error: invalid memory address or nil pointer dereference Aug 27 10:37:03 volumiokueche go-librespot[8697]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f7ef0] Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 2390183 gp=0x194c128 m=0 mp=0xffacd0 [running]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: panic({0x841d88, 0xfa0250}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x1457f20 sp=0x1457ecc pc=0xa92ec Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.panicmem(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/panic.go:262 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.sigpanic() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x1457f50 sp=0x1457f20 pc=0xabca4 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x14bd558) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:380 +0x284 fp=0x1457fe4 sp=0x1457f54 pc=0x4f7ef0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:277 +0x28 fp=0x1457fec sp=0x1457fe4 pc=0x4f73c4 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1457fec sp=0x1457fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 2390112 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:277 +0x15c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 1 gp=0x1402128 m=nil [select, 92908 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1458ce0 sp=0x1458ccc pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.selectgo(0x1458df0, 0x1605dbc, 0x0, 0x0, 0x2, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1458d90 sp=0x1458ce0 pc=0x85000 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x16a0960, 0x1458e90) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x1458e38 sp=0x1458d90 pc=0x62f43c Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*App).withAppPlayer(0x160c000, {0xa3da1c, 0x1005090}, 0x1411790) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x1458ed0 sp=0x1458e38 pc=0x6bcc60 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*App).withCredentials(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:209 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*App).SpotifyToken(0x160c000, {0xa3da1c, 0x1005090}, {0x15229c0, 0x19}, {0x1508580, 0x14b}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x1458f00 sp=0x1458ed0 pc=0x6bc02c Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.main() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:620 +0x660 fp=0x1458fa8 sp=0x1458f00 pc=0x6bf56c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.main() Aug 27 10:37:03 volumiokueche kernel: usb 1-1-port2: over-current change #24193310 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x1458fec sp=0x1458fa8 pc=0x6f390 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1458fec sp=0x1458fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 2 gp=0x14027e8 m=nil [force gc (idle), 2 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1448fd4 sp=0x1448fc0 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goparkunlock(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:466 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.forcegchelper() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x1448fec sp=0x1448fd4 pc=0x6f7f4 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1448fec sp=0x1448fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.init.6 in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:361 +0x1c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 3 gp=0x1402b48 m=nil [GC sweep wait]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x14497c4 sp=0x14497b0 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goparkunlock(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:466 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.bgsweep(0x142e050) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x14497e4 sp=0x14497c4 pc=0x5768c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcenable.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x14497ec sp=0x14497e4 pc=0x46c3c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x14497ec sp=0x14497ec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.gcenable in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 4 gp=0x1402c68 m=nil [GC scavenge wait]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97746c, 0xffa170, 0xd, 0xa, 0x2) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1449fb4 sp=0x1449fa0 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goparkunlock(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:466 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.(*scavengerState).park(0xffa170) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1449fc8 sp=0x1449fb4 pc=0x54a28 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.bgscavenge(0x142e050) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1449fe4 sp=0x1449fc8 pc=0x5516c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcenable.gowrap2() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x1449fec sp=0x1449fe4 pc=0x46be8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1449fec sp=0x1449fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.gcenable in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 5 gp=0x1402fc8 m=nil [GOMAXPROCS updater (idle), 92908 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x144a7a4 sp=0x144a790 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goparkunlock(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:466 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.updateMaxProcsGoroutine() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x144a7ec sp=0x144a7a4 pc=0x7f174 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x144a7ec sp=0x144a7ec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 6 gp=0x1403328 m=nil [finalizer wait, 43239 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x144af88 sp=0x144af74 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.runFinalizers() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x144afec sp=0x144af88 pc=0x45888 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x144afec sp=0x144afec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.createfing in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 7 gp=0x15de908 m=nil [cleanup wait, 274 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x14487a4 sp=0x1448790 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goparkunlock(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:466 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.(*cleanupQueue).dequeue(0xffa2e8) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x14487c4 sp=0x14487a4 pc=0x41cb0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.runCleanups() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x14487ec sp=0x14487c4 pc=0x4284c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x14487ec sp=0x14487ec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 80 gp=0x162e128 m=nil [chan receive, 43218 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x9772f4, 0x160a038, 0xe, 0x7, 0x2) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19bff40 sp=0x19bff2c pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.chanrecv(0x160a000, 0x1607fe0, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x19bff88 sp=0x19bff40 pc=0x3414c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.chanrecv2(0x160a000, 0x1607fe0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x19bff9c sp=0x19bff88 pc=0x33bf0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*App).withAppPlayer.func2() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:300 +0x6c fp=0x19bffec sp=0x19bff9c pc=0x6bd47c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19bffec sp=0x19bffec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by main.(*App).withAppPlayer in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:297 +0x598 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 8 gp=0x15dfb08 m=nil [IO wait, 43247 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97745c, 0x75871e10, 0x2, 0x2, 0x5) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x14a2cf0 sp=0x14a2cdc pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.netpollblock(0x75871e00, 0x72, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x14a2d08 sp=0x14a2cf0 pc=0x675a0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.runtime_pollWait(0x75871e00, 0x72) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x14a2d1c sp=0x14a2d08 pc=0xa8864 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*pollDesc).wait(0x150b288, 0x72, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x14a2d30 sp=0x14a2d1c pc=0x136044 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*pollDesc).waitRead(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*FD).Accept(0x150b270) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x14a2d78 sp=0x14a2d30 pc=0x13a800 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*netFD).accept(0x150b270) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x14a2de0 sp=0x14a2d78 pc=0x1abc28 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*TCPListener).accept(0x14004b0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x14a2e34 sp=0x14a2de0 pc=0x1c3554 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*TCPListener).Accept(0x14004b0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x14a2e50 sp=0x14a2e34 pc=0x1c259c Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.(*onceCloseListener).Accept(0x14ac080) Aug 27 10:37:03 volumiokueche go-librespot[8697]: :1 +0x34 fp=0x14a2e68 sp=0x14a2e50 pc=0x367f9c Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.(*Server).Serve(0x14bc008, {0xa3d218, 0x14004b0}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x14a2f00 sp=0x14a2e68 pc=0x341154 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.Serve(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:2971 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*ConcreteApiServer).serve(0x161c0c0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x14a2fe4 sp=0x14a2f00 pc=0x6b17bc Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.NewApiServer.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x14a2fec sp=0x14a2fe4 pc=0x6b04f4 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x14a2fec sp=0x14a2fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by main.NewApiServer in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/api_server.go:332 +0x310 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 24 gp=0x15dfd48 m=nil [chan receive, 92908 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x9772f4, 0x160af38, 0xe, 0x7, 0x2) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1792778 sp=0x1792764 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.chanrecv(0x160af00, 0x0, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x17927c0 sp=0x1792778 pc=0x3414c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.chanrecv1(0x160af00, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x17927d4 sp=0x17927c0 pc=0x33bcc Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/godbus/dbus/v5.newConn.func1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x17927ec sp=0x17927d4 pc=0x4aa8ec Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x17927ec sp=0x17927ec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 25 gp=0x15dfe68 m=nil [IO wait, 92907 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97745c, 0x75871610, 0x2, 0x2, 0x5) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x149ecbc sp=0x149eca8 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.netpollblock(0x75871600, 0x72, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x149ecd4 sp=0x149ecbc pc=0x675a0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.runtime_pollWait(0x75871600, 0x72) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x149ece8 sp=0x149ecd4 pc=0xa8864 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*pollDesc).wait(0x168fe18, 0x72, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x149ecfc sp=0x149ece8 pc=0x136044 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*pollDesc).waitRead(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*FD).ReadMsg(0x168fe00, {0x1413cd0, 0x10, 0x10}, {0x14f6018, 0x1000, 0x1000}, 0x40000000) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x149ed6c sp=0x149ecfc pc=0x1381bc Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*netFD).readMsg(0x168fe00, {0x1413cd0, 0x10, 0x10}, {0x14f6018, 0x1000, 0x1000}, 0x40000000) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x149edc8 sp=0x149ed6c pc=0x1aa2c8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*UnixConn).readMsg(0x16bb618, {0x1413cd0, 0x10, 0x10}, {0x14f6018, 0x1000, 0x1000}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x149ee28 sp=0x149edc8 pc=0x1c9d80 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*UnixConn).ReadMsgUnix(0x16bb618, {0x1413cd0, 0x10, 0x10}, {0x14f6018, 0x1000, 0x1000}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x149ee78 sp=0x149ee28 pc=0x1c820c Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/godbus/dbus/v5.(*oobReader).Read(0x14f6008, {0x1413cd0, 0x10, 0x10}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x149eec8 sp=0x149ee78 pc=0x4c2af0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: io.ReadAtLeast({0xa39d58, 0x14f6008}, {0x1413cd0, 0x10, 0x10}, 0x10) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x149eef4 sp=0x149eec8 pc=0xee954 Aug 27 10:37:03 volumiokueche go-librespot[8697]: io.ReadFull(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/io/io.go:354 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x1623b70) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x149efa0 sp=0x149eef4 pc=0x4c32c4 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x1526680) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x149efe4 sp=0x149efa0 pc=0x4aaef4 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x149efec sp=0x149efe4 pc=0x4a8318 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x149efec sp=0x149efec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 15 gp=0x15fe368 m=nil [GC worker (idle), 23464 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x977320, 0x15f4800, 0x1c, 0xa, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x193f788 sp=0x193f774 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcBgMarkWorker(0x17ea880) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x193f7e4 sp=0x193f788 pc=0x49f34 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcBgMarkStartWorkers.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x193f7ec sp=0x193f7e4 pc=0x49e14 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x193f7ec sp=0x193f7ec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.gcBgMarkStartWorkers in goroutine 13 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 16 gp=0x15fe488 m=nil [GC worker (idle), 43242 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x977320, 0x15f4a00, 0x1c, 0xa, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1700788 sp=0x1700774 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcBgMarkWorker(0x17ea880) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x17007e4 sp=0x1700788 pc=0x49f34 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcBgMarkStartWorkers.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x17007ec sp=0x17007e4 pc=0x49e14 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x17007ec sp=0x17007ec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.gcBgMarkStartWorkers in goroutine 13 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 34 gp=0x15fe5a8 m=nil [GC worker (idle), 314 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x977320, 0x15f4c00, 0x1c, 0xa, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1445f88 sp=0x1445f74 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcBgMarkWorker(0x17ea880) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1445fe4 sp=0x1445f88 pc=0x49f34 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcBgMarkStartWorkers.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1445fec sp=0x1445fe4 pc=0x49e14 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1445fec sp=0x1445fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.gcBgMarkStartWorkers in goroutine 13 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 35 gp=0x15fe6c8 m=nil [GC worker (idle)]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x977320, 0x15f4e00, 0x1c, 0xa, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1459f88 sp=0x1459f74 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcBgMarkWorker(0x17ea880) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1459fe4 sp=0x1459f88 pc=0x49f34 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gcBgMarkStartWorkers.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1459fec sp=0x1459fe4 pc=0x49e14 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1459fec sp=0x1459fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by runtime.gcBgMarkStartWorkers in goroutine 13 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 36 gp=0x15fe248 m=nil [IO wait, 92908 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97745c, 0x75871a10, 0x2, 0x2, 0x5) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1603908 sp=0x16038f4 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.netpollblock(0x75871a00, 0x72, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1603920 sp=0x1603908 pc=0x675a0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.runtime_pollWait(0x75871a00, 0x72) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1603934 sp=0x1603920 pc=0xa8864 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*pollDesc).wait(0x150a158, 0x72, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1603948 sp=0x1603934 pc=0x136044 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*pollDesc).waitRead(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*FD).Read(0x150a140, {0x1565000, 0x1000, 0x1000}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1603990 sp=0x1603948 pc=0x1371c4 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*netFD).Read(0x150a140, {0x1565000, 0x1000, 0x1000}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x16039bc sp=0x1603990 pc=0x1a9e78 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*conn).Read(0x1610008, {0x1565000, 0x1000, 0x1000}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x16039e8 sp=0x16039bc pc=0x1b967c Aug 27 10:37:03 volumiokueche go-librespot[8697]: io.(*multiReader).Read(0x14103a0, {0x1565000, 0x1000, 0x1000}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1603a14 sp=0x16039e8 pc=0xefb88 Aug 27 10:37:03 volumiokueche go-librespot[8697]: bufio.(*Reader).fill(0x1400270) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x1603a38 sp=0x1603a14 pc=0x2b6c1c Aug 27 10:37:03 volumiokueche go-librespot[8697]: bufio.(*Reader).ReadByte(0x1400270) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x1603a44 sp=0x1603a38 pc=0x2b7498 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/coder/websocket.readFrameHeader(0x1400270, {0x155cc38, 0x8, 0x8}) Aug 27 10:37:03 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Aug 27 10:37:03 volumiokueche volumio[8510]: info: Connection to go-librespot Websocket closed Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x1603a84 sp=0x1603a44 pc=0x372a08 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/coder/websocket.(*Conn).readFrameHeader(0x155cc08, {0xa3da00, 0x1005090}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x1603b08 sp=0x1603a84 pc=0x375bd4 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/coder/websocket.(*Conn).readLoop(0x155cc08, {0xa3da00, 0x1005090}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x1603bcc sp=0x1603b08 pc=0x375390 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/coder/websocket.(*Conn).reader(0x155cc08, {0xa3da00, 0x1005090}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x1603c50 sp=0x1603bcc pc=0x376798 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/coder/websocket.(*Conn).Reader(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/coder/websocket.(*Conn).Read(0x155cc08, {0xa3da00, 0x1005090}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x1603c78 sp=0x1603c50 pc=0x374944 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x14bc168}, 0x1772378) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x1603cfc sp=0x1603c78 pc=0x6b2040 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.HandlerFunc.ServeHTTP(0x1498188, {0xa3d2d8, 0x14bc168}, 0x1772378) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1603d0c sp=0x1603cfc pc=0x33d7d8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.(*ServeMux).ServeHTTP(0x1490000, {0xa3d2d8, 0x14bc168}, 0x1772378) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x1603d4c sp=0x1603d0c pc=0x33f6c8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x14bc168}, 0x1772378) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x1603d74 sp=0x1603d4c pc=0x6b19b8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.HandlerFunc.ServeHTTP(0x1494140, {0xa3d2d8, 0x14bc168}, 0x1772378) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1603d84 sp=0x1603d74 pc=0x33d7d8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.serverHandler.ServeHTTP({0x14bc008}, {0xa3d2d8, 0x14bc168}, 0x1772378) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x1603da0 sp=0x1603d84 pc=0x35cb44 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.(*conn).serve(0x14b0000, {0xa3da38, 0x14aa1e0}) Aug 27 10:37:03 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x1603fdc sp=0x1603da0 pc=0x33b894 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.(*Server).Serve.gowrap3() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x1603fec sp=0x1603fdc pc=0x3415cc Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1603fec sp=0x1603fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by net/http.(*Server).Serve in goroutine 8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:3493 +0x42c Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 79 gp=0x1902488 m=nil [chan receive, 43247 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x9772f4, 0x160a078, 0xe, 0x7, 0x2) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1692f3c sp=0x1692f28 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.chanrecv(0x160a040, 0x1692fd0, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x1692f84 sp=0x1692f3c pc=0x3414c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.chanrecv2(0x160a040, 0x1692fd0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x1692f98 sp=0x1692f84 pc=0x33bf0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*App).withAppPlayer.func1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:284 +0x68 fp=0x1692fec sp=0x1692f98 pc=0x6bd918 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1692fec sp=0x1692fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by main.(*App).withAppPlayer in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:281 +0x4f8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 81 gp=0x1482a28 m=nil [IO wait, 150 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x97745c, 0x75871810, 0x2, 0x2, 0x5) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x14a0dac sp=0x14a0d98 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.netpollblock(0x75871800, 0x72, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x14a0dc4 sp=0x14a0dac pc=0x675a0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.runtime_pollWait(0x75871800, 0x72) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x14a0dd8 sp=0x14a0dc4 pc=0xa8864 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*pollDesc).wait(0x168fdc8, 0x72, 0x0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x14a0dec sp=0x14a0dd8 pc=0x136044 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*pollDesc).waitRead(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/poll.(*FD).Accept(0x168fdb0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x14a0e34 sp=0x14a0dec pc=0x13a800 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*netFD).accept(0x168fdb0) Aug 27 10:37:03 volumiokueche systemd[1]: go-librespot-daemon.service: Consumed 2w 2d 4min 7.436s CPU time. Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x14a0e9c sp=0x14a0e34 pc=0x1abc28 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*TCPListener).accept(0x176a2a0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x14a0ef0 sp=0x14a0e9c pc=0x1c3554 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*TCPListener).Accept(0x176a2a0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x14a0f0c sp=0x14a0ef0 pc=0x1c259c Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.(*onceCloseListener).Accept(0x17d58a0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: :1 +0x34 fp=0x14a0f24 sp=0x14a0f0c pc=0x367f9c Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.(*Server).Serve(0x17fc6e8, {0xa3d218, 0x176a2a0}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x14a0fbc sp=0x14a0f24 pc=0x341154 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net/http.Serve(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/http/server.go:2971 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x14a0fec sp=0x14a0fbc pc=0x62f538 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x14a0fec sp=0x14a0fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/zeroconf/zeroconf.go:297 +0x160 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 2393456 gp=0x15fed88 m=3 mp=0x144d008 [syscall]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.cgocall(0x75ea90, 0x1691efc) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/cgocall.go:167 +0x50 fp=0x1691ee4 sp=0x1691ecc pc=0xa628c Aug 27 10:37:03 volumiokueche go-librespot[8697]: net._C2func_getaddrinfo(0x162a198, 0x0, 0x197e080, 0x1432548) Aug 27 10:37:03 volumiokueche go-librespot[8697]: _cgo_gotypes.go:105 +0x44 fp=0x1691ef8 sp=0x1691ee4 pc=0x1caa18 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net._C_getaddrinfo.func1(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/cgo_unix_cgo.go:84 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net._C_getaddrinfo(0x162a198, 0x0, 0x197e080, 0x1432548) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/cgo_unix_cgo.go:84 +0x80 fp=0x1691f18 sp=0x1691ef8 pc=0x1cacdc Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.cgoLookupHostIP({0x919486, 0x3}, {0x162a210, 0x13}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/cgo_unix.go:174 +0x1f4 fp=0x1691f8c sp=0x1691f18 pc=0x19cfe0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.cgoLookupIP.func1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/cgo_unix.go:229 +0x34 fp=0x1691fb4 sp=0x1691f8c pc=0x19d604 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.doBlockingWithCtx[...].func1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/cgo_unix.go:67 +0x54 fp=0x1691fec sp=0x1691fb4 pc=0x1cdac8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1691fec sp=0x1691fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by net.doBlockingWithCtx[...] in goroutine 2393455 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/cgo_unix.go:64 +0x204 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 2393466 gp=0x162fc28 m=nil [select]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x149f868 sp=0x149f854 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.selectgo(0x149f9d8, 0x149f944, 0x0, 0x0, 0x2, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x149f918 sp=0x149f868 pc=0x85000 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*Resolver).lookupIPAddr(0xff9700, {0xa3da8c, 0x2d48000}, {0x919486, 0x3}, {0x162a210, 0x13}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/lookup.go:343 +0x394 fp=0x149fa20 sp=0x149f918 pc=0x1b7b00 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*Resolver).internetAddrList(0xff9700, {0xa3da8c, 0x2d48000}, {0x919486, 0x3}, {0x162a210, 0x18}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/ipsock.go:289 +0x6b0 fp=0x149fab4 sp=0x149fa20 pc=0x1b5abc Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*Resolver).resolveAddrList(0xff9700, {0xa3da8c, 0x2d48000}, {0x9198a7, 0x4}, {0x919486, 0x3}, {0x162a210, 0x18}, {0x0, ...}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/dial.go:353 +0x510 fp=0x149fb3c sp=0x149fab4 pc=0x1a03f8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*Dialer).DialContext(0x149fc68, {0xa3da8c, 0x2d48000}, {0x919486, 0x3}, {0x162a210, 0x18}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/dial.go:560 +0x43c fp=0x149fc38 sp=0x149fb3c pc=0x1a1334 Aug 27 10:37:03 volumiokueche go-librespot[8697]: golang.org/x/net/proxy.direct.DialContext(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 Aug 27 10:37:03 volumiokueche go-librespot[8697]: golang.org/x/net/proxy.(*direct).DialContext(0x1005090, {0xa3da8c, 0x2d48000}, {0x919486, 0x3}, {0x162a210, 0x18}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: :1 +0x74 fp=0x149fccc sp=0x149fc38 pc=0x4f2fcc Aug 27 10:37:03 volumiokueche go-librespot[8697]: golang.org/x/net/proxy.Dial({0xa3da8c, 0x2d48000}, {0x919486, 0x3}, {0x162a210, 0x18}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0xac fp=0x149fd08 sp=0x149fccc pc=0x4f0e90 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0x14bd558, {0xa3da1c, 0x1005090}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:95 +0x2a8 fp=0x149fd80 sp=0x149fd08 pc=0x4f5950 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0x14bd558, {0xa3da1c, 0x1005090}, 0x176a090) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:205 +0xd4 fp=0x149fde4 sp=0x149fd80 pc=0x4f6a58 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0x14bd558) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:394 +0xcc fp=0x149fe0c sp=0x149fde4 pc=0x4f8018 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Aug 27 10:37:03 volumiokueche go-librespot[8697]: :1 +0x28 fp=0x149fe1c sp=0x149fe0c pc=0x4fc270 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x149fe28 sp=0x149fe1c pc=0x4eb1c8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x149fecc, {0xa3c8c0, 0x16a06c0}, 0x0, {0x0, 0x0}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x149fea4 sp=0x149fe28 pc=0x4eb644 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x149ff5c, {0xa3c8c0, 0x16a06c0}, 0x0, {0x0, 0x0}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x149fed4 sp=0x149fea4 pc=0x4eb180 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/cenkalti/backoff/v4.RetryNotify(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/cenkalti/backoff/v4.Retry(...) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x14bd558) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:335 +0x434 fp=0x149ffe4 sp=0x149fed4 pc=0x4f7878 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:403 +0x28 fp=0x149ffec sp=0x149ffe4 pc=0x4f81cc Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x149ffec sp=0x149ffec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 2393485 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/ap/ap.go:403 +0x120 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 2390112 gp=0x1716c68 m=nil [runnable]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.sellock({0x177bf70, 0xa, 0xa}, {0x177bb34, 0xa, 0xa}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/select.go:34 +0xa4 fp=0x177b930 sp=0x177b930 pc=0x8422c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.selectgo(0x177bf70, 0x177bb20, 0x0, 0x0, 0xa, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/select.go:252 +0x380 fp=0x177b9e0 sp=0x177b930 pc=0x84734 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*AppPlayer).Run(0x17aa140, {0xa3da1c, 0x1005090}, 0x1a2d180, 0x1a2d1c0) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/player.go:661 +0x368 fp=0x177bfd4 sp=0x177b9e0 pc=0x6c5950 Aug 27 10:37:03 volumiokueche go-librespot[8697]: main.(*App).withAppPlayer.func2.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:328 +0x48 fp=0x177bfec sp=0x177bfd4 pc=0x6bd884 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x177bfec sp=0x177bfec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by main.(*App).withAppPlayer.func2 in goroutine 80 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/cmd/daemon/main.go:328 +0x2f0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 2393455 gp=0x1b54368 m=nil [select]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1456b60 sp=0x1456b4c pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.selectgo(0x1456c68, 0x1456c38, 0x0, 0x0, 0x2, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1456c10 sp=0x1456b60 pc=0x85000 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.doBlockingWithCtx[...]({0xa3da54, 0x18902d0}, {0x162a210, 0x13}, 0x14aa258) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/cgo_unix.go:71 +0x280 fp=0x1456c90 sp=0x1456c10 pc=0x1cd880 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.cgoLookupIP({0xa3da54, 0x18902d0}, {0x919486, 0x3}, {0x162a210, 0x13}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/cgo_unix.go:228 +0x98 fp=0x1456cc0 sp=0x1456c90 pc=0x19d584 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*Resolver).lookupIP(0xff9700, {0xa3da54, 0x18902d0}, {0x919486, 0x3}, {0x162a210, 0x13}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/lookup_unix.go:64 +0x90 fp=0x1456efc sp=0x1456cc0 pc=0x1b8c5c Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*Resolver).lookupIP-fm({0xa3da54, 0x18902d0}, {0x919486, 0x3}, {0x162a210, 0x13}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: :1 +0x58 fp=0x1456f30 sp=0x1456efc pc=0x1d3574 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.init.func1({0xa3da54, 0x18902d0}, 0x14323b8, {0x919486, 0x3}, {0x162a210, 0x13}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/hook.go:21 +0x4c fp=0x1456f60 sp=0x1456f30 pc=0x19abf8 Aug 27 10:37:03 volumiokueche go-librespot[8697]: net.(*Resolver).lookupIPAddr.func1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/net/lookup.go:335 +0x58 fp=0x1456f9c sp=0x1456f60 pc=0x1b849c Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/singleflight.(*Group).doCall(0xff9708, 0x1890360, {0x162a048, 0x17}, 0x197e060) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x1456fd4 sp=0x1456f9c pc=0x198e98 Aug 27 10:37:03 volumiokueche go-librespot[8697]: internal/singleflight.(*Group).DoChan.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x1456fec sp=0x1456fd4 pc=0x198e50 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1456fec sp=0x1456fec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by internal/singleflight.(*Group).DoChan in goroutine 2393466 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x304 Aug 27 10:37:03 volumiokueche go-librespot[8697]: goroutine 2390111 gp=0x189e7e8 m=nil [select, 43218 minutes]: Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1446674 sp=0x1446660 pc=0xa97c0 Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.selectgo(0x1446780, 0x144674c, 0x0, 0x0, 0x3, 0x1) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1446724 sp=0x1446674 pc=0x85000 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x25d6c30) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/player/player.go:215 +0x1f4 fp=0x14467e4 sp=0x1446724 pc=0x582954 Aug 27 10:37:03 volumiokueche go-librespot[8697]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/player/player.go:197 +0x28 fp=0x14467ec sp=0x14467e4 pc=0x58253c Aug 27 10:37:03 volumiokueche go-librespot[8697]: runtime.goexit({}) Aug 27 10:37:03 volumiokueche go-librespot[8697]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x14467ec sp=0x14467ec pc=0xb0874 Aug 27 10:37:03 volumiokueche go-librespot[8697]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 80 Aug 27 10:37:03 volumiokueche go-librespot[8697]: /src/player/player.go:197 +0x220 Aug 27 10:37:03 volumiokueche go-librespot[8691]: Aborted Aug 27 10:37:04 volumiokueche kernel: usb 1-1-port2: over-current change #24193311 Aug 27 10:37:04 volumiokueche kernel: usb 1-1-port2: over-current change #24193312 Aug 27 10:37:04 volumiokueche kernel: usb 1-1-port2: over-current change #24193313 Aug 27 10:37:04 volumiokueche kernel: usb 1-1-port2: over-current change #24193314 Aug 27 10:37:05 volumiokueche kernel: usb 1-1-port2: over-current change #24193315 Aug 27 10:37:05 volumiokueche kernel: usb 1-1-port2: over-current change #24193316 Aug 27 10:37:05 volumiokueche kernel: usb 1-1-port2: over-current change #24193317 Aug 27 10:37:05 volumiokueche kernel: usb 1-1-port2: over-current change #24193318 Aug 27 10:37:06 volumiokueche kernel: usb 1-1-port2: over-current change #24193319 Aug 27 10:37:06 volumiokueche kernel: usb 1-1-port2: over-current change #24193320 Aug 27 10:37:06 volumiokueche kernel: usb 1-1-port2: over-current change #24193321 Aug 27 10:37:06 volumiokueche volumio[8510]: info: Initializing connection to go-librespot Websocket Aug 27 10:37:06 volumiokueche volumio[8510]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 10:37:06 volumiokueche kernel: usb 1-1-port2: over-current change #24193322 Aug 27 10:37:07 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 27 10:37:07 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:07 volumiokueche systemd[1]: go-librespot-daemon.service: Consumed 2w 2d 4min 7.436s CPU time. Aug 27 10:37:07 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:07 volumiokueche go-librespot[29960]: go-librespot daemon starting... Aug 27 10:37:07 volumiokueche go-librespot[29961]: time="2026-08-27T10:37:07+02:00" level=info msg="running go-librespot 0.7.1" Aug 27 10:37:07 volumiokueche go-librespot[29961]: time="2026-08-27T10:37:07+02:00" level=debug msg="app state loaded" Aug 27 10:37:07 volumiokueche go-librespot[29961]: time="2026-08-27T10:37:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 10:37:07 volumiokueche kernel: usb 1-1-port2: over-current change #24193323 Aug 27 10:37:07 volumiokueche kernel: usb 1-1-port2: over-current change #24193324 Aug 27 10:37:07 volumiokueche kernel: usb 1-1-port2: over-current change #24193325 Aug 27 10:37:08 volumiokueche kernel: usb 1-1-port2: over-current change #24193326 Aug 27 10:37:08 volumiokueche kernel: usb 1-1-port2: over-current change #24193327 Aug 27 10:37:08 volumiokueche kernel: usb 1-1-port2: over-current change #24193328 Aug 27 10:37:08 volumiokueche kernel: usb 1-1-port2: over-current change #24193329 Aug 27 10:37:09 volumiokueche kernel: usb 1-1-port2: over-current change #24193330 Aug 27 10:37:09 volumiokueche kernel: usb 1-1-port2: over-current change #24193331 Aug 27 10:37:09 volumiokueche kernel: usb 1-1-port2: over-current change #24193332 Aug 27 10:37:09 volumiokueche kernel: usb 1-1-port2: over-current change #24193333 Aug 27 10:37:09 volumiokueche volumio[8510]: info: Initializing connection to go-librespot Websocket Aug 27 10:37:09 volumiokueche go-librespot[29961]: time="2026-08-27T10:37:09+02:00" level=debug msg="new websocket client" Aug 27 10:37:09 volumiokueche volumio[8510]: info: Connection to go-librespot Websocket established Aug 27 10:37:10 volumiokueche kernel: usb 1-1-port2: over-current change #24193334 Aug 27 10:37:10 volumiokueche kernel: usb 1-1-port2: over-current change #24193335 Aug 27 10:37:10 volumiokueche kernel: usb 1-1-port2: over-current change #24193336 Aug 27 10:37:10 volumiokueche kernel: usb 1-1-port2: over-current change #24193337 Aug 27 10:37:11 volumiokueche kernel: usb 1-1-port2: over-current change #24193338 Aug 27 10:37:11 volumiokueche kernel: usb 1-1-port2: over-current change #24193339 Aug 27 10:37:11 volumiokueche kernel: usb 1-1-port2: over-current change #24193340 Aug 27 10:37:11 volumiokueche kernel: usb 1-1-port2: over-current change #24193341 Aug 27 10:37:12 volumiokueche kernel: usb 1-1-port2: over-current change #24193342 Aug 27 10:37:12 volumiokueche kernel: usb 1-1-port2: over-current change #24193343 Aug 27 10:37:12 volumiokueche kernel: usb 1-1-port2: over-current change #24193344 Aug 27 10:37:12 volumiokueche kernel: usb 1-1-port2: over-current change #24193345 Aug 27 10:37:12 volumiokueche volumio[8510]: info: Getting Spotify volume Aug 27 10:37:13 volumiokueche volumio[8510]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 27 10:37:13 volumiokueche volumio[8510]: info: CoreCommandRouter::volumioGetState Aug 27 10:37:13 volumiokueche volumio[8510]: SPOTIFY: RECEIVED VOLUMIO VOLUME 84 Aug 27 10:37:13 volumiokueche kernel: usb 1-1-port2: over-current change #24193346 Aug 27 10:37:13 volumiokueche kernel: usb 1-1-port2: over-current change #24193347 Aug 27 10:37:13 volumiokueche kernel: usb 1-1-port2: over-current change #24193348 Aug 27 10:37:13 volumiokueche kernel: usb 1-1-port2: over-current change #24193349 Aug 27 10:37:14 volumiokueche kernel: usb 1-1-port2: over-current change #24193350 Aug 27 10:37:14 volumiokueche kernel: usb 1-1-port2: over-current change #24193351 Aug 27 10:37:14 volumiokueche kernel: usb 1-1-port2: over-current change #24193352 Aug 27 10:37:14 volumiokueche kernel: usb 1-1-port2: over-current change #24193353 Aug 27 10:37:15 volumiokueche kernel: usb 1-1-port2: over-current change #24193354 Aug 27 10:37:15 volumiokueche kernel: usb 1-1-port2: over-current change #24193355 Aug 27 10:37:15 volumiokueche kernel: usb 1-1-port2: over-current change #24193356 Aug 27 10:37:15 volumiokueche kernel: usb 1-1-port2: over-current change #24193357 Aug 27 10:37:16 volumiokueche kernel: usb 1-1-port2: over-current change #24193358 Aug 27 10:37:16 volumiokueche kernel: usb 1-1-port2: over-current change #24193359 Aug 27 10:37:16 volumiokueche kernel: usb 1-1-port2: over-current change #24193360 Aug 27 10:37:17 volumiokueche kernel: usb 1-1-port2: over-current change #24193361 Aug 27 10:37:17 volumiokueche kernel: usb 1-1-port2: over-current change #24193362 Aug 27 10:37:17 volumiokueche go-librespot[29961]: time="2026-08-27T10:37:17+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" Aug 27 10:37:17 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 10:37:17 volumiokueche volumio[8510]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 10:37:17 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 10:37:17 volumiokueche volumio[8510]: Error: socket hang up Aug 27 10:37:17 volumiokueche volumio[8510]: at connResetException (node:internal/errors:720:14) Aug 27 10:37:17 volumiokueche volumio[8510]: at Socket.socketOnEnd (node:_http_client:519:23) Aug 27 10:37:17 volumiokueche volumio[8510]: at Socket.emit (node:events:526:35) Aug 27 10:37:17 volumiokueche volumio[8510]: at endReadableNT (node:internal/streams/readable:1376:12) Aug 27 10:37:17 volumiokueche volumio[8510]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Aug 27 10:37:17 volumiokueche volumio[8510]: code: 'ECONNRESET', Aug 27 10:37:17 volumiokueche volumio[8510]: response: undefined Aug 27 10:37:17 volumiokueche volumio[8510]: } Aug 27 10:37:17 volumiokueche volumio[8510]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 10:37:17 volumiokueche kernel: usb 1-1-port2: over-current change #24193363 Aug 27 10:37:17 volumiokueche kernel: usb 1-1-port2: over-current change #24193364 Aug 27 10:37:18 volumiokueche kernel: usb 1-1-port2: over-current change #24193365 Aug 27 10:37:18 volumiokueche sudo[29999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 10:36' Aug 27 10:37:18 volumiokueche sudo[29999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:18 volumiokueche sudo[29999]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:18 volumiokueche kernel: usb 1-1-port2: over-current change #24193366 Aug 27 10:37:18 volumiokueche kernel: usb 1-1-port2: over-current change #24193367 Aug 27 10:37:18 volumiokueche kernel: usb 1-1-port2: over-current change #24193368 Aug 27 10:37:19 volumiokueche kernel: usb 1-1-port2: over-current change #24193369 Aug 27 10:37:19 volumiokueche kernel: usb 1-1-port2: over-current change #24193370 Aug 27 10:37:19 volumiokueche kernel: usb 1-1-port2: over-current change #24193371 Aug 27 10:37:19 volumiokueche kernel: usb 1-1-port2: over-current change #24193372 Aug 27 10:37:20 volumiokueche kernel: usb 1-1-port2: over-current change #24193373 Aug 27 10:37:20 volumiokueche kernel: usb 1-1-port2: over-current change #24193374 Aug 27 10:37:20 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 27 10:37:20 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:20 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:20 volumiokueche go-librespot[30009]: go-librespot daemon starting... Aug 27 10:37:20 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:20+02:00" level=info msg="running go-librespot 0.7.1" Aug 27 10:37:20 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:20+02:00" level=debug msg="app state loaded" Aug 27 10:37:20 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 10:37:20 volumiokueche kernel: usb 1-1-port2: over-current change #24193375 Aug 27 10:37:20 volumiokueche kernel: usb 1-1-port2: over-current change #24193376 Aug 27 10:37:21 volumiokueche kernel: usb 1-1-port2: over-current change #24193377 Aug 27 10:37:21 volumiokueche kernel: usb 1-1-port2: over-current change #24193378 Aug 27 10:37:21 volumiokueche kernel: usb 1-1-port2: over-current change #24193379 Aug 27 10:37:21 volumiokueche kernel: usb 1-1-port2: over-current change #24193380 Aug 27 10:37:22 volumiokueche kernel: usb 1-1-port2: over-current change #24193381 Aug 27 10:37:22 volumiokueche kernel: usb 1-1-port2: over-current change #24193382 Aug 27 10:37:22 volumiokueche kernel: usb 1-1-port2: over-current change #24193383 Aug 27 10:37:22 volumiokueche kernel: usb 1-1-port2: over-current change #24193384 Aug 27 10:37:23 volumiokueche kernel: usb 1-1-port2: over-current change #24193385 Aug 27 10:37:23 volumiokueche kernel: usb 1-1-port2: over-current change #24193386 Aug 27 10:37:23 volumiokueche kernel: usb 1-1-port2: over-current change #24193387 Aug 27 10:37:23 volumiokueche kernel: usb 1-1-port2: over-current change #24193388 Aug 27 10:37:24 volumiokueche kernel: usb 1-1-port2: over-current change #24193389 Aug 27 10:37:24 volumiokueche kernel: usb 1-1-port2: over-current change #24193390 Aug 27 10:37:24 volumiokueche kernel: usb 1-1-port2: over-current change #24193391 Aug 27 10:37:24 volumiokueche kernel: usb 1-1-port2: over-current change #24193392 Aug 27 10:37:25 volumiokueche kernel: usb 1-1-port2: over-current change #24193393 Aug 27 10:37:25 volumiokueche kernel: usb 1-1-port2: over-current change #24193394 Aug 27 10:37:25 volumiokueche kernel: usb 1-1-port2: over-current change #24193395 Aug 27 10:37:26 volumiokueche kernel: usb 1-1-port2: over-current change #24193396 Aug 27 10:37:26 volumiokueche kernel: usb 1-1-port2: over-current change #24193397 Aug 27 10:37:26 volumiokueche kernel: usb 1-1-port2: over-current change #24193398 Aug 27 10:37:26 volumiokueche kernel: usb 1-1-port2: over-current change #24193399 Aug 27 10:37:27 volumiokueche kernel: usb 1-1-port2: over-current change #24193400 Aug 27 10:37:27 volumiokueche kernel: usb 1-1-port2: over-current change #24193401 Aug 27 10:37:27 volumiokueche kernel: usb 1-1-port2: over-current change #24193402 Aug 27 10:37:27 volumiokueche kernel: usb 1-1-port2: over-current change #24193403 Aug 27 10:37:28 volumiokueche kernel: usb 1-1-port2: over-current change #24193404 Aug 27 10:37:28 volumiokueche kernel: usb 1-1-port2: over-current change #24193405 Aug 27 10:37:28 volumiokueche kernel: usb 1-1-port2: over-current change #24193406 Aug 27 10:37:28 volumiokueche kernel: usb 1-1-port2: over-current change #24193407 Aug 27 10:37:29 volumiokueche kernel: usb 1-1-port2: over-current change #24193408 Aug 27 10:37:29 volumiokueche kernel: usb 1-1-port2: over-current change #24193409 Aug 27 10:37:29 volumiokueche kernel: usb 1-1-port2: over-current change #24193410 Aug 27 10:37:29 volumiokueche kernel: usb 1-1-port2: over-current change #24193411 Aug 27 10:37:30 volumiokueche kernel: usb 1-1-port2: over-current change #24193412 Aug 27 10:37:30 volumiokueche kernel: usb 1-1-port2: over-current change #24193413 Aug 27 10:37:30 volumiokueche kernel: usb 1-1-port2: over-current change #24193414 Aug 27 10:37:30 volumiokueche kernel: usb 1-1-port2: over-current change #24193415 Aug 27 10:37:31 volumiokueche kernel: usb 1-1-port2: over-current change #24193416 Aug 27 10:37:31 volumiokueche kernel: usb 1-1-port2: over-current change #24193417 Aug 27 10:37:31 volumiokueche kernel: usb 1-1-port2: over-current change #24193418 Aug 27 10:37:31 volumiokueche kernel: usb 1-1-port2: over-current change #24193419 Aug 27 10:37:32 volumiokueche kernel: usb 1-1-port2: over-current change #24193420 Aug 27 10:37:32 volumiokueche kernel: usb 1-1-port2: over-current change #24193421 Aug 27 10:37:32 volumiokueche kernel: usb 1-1-port2: over-current change #24193422 Aug 27 10:37:32 volumiokueche kernel: usb 1-1-port2: over-current change #24193423 Aug 27 10:37:33 volumiokueche kernel: usb 1-1-port2: over-current change #24193424 Aug 27 10:37:33 volumiokueche kernel: usb 1-1-port2: over-current change #24193425 Aug 27 10:37:33 volumiokueche kernel: usb 1-1-port2: over-current change #24193426 Aug 27 10:37:33 volumiokueche kernel: usb 1-1-port2: over-current change #24193427 Aug 27 10:37:34 volumiokueche kernel: usb 1-1-port2: over-current change #24193428 Aug 27 10:37:34 volumiokueche kernel: usb 1-1-port2: over-current change #24193429 Aug 27 10:37:34 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:34+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]" Aug 27 10:37:34 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:34+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]" Aug 27 10:37:34 volumiokueche kernel: usb 1-1-port2: over-current change #24193430 Aug 27 10:37:34 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:34+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]" Aug 27 10:37:34 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:34+02:00" level=info msg="zeroconf server listening on port 41263" Aug 27 10:37:34 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:34+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 10:37:34 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:34+02:00" level=debug msg="obtained new client token: AAFp9ARdyKPwWJ+F5uyTbXUaq0AVeL8Bp4p2Iv9Cbf84VwM3ylZev0Q7w9wWQ7fiAccNfJ5S/4xCeWVgsAEDwxNmpJPfq03QC4CZWcJ2QLw4WzZIG7Y5sLWlmsW0SS/t8Q8judCbVD18ZFixvP3KPpt/eONex7Gi3TnXtPikDUII7KStHCwiBMF5ZealmkEMuRVQcNCwMd2VcMfoNR/JZ7GnOg1lyTB2EhjiCfz29X29ola1lgWy+ACfOg==" Aug 27 10:37:34 volumiokueche kernel: usb 1-1-port2: over-current change #24193431 Aug 27 10:37:35 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 27 10:37:35 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:35+02:00" level=debug msg="completed keyexchange" Aug 27 10:37:35 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:35+02:00" level=debug msg="completed challenge" Aug 27 10:37:35 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:35+02:00" level=info msg="authenticated AP" username="2d*********************gx" Aug 27 10:37:35 volumiokueche kernel: usb 1-1-port2: over-current change #24193432 Aug 27 10:37:35 volumiokueche go-librespot[30010]: time="2026-08-27T10:37:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 10:37:35 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 10:37:35 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 10:37:35 volumiokueche kernel: usb 1-1-port2: over-current change #24193433 Aug 27 10:37:35 volumiokueche kernel: usb 1-1-port2: over-current change #24193434 Aug 27 10:37:36 volumiokueche kernel: usb 1-1-port2: over-current change #24193435 Aug 27 10:37:36 volumiokueche kernel: usb 1-1-port2: over-current change #24193436 Aug 27 10:37:36 volumiokueche kernel: usb 1-1-port2: over-current change #24193437 Aug 27 10:37:36 volumiokueche kernel: usb 1-1-port2: over-current change #24193438 Aug 27 10:37:37 volumiokueche kernel: usb 1-1-port2: over-current change #24193439 Aug 27 10:37:37 volumiokueche kernel: usb 1-1-port2: over-current change #24193440 Aug 27 10:37:37 volumiokueche kernel: usb 1-1-port2: over-current change #24193441 Aug 27 10:37:37 volumiokueche kernel: usb 1-1-port2: over-current change #24193442 Aug 27 10:37:38 volumiokueche kernel: usb 1-1-port2: over-current change #24193443 Aug 27 10:37:38 volumiokueche kernel: usb 1-1-port2: over-current change #24193444 Aug 27 10:37:38 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 27 10:37:38 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:38 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:38 volumiokueche go-librespot[30069]: go-librespot daemon starting... Aug 27 10:37:38 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:38+02:00" level=info msg="running go-librespot 0.7.1" Aug 27 10:37:38 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:38+02:00" level=debug msg="app state loaded" Aug 27 10:37:38 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 10:37:38 volumiokueche kernel: usb 1-1-port2: over-current change #24193445 Aug 27 10:37:38 volumiokueche kernel: usb 1-1-port2: over-current change #24193446 Aug 27 10:37:39 volumiokueche kernel: usb 1-1-port2: over-current change #24193447 Aug 27 10:37:39 volumiokueche volumio-remote-updater[699]: [2026-08-27 10:37:39] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Aug 27 10:37:39 volumiokueche volumio-remote-updater[699]: [2026-08-27 10:37:39] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Aug 27 10:37:39 volumiokueche volumio5-onboarding[1657]: time=2026-08-27T10:37:39.342+02:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:47732->127.0.0.1:3000: read: connection reset by peer" Aug 27 10:37:39 volumiokueche volumio5-onboarding[1657]: time=2026-08-27T10:37:39.344+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 10:37:39 volumiokueche systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 27 10:37:39 volumiokueche kernel: usb 1-1-port2: over-current change #24193448 Aug 27 10:37:39 volumiokueche systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 27 10:37:39 volumiokueche systemd[1]: volumio.service: Consumed 11h 45min 11.786s CPU time. Aug 27 10:37:39 volumiokueche systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 27 10:37:39 volumiokueche systemd[1]: dynamicswap.service: Deactivated successfully. Aug 27 10:37:39 volumiokueche systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Aug 27 10:37:39 volumiokueche kernel: usb 1-1-port2: over-current change #24193449 Aug 27 10:37:39 volumiokueche systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 27 10:37:39 volumiokueche systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 27 10:37:39 volumiokueche systemd[1]: volumio.service: Consumed 11h 45min 11.786s CPU time. Aug 27 10:37:39 volumiokueche systemd[1]: Started volumio.service - Volumio Backend Module. Aug 27 10:37:39 volumiokueche systemd[1]: dynamicswap.service: Deactivated successfully. Aug 27 10:37:39 volumiokueche kernel: usb 1-1-port2: over-current change #24193450 Aug 27 10:37:40 volumiokueche kernel: usb 1-1-port2: over-current change #24193451 Aug 27 10:37:40 volumiokueche volumio5-onboarding[1657]: time=2026-08-27T10:37:40.346+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 10:37:40 volumiokueche kernel: usb 1-1-port2: over-current change #24193452 Aug 27 10:37:40 volumiokueche kernel: usb 1-1-port2: over-current change #24193453 Aug 27 10:37:40 volumiokueche kernel: usb 1-1-port2: over-current change #24193454 Aug 27 10:37:41 volumiokueche kernel: usb 1-1-port2: over-current change #24193455 Aug 27 10:37:41 volumiokueche kernel: usb 1-1-port2: over-current change #24193456 Aug 27 10:37:41 volumiokueche kernel: usb 1-1-port2: over-current change #24193457 Aug 27 10:37:41 volumiokueche volumio[30124]: info: ------------------------------------------- Aug 27 10:37:41 volumiokueche volumio[30124]: info: ----- Volumio3 ---- Aug 27 10:37:41 volumiokueche volumio[30124]: info: ------------------------------------------- Aug 27 10:37:41 volumiokueche volumio[30124]: info: ----- System startup ---- Aug 27 10:37:41 volumiokueche volumio[30124]: info: ------------------------------------------- Aug 27 10:37:41 volumiokueche kernel: usb 1-1-port2: over-current change #24193458 Aug 27 10:37:42 volumiokueche kernel: usb 1-1-port2: over-current change #24193459 Aug 27 10:37:42 volumiokueche kernel: usb 1-1-port2: over-current change #24193460 Aug 27 10:37:42 volumiokueche kernel: usb 1-1-port2: over-current change #24193461 Aug 27 10:37:42 volumiokueche volumio[30124]: info: MYVOLUMIO Environment detected Aug 27 10:37:42 volumiokueche volumio[30124]: info: Plugin folders cleanup Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning into folder /volumio/app/plugins/ Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning category audio_interface Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning category miscellanea Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning category music_service Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning category plugins.json Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning category system_controller Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning category user_interface Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning into folder /data/plugins/ Aug 27 10:37:42 volumiokueche volumio[30124]: info: Scanning category music_service Aug 27 10:37:42 volumiokueche volumio[30124]: info: Plugin folders cleanup completed Aug 27 10:37:42 volumiokueche volumio[30124]: info: ------------------------------------------- Aug 27 10:37:42 volumiokueche volumio[30124]: info: ----- Core plugins startup ---- Aug 27 10:37:42 volumiokueche kernel: usb 1-1-port2: over-current change #24193462 Aug 27 10:37:42 volumiokueche volumio[30124]: info: ------------------------------------------- Aug 27 10:37:42 volumiokueche volumio[30124]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 10:37:42 volumiokueche volumio[30124]: info: Adding plugin upnp to MyMusic Plugins Aug 27 10:37:42 volumiokueche volumio[30124]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 10:37:42 volumiokueche volumio[30124]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 10:37:42 volumiokueche volumio[30124]: info: Loading plugins from folder /data/plugins/ Aug 27 10:37:42 volumiokueche volumio[30124]: info: Loading plugin "system"... Aug 27 10:37:43 volumiokueche volumio[30124]: info: Loading plugin "appearance"... Aug 27 10:37:43 volumiokueche kernel: usb 1-1-port2: over-current change #24193463 Aug 27 10:37:43 volumiokueche kernel: usb 1-1-port2: over-current change #24193464 Aug 27 10:37:43 volumiokueche kernel: usb 1-1-port2: over-current change #24193465 Aug 27 10:37:43 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:43+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]" Aug 27 10:37:43 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:43+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]" Aug 27 10:37:43 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:43+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]" Aug 27 10:37:43 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:43+02:00" level=info msg="zeroconf server listening on port 37613" Aug 27 10:37:43 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:43+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 10:37:43 volumiokueche kernel: usb 1-1-port2: over-current change #24193466 Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "network"... Aug 27 10:37:44 volumiokueche volumio[30124]: info: Refreshing Cached IP Addresses Aug 27 10:37:44 volumiokueche sudo[30154]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 10:37:44 volumiokueche sudo[30154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:44 volumiokueche sudo[30156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "services"... Aug 27 10:37:44 volumiokueche sudo[30156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:44 volumiokueche sudo[30154]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "volumio5onboarding"... Aug 27 10:37:44 volumiokueche sudo[30156]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "alsa_controller"... Aug 27 10:37:44 volumiokueche kernel: usb 1-1-port2: over-current change #24193467 Aug 27 10:37:44 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "wizard"... Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "networkfs"... Aug 27 10:37:44 volumiokueche volumio[30124]: info: Cannot mount NAS Musik at system boot, trial number 1 ,retrying in 5 seconds Aug 27 10:37:44 volumiokueche volumio[30124]: info: Starting Udev Watcher for removable devices Aug 27 10:37:44 volumiokueche volumio-remote-updater[699]: [2026-08-27 10:37:44] [connect] Successful connection Aug 27 10:37:44 volumiokueche volumio[30124]: info: Ignoring mount for partition: boot Aug 27 10:37:44 volumiokueche volumio[30124]: info: Ignoring mount for partition: volumio Aug 27 10:37:44 volumiokueche volumio[30124]: info: Ignoring mount for partition: volumio_data Aug 27 10:37:44 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "volumio_command_line_client"... Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "upnp"... Aug 27 10:37:44 volumiokueche volumio[30124]: info: [1787819864362] Starting Upmpd Daemon Aug 27 10:37:44 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "my_music"... Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "mpd"... Aug 27 10:37:44 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:44+02:00" level=debug msg="obtained new client token: AAHH1GhrrXxTtweht9lj6RjpNfEcrE9f/5JcOVW2cqWMySIepkDMiEhvCMkJFBN269Rf72qwWqM/PiyHj7BgP9WPpmO+dc0QL2l9dlwFwKBoECbR5zB5aMx6UQjfQK0q6Ts/NysHPyaEWlAZxm4yr1uin7XDpmrHwnUKUn4s+o/r2brPcuKNOaa99kEU9Fcz0L0aP7CMSCO7/x3z57G84w2KvKQujVcWps/TpiesYaBVXhTCKlhcYd3L3w==" Aug 27 10:37:44 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:44+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 27 10:37:44 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:44+02:00" level=debug msg="completed keyexchange" Aug 27 10:37:44 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:44+02:00" level=debug msg="completed challenge" Aug 27 10:37:44 volumiokueche kernel: usb 1-1-port2: over-current change #24193468 Aug 27 10:37:44 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:44+02:00" level=info msg="authenticated AP" username="2d*********************gx" Aug 27 10:37:44 volumiokueche go-librespot[30070]: time="2026-08-27T10:37:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 10:37:44 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 10:37:44 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 10:37:44 volumiokueche volumio[30124]: info: Loading plugin "upnp_browser"... Aug 27 10:37:44 volumiokueche kernel: usb 1-1-port2: over-current change #24193469 Aug 27 10:37:45 volumiokueche kernel: usb 1-1-port2: over-current change #24193470 Aug 27 10:37:45 volumiokueche kernel: usb 1-1-port2: over-current change #24193471 Aug 27 10:37:45 volumiokueche kernel: usb 1-1-port2: over-current change #24193472 Aug 27 10:37:45 volumiokueche kernel: usb 1-1-port2: over-current change #24193473 Aug 27 10:37:46 volumiokueche kernel: usb 1-1-port2: over-current change #24193474 Aug 27 10:37:46 volumiokueche volumio[30124]: info: Starting UPNP Browser Aug 27 10:37:46 volumiokueche volumio[30124]: info: Loading plugin "alarm-clock"... Aug 27 10:37:46 volumiokueche kernel: usb 1-1-port2: over-current change #24193475 Aug 27 10:37:46 volumiokueche volumio[30124]: info: Loading plugin "airplay_emulation"... Aug 27 10:37:46 volumiokueche volumio[30124]: info: Starting Shairport Sync Aug 27 10:37:46 volumiokueche volumio[30124]: info: Loading plugin "last_100"... Aug 27 10:37:46 volumiokueche volumio[30124]: info: Loading plugin "webradio"... Aug 27 10:37:46 volumiokueche kernel: usb 1-1-port2: over-current change #24193476 Aug 27 10:37:46 volumiokueche volumio[30124]: info: Loading plugin "i2s_dacs"... Aug 27 10:37:46 volumiokueche volumio[30124]: info: Loading plugin "volumiodiscovery"... Aug 27 10:37:46 volumiokueche volumio[30124]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 10:37:46 volumiokueche volumio[30124]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 10:37:46 volumiokueche volumio[30124]: *** WARNING *** For more information see Aug 27 10:37:46 volumiokueche volumio[30124]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 10:37:46 volumiokueche volumio[30124]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 10:37:46 volumiokueche volumio[30124]: *** WARNING *** For more information see Aug 27 10:37:46 volumiokueche node[30124]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 10:37:46 volumiokueche node[30124]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 10:37:46 volumiokueche node[30124]: *** WARNING *** For more information see Aug 27 10:37:46 volumiokueche node[30124]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 10:37:46 volumiokueche node[30124]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 10:37:46 volumiokueche node[30124]: *** WARNING *** For more information see Aug 27 10:37:46 volumiokueche volumio[30124]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 10:37:46 volumiokueche volumio[30124]: info: Discovery: Started advertising with name: VolumioKueche Aug 27 10:37:46 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 10:37:46 volumiokueche volumio[30124]: info: Loading plugin "spop"... Aug 27 10:37:46 volumiokueche kernel: usb 1-1-port2: over-current change #24193477 Aug 27 10:37:47 volumiokueche kernel: usb 1-1-port2: over-current change #24193478 Aug 27 10:37:47 volumiokueche kernel: usb 1-1-port2: over-current change #24193479 Aug 27 10:37:47 volumiokueche kernel: usb 1-1-port2: over-current change #24193480 Aug 27 10:37:47 volumiokueche volumio[30124]: info: Loading plugin "outputs"... Aug 27 10:37:47 volumiokueche volumio[30124]: info: Loading plugin "albumart"... Aug 27 10:37:47 volumiokueche volumio[30124]: info: Plugin example_plugin is not enabled Aug 27 10:37:47 volumiokueche volumio[30124]: info: Loading plugin "inputs"... Aug 27 10:37:47 volumiokueche volumio[30124]: info: Loading plugin "updater_comm"... Aug 27 10:37:47 volumiokueche kernel: usb 1-1-port2: over-current change #24193481 Aug 27 10:37:47 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 27 10:37:47 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:47 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:47 volumiokueche go-librespot[30197]: go-librespot daemon starting... Aug 27 10:37:47 volumiokueche volumio[30124]: info: Plugin mpdemulation is not enabled Aug 27 10:37:47 volumiokueche volumio[30124]: info: Loading plugin "rest_api"... Aug 27 10:37:47 volumiokueche volumio[30124]: info: Loading plugin "websocket"... Aug 27 10:37:47 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:47+02:00" level=info msg="running go-librespot 0.7.1" Aug 27 10:37:47 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:47+02:00" level=debug msg="app state loaded" Aug 27 10:37:47 volumiokueche volumio[30124]: info: Starting Socket.io Server version 1.7.4 Aug 27 10:37:47 volumiokueche volumio[30124]: info: Loading i18n strings for locale de Aug 27 10:37:47 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 10:37:48 volumiokueche volumio[30124]: Updating browse sources language Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 10:37:48 volumiokueche kernel: usb 1-1-port2: over-current change #24193482 Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::initPlayerControls Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: Express server listening on port 3000 Aug 27 10:37:48 volumiokueche volumio[30124]: [Metrics] WebUI: 6s 903.86ms Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::resetVolumioState Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::getcurrentVolume Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 10:37:48 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 10:37:48 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 10:37:48 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 10:37:48 volumiokueche volumio[30124]: info: Cannot read play queue from file Aug 27 10:37:48 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:48+02:00" level=info msg="zeroconf server listening on port 39731" Aug 27 10:37:48 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:48+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 10:37:48 volumiokueche volumio[30124]: info: Volumio Network Manager: Network status updated: 1 Aug 27 10:37:48 volumiokueche kernel: usb 1-1-port2: over-current change #24193483 Aug 27 10:37:48 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:48+02:00" level=debug msg="obtained new client token: AAG/roqGsAPhD55XS+iu1/NyB8usY201NFj3Yx/riMAW35poJIytHSB8T2PLPwxDFrbU0BgJKcs+MDK2x56vwz2tCTLYGHvfiyr+2iD9L6XKftLIP3DUX6nqEoN5oBF4RL2Mv+xQ7PqHGM0KYaDhkMPNT9unXM1NFiuhpDq+5YgzVU41Ecreknp9zbSzJRmC4aoMQM6jLvqxVzM9TwIFCOLlCbE/Toyjy1JlGdF0ROFi1a+mOLKGCGtF+Q==" Aug 27 10:37:48 volumiokueche volumio[30124]: 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 Aug 27 10:37:48 volumiokueche volumio[30187]: Forking 3 albumart workers Aug 27 10:37:48 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 27 10:37:48 volumiokueche volumio-remote-updater[699]: [2026-08-27 10:37:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787819864 101 Aug 27 10:37:48 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:48+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Aug 27 10:37:48 volumiokueche volumio[30124]: 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: 1 Aug 27 10:37:48 volumiokueche volumio[30124]: info: VolumeController:: Volume=84 Mute =false Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::pushState Aug 27 10:37:48 volumiokueche volumio[30124]: info: CorePlayQueue::getTrack 0 Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioPushState Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::updateTrackBlock Aug 27 10:37:48 volumiokueche volumio[30124]: info: CorePlayQueue::getTrackBlock Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 10:37:48 volumiokueche kernel: usb 1-1-port2: over-current change #24193484 Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: verbose: New Socket.io Connection to 192.168.178.211:3000 from 192.168.178.210 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::setRepeat null single undefined Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::pushState Aug 27 10:37:48 volumiokueche volumio[30124]: info: CorePlayQueue::getTrack 0 Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioPushState Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::setRandom null Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::pushState Aug 27 10:37:48 volumiokueche volumio[30124]: info: CorePlayQueue::getTrack 0 Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioPushState Aug 27 10:37:48 volumiokueche volumio[30124]: info: Setting Device type: Raspberry PI Aug 27 10:37:48 volumiokueche volumio[30124]: verbose: New Socket.io Connection to 192.168.178.211:3000 from 192.168.178.210 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 27 10:37:48 volumiokueche volumio[30124]: info: Completed loading Core Plugins Aug 27 10:37:48 volumiokueche volumio[30124]: info: Preparing to generate the ALSA configuration file Aug 27 10:37:48 volumiokueche volumio[30124]: 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: 4 Aug 27 10:37:48 volumiokueche volumio[30124]: info: VolumeController:: Volume=84 Mute =false Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreStateMachine::pushState Aug 27 10:37:48 volumiokueche volumio[30124]: info: CorePlayQueue::getTrack 0 Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioPushState Aug 27 10:37:48 volumiokueche kernel: usb 1-1-port2: over-current change #24193485 Aug 27 10:37:48 volumiokueche volumio[30124]: info: Asound.conf file unchanged, so no further update is needed Aug 27 10:37:48 volumiokueche volumio[30124]: info: Output device has changed, restarting MPD Aug 27 10:37:48 volumiokueche volumio[30124]: info: Output device has changed, restarting Shairport Sync Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:37:48 volumiokueche volumio[30124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 10:37:48 volumiokueche volumio[30124]: info: ___________ START PLUGINS ___________ Aug 27 10:37:48 volumiokueche sudo[30255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 10:37:48 volumiokueche sudo[30253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 10:37:48 volumiokueche sudo[30255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:48 volumiokueche sudo[30253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:48 volumiokueche sudo[30253]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:48 volumiokueche volumio[30124]: info: ControllerMpd::onStart: Initializing MPD Aug 27 10:37:48 volumiokueche volumio[30124]: info: Creating MPD Configuration file Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 10:37:49 volumiokueche systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 27 10:37:49 volumiokueche upmpdcli[1426]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 10:37:49 volumiokueche volumio[30124]: info: [1787819869054] CoreMusicLibrary::Adding element Medienserver Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 10:37:49 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 27 10:37:49 volumiokueche sudo[30265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 10:37:49 volumiokueche sudo[30265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:49 volumiokueche sudo[30263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 27 10:37:49 volumiokueche sudo[30263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:49 volumiokueche sudo[30265]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:49 volumiokueche sudo[30267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 10:37:49 volumiokueche sudo[30267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:49 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:49+02:00" level=debug msg="completed keyexchange" Aug 27 10:37:49 volumiokueche volumio[30124]: info: UPNP Browser: Client initialized successfully Aug 27 10:37:49 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:49+02:00" level=debug msg="completed challenge" Aug 27 10:37:49 volumiokueche systemd[1]: mpd.service: Deactivated successfully. Aug 27 10:37:49 volumiokueche systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 10:37:49 volumiokueche systemd[1]: mpd.service: Consumed 1min 36.636s CPU time. Aug 27 10:37:49 volumiokueche systemd[1]: mpd.socket: Deactivated successfully. Aug 27 10:37:49 volumiokueche systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 10:37:49 volumiokueche systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 10:37:49 volumiokueche kernel: usb 1-1-port2: over-current change #24193486 Aug 27 10:37:49 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:49+02:00" level=info msg="authenticated AP" username="2d*********************gx" Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:37:49 volumiokueche systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 10:37:49 volumiokueche systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 10:37:49 volumiokueche volumio[30124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:37:49 volumiokueche systemd[1]: mpd.service: Deactivated successfully. Aug 27 10:37:49 volumiokueche systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 10:37:49 volumiokueche systemd[1]: mpd.socket: Deactivated successfully. Aug 27 10:37:49 volumiokueche systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 10:37:49 volumiokueche go-librespot[30198]: time="2026-08-27T10:37:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 10:37:49 volumiokueche systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 10:37:49 volumiokueche volumio[30124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 10:37:49 volumiokueche volumio[30124]: info: [1787819869341] CoreMusicLibrary::Adding element Last_100 Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 10:37:49 volumiokueche volumio[30124]: info: [1787819869347] CoreMusicLibrary::Adding element Webradio Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 10:37:49 volumiokueche volumio[30124]: info: Initializing BBC Radios Aug 27 10:37:49 volumiokueche kernel: usb 1-1-port2: over-current change #24193487 Aug 27 10:37:49 volumiokueche systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 10:37:49 volumiokueche systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 10:37:49 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 10:37:49 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 10:37:49 volumiokueche sudo[30263]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:37:49 volumiokueche volumio[30124]: info: Creating Spotify config file Aug 27 10:37:49 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:49 volumiokueche kernel: usb 1-1-port2: over-current change #24193488 Aug 27 10:37:49 volumiokueche sudo[30295]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 10:37:49 volumiokueche sudo[30295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 10:37:49 volumiokueche sudo[30295]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:49 volumiokueche kernel: usb 1-1-port2: over-current change #24193489 Aug 27 10:37:49 volumiokueche volumio[30124]: info: Volumio Calling Home Aug 27 10:37:50 volumiokueche kernel: usb 1-1-port2: over-current change #24193490 Aug 27 10:37:50 volumiokueche volumio[30124]: 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: 4 Aug 27 10:37:50 volumiokueche kernel: usb 1-1-port2: over-current change #24193491 Aug 27 10:37:50 volumiokueche volumio[30124]: info: Cannot mount NAS Musik at system boot, trial number 2 ,retrying in 5 seconds Aug 27 10:37:50 volumiokueche volumio[30215]: Starting albumart workers Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioGetState Aug 27 10:37:50 volumiokueche volumio[30124]: info: CorePlayQueue::getTrack 0 Aug 27 10:37:50 volumiokueche volumio[30124]: info: MPD Permissions set Aug 27 10:37:50 volumiokueche volumio[30124]: info: MPD Permissions set Aug 27 10:37:50 volumiokueche volumio[30124]: 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: 4 Aug 27 10:37:50 volumiokueche volumio[30124]: info: Spotify config file written Aug 27 10:37:50 volumiokueche volumio[30124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche sudo[30307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 27 10:37:50 volumiokueche sudo[30307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:50 volumiokueche kernel: usb 1-1-port2: over-current change #24193492 Aug 27 10:37:50 volumiokueche volumio[30216]: Starting albumart workers Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:37:50 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:37:50 volumiokueche volumio[30124]: info: No need to fix Spotify hosts Aug 27 10:37:50 volumiokueche go-librespot[30314]: go-librespot daemon starting... Aug 27 10:37:50 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:50 volumiokueche sudo[30307]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:50 volumiokueche volumio[30214]: Starting albumart workers Aug 27 10:37:50 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:50+02:00" level=info msg="running go-librespot 0.7.1" Aug 27 10:37:50 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:50+02:00" level=debug msg="app state loaded" Aug 27 10:37:50 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:50 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 10:37:50 volumiokueche kernel: usb 1-1-port2: over-current change #24193493 Aug 27 10:37:50 volumiokueche volumio[30124]: info: Discovery: adding 61b2ca08-441a-4552-bced-e073ce312686 Aug 27 10:37:50 volumiokueche volumio[30124]: info: Discovery: Found device VolumioKueche Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioGetState Aug 27 10:37:50 volumiokueche volumio[30124]: info: CorePlayQueue::getTrack 0 Aug 27 10:37:50 volumiokueche volumio[30124]: info: Discovery: this is already registered, 61b2ca08-441a-4552-bced-e073ce312686 Aug 27 10:37:50 volumiokueche volumio[30124]: info: Discovery: Found device VolumioKueche Aug 27 10:37:50 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioGetState Aug 27 10:37:50 volumiokueche volumio[30124]: info: CorePlayQueue::getTrack 0 Aug 27 10:37:51 volumiokueche volumio[30124]: info: Volumio called home Aug 27 10:37:51 volumiokueche volumio[30124]: info: Discovery: adding 3bf20bf0-bd99-4e17-9943-2cfc30c0e5e6 Aug 27 10:37:51 volumiokueche volumio[30124]: info: Discovery: Found device VolumioWohnzimmer Aug 27 10:37:51 volumiokueche volumio[30124]: info: Discovery: Connecting to remote: 192.168.178.210 Aug 27 10:37:51 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:51 volumiokueche volumio[30124]: info: Starting Shairport Sync Aug 27 10:37:51 volumiokueche volumio[30124]: info: Starting Shairport Sync Aug 27 10:37:51 volumiokueche volumio[30124]: info: Starting Shairport Sync Aug 27 10:37:51 volumiokueche sudo[30330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 10:37:51 volumiokueche sudo[30330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:51 volumiokueche kernel: usb 1-1-port2: over-current change #24193494 Aug 27 10:37:51 volumiokueche sudo[30333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 10:37:51 volumiokueche sudo[30333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:51 volumiokueche sudo[30331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 10:37:51 volumiokueche sudo[30331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:51 volumiokueche systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 10:37:51 volumiokueche systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 10:37:51 volumiokueche systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 10:37:51 volumiokueche systemd[1]: shairport-sync.service: Consumed 5h 31min 37.584s CPU time. Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+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-gae2.spotify.com:80]" Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=info msg="zeroconf server listening on port 43599" Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 10:37:51 volumiokueche systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 10:37:51 volumiokueche sudo[30333]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:51 volumiokueche kernel: usb 1-1-port2: over-current change #24193495 Aug 27 10:37:51 volumiokueche sudo[30330]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:51 volumiokueche systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 10:37:51 volumiokueche systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 10:37:51 volumiokueche systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 10:37:51 volumiokueche systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 10:37:51 volumiokueche sudo[30331]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=debug msg="obtained new client token: AAFO4gwvDyTqKztoBjwUrch6EvmiyjA86mn7inHss/BflsMQ59Hj/QB2SasHqiS9uROlKoF2g5lBj7GbxjxVGMykA2dvy7wGnxU4asY3Y4cNpmApoguXCk1Duv8mVtiMYnDPeR85fp05mXBse1rymOqq81spTYItOLuF/okCrMFc5Q9sbXiVM6sciU9X/Cpwv5d7jAfjPd6yxSWrGnaCHRhsm10/T97xIXPtSMSMs8TcgvyNDa0z7IJ9oA==" Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=debug msg="completed keyexchange" Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=debug msg="completed challenge" Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=info msg="authenticated AP" username="2d*********************gx" Aug 27 10:37:51 volumiokueche kernel: usb 1-1-port2: over-current change #24193496 Aug 27 10:37:51 volumiokueche go-librespot[30320]: time="2026-08-27T10:37:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 10:37:51 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 10:37:51 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 10:37:51 volumiokueche kernel: usb 1-1-port2: over-current change #24193497 Aug 27 10:37:52 volumiokueche volumio[30124]: info: Shairport-Sync Started Aug 27 10:37:52 volumiokueche volumio[30124]: Error adding Membership: Error: addMembership EINVAL Aug 27 10:37:52 volumiokueche volumio[30124]: info: Shairport-Sync Started Aug 27 10:37:52 volumiokueche volumio[30124]: info: Shairport-Sync Started Aug 27 10:37:52 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:52 volumiokueche kernel: usb 1-1-port2: over-current change #24193498 Aug 27 10:37:52 volumiokueche volumio[30124]: info: New Spotify access tokenBQDcjo1fo6... Aug 27 10:37:52 volumiokueche volumio[30124]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 27 10:37:52 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:52 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioGetState Aug 27 10:37:52 volumiokueche volumio[30124]: info: CorePlayQueue::getTrack 0 Aug 27 10:37:52 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:52 volumiokueche kernel: usb 1-1-port2: over-current change #24193499 Aug 27 10:37:52 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:52 volumiokueche volumio[30124]: info: Discovery: Connected to remote: 192.168.178.210 Aug 27 10:37:52 volumiokueche kernel: usb 1-1-port2: over-current change #24193500 Aug 27 10:37:52 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:52 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:52 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:52 volumiokueche volumio[30124]: 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: 5 Aug 27 10:37:52 volumiokueche volumio[30124]: SPOTIFY: User informations: {"account_id":"xl7vBlaAES","country":"DE","display_name":"Klaus","email":"klaus.wallenfels@googlemail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/2d4aoi5g8ykumxge05st6gpgx"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/2d4aoi5g8ykumxge05st6gpgx","id":"2d4aoi5g8ykumxge05st6gpgx","images":[],"product":"premium","type":"user","uri":"spotify:user:2d4aoi5g8ykumxge05st6gpgx"} Aug 27 10:37:52 volumiokueche volumio[30124]: info: Spotify Successfully logged in Aug 27 10:37:52 volumiokueche volumio[30124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 10:37:52 volumiokueche volumio[30124]: info: [1787819872971] CoreMusicLibrary::Adding element Spotify Aug 27 10:37:52 volumiokueche volumio[30124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 10:37:52 volumiokueche volumio[30124]: Cannot find translation for source Spotify Aug 27 10:37:52 volumiokueche kernel: usb 1-1-port2: over-current change #24193501 Aug 27 10:37:53 volumiokueche kernel: usb 1-1-port2: over-current change #24193502 Aug 27 10:37:53 volumiokueche kernel: usb 1-1-port2: over-current change #24193503 Aug 27 10:37:53 volumiokueche kernel: usb 1-1-port2: over-current change #24193504 Aug 27 10:37:54 volumiokueche volumio[30124]: info: go-librespot daemon successfully initialized Aug 27 10:37:54 volumiokueche kernel: usb 1-1-port2: over-current change #24193505 Aug 27 10:37:54 volumiokueche mpd[30301]: 2026-08-27T10:37:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 10:37:54 volumiokueche systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 10:37:54 volumiokueche upmpdcli[1426]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Aug 27 10:37:54 volumiokueche sudo[30255]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:54 volumiokueche sudo[30267]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:54 volumiokueche upmpdcli[1426]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 10:37:54 volumiokueche volumio[30124]: info: Completed starting Core Plugins Aug 27 10:37:54 volumiokueche volumio[30124]: info: ------------------------------------------- Aug 27 10:37:54 volumiokueche volumio[30124]: info: ----- MyVolumio plugins startup ---- Aug 27 10:37:54 volumiokueche volumio[30124]: info: ------------------------------------------- Aug 27 10:37:54 volumiokueche volumio[30124]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 10:37:54 volumiokueche volumio[30124]: error: MPD error: The expression evaluated to a falsy value: Aug 27 10:37:54 volumiokueche volumio[30124]: assert.ok(self.idling) Aug 27 10:37:54 volumiokueche volumio[30124]: error: The expression evaluated to a falsy value: Aug 27 10:37:54 volumiokueche volumio[30124]: assert.ok(self.idling) Aug 27 10:37:54 volumiokueche volumio[30124]: info: MPD running with PID30301 Aug 27 10:37:54 volumiokueche volumio[30124]: ,establishing connection Aug 27 10:37:54 volumiokueche volumio[30124]: error: updateQueue error: null Aug 27 10:37:54 volumiokueche volumio[30124]: error: updateQueue error: null Aug 27 10:37:54 volumiokueche kernel: usb 1-1-port2: over-current change #24193506 Aug 27 10:37:54 volumiokueche sudo[30397]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 10:37:54 volumiokueche sudo[30397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:54 volumiokueche sudo[30395]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 10:37:54 volumiokueche sudo[30395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:54 volumiokueche sudo[30395]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:54 volumiokueche sudo[30397]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:54 volumiokueche sudo[30399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 10:37:54 volumiokueche sudo[30399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:37:54 volumiokueche sudo[30399]: pam_unix(sudo:session): session closed for user root Aug 27 10:37:54 volumiokueche volumio[30124]: info: Upmpdcli Daemon Started Aug 27 10:37:54 volumiokueche kernel: usb 1-1-port2: over-current change #24193507 Aug 27 10:37:54 volumiokueche kernel: usb 1-1-port2: over-current change #24193508 Aug 27 10:37:54 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 27 10:37:54 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:54 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:37:54 volumiokueche go-librespot[30404]: go-librespot daemon starting... Aug 27 10:37:54 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:54+02:00" level=info msg="running go-librespot 0.7.1" Aug 27 10:37:54 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:54+02:00" level=debug msg="app state loaded" Aug 27 10:37:54 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 10:37:55 volumiokueche kernel: usb 1-1-port2: over-current change #24193509 Aug 27 10:37:55 volumiokueche kernel: usb 1-1-port2: over-current change #24193510 Aug 27 10:37:55 volumiokueche volumio[30124]: info: Cannot mount NAS Musik at system boot, trial number 3 ,retrying in 5 seconds Aug 27 10:37:55 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:55+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]" Aug 27 10:37:55 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:55+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]" Aug 27 10:37:55 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:55+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]" Aug 27 10:37:55 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:55+02:00" level=info msg="zeroconf server listening on port 34365" Aug 27 10:37:55 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:55+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 10:37:55 volumiokueche kernel: usb 1-1-port2: over-current change #24193511 Aug 27 10:37:55 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:55+02:00" level=debug msg="obtained new client token: AAHLY208M6nB5I1m3XMrJxaQAEf4zIWtX+sG412+okEu8M97JtVUJ7p837a3jb04F2Ip6Jq8Ue/TeAnk5kZYPehueTu7pyLRp6Cs+N9EN+qjU8mt5AMILKiyz5vHtsfRecED3WZmuNAIH2en73v4COuLIVK5ThT89/FUyMj+982pUfUbcuLR12z0uX+d4D7y9/ljg72hFeG8vvmksXuIu/t1qjoyDBnsOvWiM7MfYMLyej4TWqggHOBepw==" Aug 27 10:37:55 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 27 10:37:55 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:55+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Aug 27 10:37:55 volumiokueche kernel: usb 1-1-port2: over-current change #24193512 Aug 27 10:37:56 volumiokueche kernel: usb 1-1-port2: over-current change #24193513 Aug 27 10:37:56 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:56+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 27 10:37:56 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:56+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.178.211:37868->34.158.1.133:443: read: connection reset by peer" Aug 27 10:37:56 volumiokueche kernel: usb 1-1-port2: over-current change #24193514 Aug 27 10:37:56 volumiokueche kernel: usb 1-1-port2: over-current change #24193515 Aug 27 10:37:56 volumiokueche kernel: usb 1-1-port2: over-current change #24193516 Aug 27 10:37:56 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:56+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 27 10:37:56 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:56+02:00" level=debug msg="completed keyexchange" Aug 27 10:37:56 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:56+02:00" level=debug msg="completed challenge" Aug 27 10:37:56 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:56+02:00" level=info msg="authenticated AP" username="2d*********************gx" Aug 27 10:37:57 volumiokueche volumio[30124]: info: Initializing connection to go-librespot Websocket Aug 27 10:37:57 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:57+02:00" level=debug msg="new websocket client" Aug 27 10:37:57 volumiokueche volumio[30124]: info: Connection to go-librespot Websocket established Aug 27 10:37:57 volumiokueche go-librespot[30405]: time="2026-08-27T10:37:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 10:37:57 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 10:37:57 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 10:37:57 volumiokueche volumio[30124]: info: Connection to go-librespot Websocket closed Aug 27 10:37:57 volumiokueche kernel: usb 1-1-port2: over-current change #24193517 Aug 27 10:37:57 volumiokueche kernel: usb 1-1-port2: over-current change #24193518 Aug 27 10:37:57 volumiokueche kernel: usb 1-1-port2: over-current change #24193519 Aug 27 10:37:57 volumiokueche kernel: usb 1-1-port2: over-current change #24193520 Aug 27 10:37:58 volumiokueche kernel: usb 1-1-port2: over-current change #24193521 Aug 27 10:37:58 volumiokueche kernel: usb 1-1-port2: over-current change #24193522 Aug 27 10:37:58 volumiokueche kernel: usb 1-1-port2: over-current change #24193523 Aug 27 10:37:58 volumiokueche kernel: usb 1-1-port2: over-current change #24193524 Aug 27 10:37:59 volumiokueche kernel: usb 1-1-port2: over-current change #24193525 Aug 27 10:37:59 volumiokueche volumio[30124]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 10:37:59 volumiokueche kernel: usb 1-1-port2: over-current change #24193526 Aug 27 10:37:59 volumiokueche kernel: usb 1-1-port2: over-current change #24193527 Aug 27 10:37:59 volumiokueche kernel: usb 1-1-port2: over-current change #24193528 Aug 27 10:38:00 volumiokueche volumio[30124]: info: Getting Spotify volume Aug 27 10:38:00 volumiokueche volumio[30124]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 10:38:00 volumiokueche volumio[30124]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 10:38:00 volumiokueche volumio[30124]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 27 10:38:00 volumiokueche volumio[30124]: errno: -111, Aug 27 10:38:00 volumiokueche volumio[30124]: code: 'ECONNREFUSED', Aug 27 10:38:00 volumiokueche volumio[30124]: syscall: 'connect', Aug 27 10:38:00 volumiokueche volumio[30124]: address: '127.0.0.1', Aug 27 10:38:00 volumiokueche volumio[30124]: port: 9879, Aug 27 10:38:00 volumiokueche volumio[30124]: response: undefined Aug 27 10:38:00 volumiokueche volumio[30124]: } Aug 27 10:38:00 volumiokueche volumio[30124]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 10:38:00 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 27 10:38:00 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:38:00 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 10:38:00 volumiokueche go-librespot[30419]: go-librespot daemon starting... Aug 27 10:38:00 volumiokueche kernel: usb 1-1-port2: over-current change #24193529 Aug 27 10:38:00 volumiokueche go-librespot[30428]: time="2026-08-27T10:38:00+02:00" level=info msg="running go-librespot 0.7.1" Aug 27 10:38:00 volumiokueche go-librespot[30428]: time="2026-08-27T10:38:00+02:00" level=debug msg="app state loaded" Aug 27 10:38:00 volumiokueche go-librespot[30428]: time="2026-08-27T10:38:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 10:38:00 volumiokueche kernel: usb 1-1-port2: over-current change #24193530 Aug 27 10:38:00 volumiokueche kernel: usb 1-1-port2: over-current change #24193531 Aug 27 10:38:00 volumiokueche sudo[30440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 10:37' Aug 27 10:38:00 volumiokueche sudo[30440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:38:00 volumiokueche kernel: usb 1-1-port2: over-current change #24193532 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"