-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-01-19 19:35:17 CET. -- Jan 19 19:34:00 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:00 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:00 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:00 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:01 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:01 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:01 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:01 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:02 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:02 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:02 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:02 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:03 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:03 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:03 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:03 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:04 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:04 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:04 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:04 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:05 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:05 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:05 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:05 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:06 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:06 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:06 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:06 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:07 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:07 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:07 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:07 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:08 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:08 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:08 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:08 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:09 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:09 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:09 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:09 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:10 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:10 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:10 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:10 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:11 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:11 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:11 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:11 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:12 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:12 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:12 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:12 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:13 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:13 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:13 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:13 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:14 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:14 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:14 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:14 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:15 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:15 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:15 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:15 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:16 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:16 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:16 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:16 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:17 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:17 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:17 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:17 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:18 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:18 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:18 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:18 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:19 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:19 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:19 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:19 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:20 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:20 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:20 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:20 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:21 volumioraspdac go-librespot[1424]: time="2025-01-19T19:34:21+01:00" level=error msg="did not receive last pong ack from accesspoint, 176s passed" Jan 19 19:34:21 volumioraspdac go-librespot[1424]: panic: runtime error: invalid memory address or nil pointer dereference Jan 19 19:34:21 volumioraspdac go-librespot[1424]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x10 pc=0x48d6d4] Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 15 gp=0x2dccb48 m=3 mp=0x2c46b08 [running]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: panic({0x5eab20, 0xa60098}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:779 +0x12c fp=0x2c53f14 sp=0x2c53ec0 pc=0x58364 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.panicmem(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:261 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.sigpanic() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:881 +0x39c fp=0x2c53f44 sp=0x2c53f14 pc=0x74350 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/ap.(*Accesspoint).pongAckTicker(0x2d025a8) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:328 +0x24c fp=0x2c53fe4 sp=0x2c53f48 pc=0x48d6d4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/ap.NewAccesspoint.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x28 fp=0x2c53fec sp=0x2c53fe4 pc=0x48b71c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c53fec sp=0x2c53fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by go-librespot/ap.NewAccesspoint in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x188 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 1 gp=0x2c02128 m=nil [select, 22 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cd8d0c sp=0x2cd8cf8 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.selectgo(0x2cd8e0c, 0x2cd1dd8, 0x0, 0x0, 0x2, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2cd8dac sp=0x2cd8d0c pc=0x705e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/zeroconf.(*Zeroconf).Serve(0x30d4a50, 0x2cd8e88) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:268 +0x228 fp=0x2cd8e50 sp=0x2cd8dac pc=0x528c80 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*App).withAppPlayer(0x2c001e0, 0x2c7d000) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:297 +0x4b0 fp=0x2cd8ec0 sp=0x2cd8e50 pc=0x578aac Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*App).withCredentials(0x2c001e0, {0x5f3170, 0x2c0e1b0}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:171 +0x3d4 fp=0x2cd8f1c sp=0x2cd8ec0 pc=0x578184 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*App).SpotifyToken(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:131 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.main() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:466 +0x530 fp=0x2cd8fa8 sp=0x2cd8f1c pc=0x57a0cc Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.main() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:271 +0x2fc fp=0x2cd8fec sp=0x2cd8fa8 pc=0x5c29c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cd8fec sp=0x2cd8fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 18 gp=0x2c84248 m=nil [force gc (idle), 3 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f938, 0xaa84c0, 0x11, 0xa, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c3e7d4 sp=0x2c3e7c0 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goparkunlock(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.forcegchelper() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:326 +0xe4 fp=0x2c3e7ec sp=0x2c3e7d4 pc=0x5c668 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c3e7ec sp=0x2c3e7ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by runtime.init.5 in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:314 +0x1c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 19 gp=0x2c84368 m=nil [GC sweep wait]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f938, 0xaa8a10, 0xc, 0x9, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c3efc4 sp=0x2c3efb0 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goparkunlock(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.bgsweep(0x2c92000) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcsweep.go:318 +0x11c fp=0x2c3efe4 sp=0x2c3efc4 pc=0x4506c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gcenable.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x28 fp=0x2c3efec sp=0x2c3efe4 pc=0x3518c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c3efec sp=0x2c3efec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by runtime.gcenable in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x74 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 20 gp=0x2c84488 m=nil [GC scavenge wait]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f938, 0xaa9020, 0xd, 0xa, 0x2) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c3f7b4 sp=0x2c3f7a0 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goparkunlock(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.(*scavengerState).park(0xaa9020) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2c3f7c8 sp=0x2c3f7b4 pc=0x4238c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.bgscavenge(0x2c92000) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2c3f7e4 sp=0x2c3f7c8 pc=0x42ac4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gcenable.gowrap2() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0x28 fp=0x2c3f7ec sp=0x2c3f7e4 pc=0x35138 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c3f7ec sp=0x2c3f7ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by runtime.gcenable in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0xbc Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 21 gp=0x2c845a8 m=nil [finalizer wait, 7 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f7e4, 0xad3bac, 0x10, 0xa, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c3ff8c sp=0x2c3ff78 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.runfinq() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:194 +0x110 fp=0x2c3ffec sp=0x2c3ff8c pc=0x33f78 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c3ffec sp=0x2c3ffec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by runtime.createfing in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:164 +0x5c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 2 gp=0x2c030e8 m=nil [IO wait, 21 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f928, 0xb60b7f08, 0x2, 0x2, 0x5) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c42ddc sp=0x2c42dc8 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.netpollblock(0xb60b7ef8, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c42df4 sp=0x2c42ddc pc=0x540f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.runtime_pollWait(0xb60b7ef8, 0x72) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c42e08 sp=0x2c42df4 pc=0x8eba8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).wait(0x2c26d88, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c42e1c sp=0x2c42e08 pc=0x108108 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).waitRead(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*FD).Accept(0x2c26d70) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2c42e64 sp=0x2c42e1c pc=0x10c974 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*netFD).accept(0x2c26d70) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x2c42ecc sp=0x2c42e64 pc=0x2a2504 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*TCPListener).accept(0x2d24258) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x2c42ee4 sp=0x2c42ecc pc=0x2ba604 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*TCPListener).Accept(0x2d24258) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x2c42f00 sp=0x2c42ee4 pc=0x2b9670 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.(*onceCloseListener).Accept(0x2d89000) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: :1 +0x34 fp=0x2c42f18 sp=0x2c42f00 pc=0x45e914 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.(*Server).Serve(0x2d95048, {0x72527c, 0x2d24258}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x2c42fb0 sp=0x2c42f18 pc=0x43cfd0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.Serve(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*ApiServer).serve(0x2d1cd80) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:470 +0x5ec fp=0x2c42fe4 sp=0x2c42fb0 pc=0x57084c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.NewApiServer.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x28 fp=0x2c42fec sp=0x2c42fe4 pc=0x56ff20 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c42fec sp=0x2c42fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by main.NewApiServer in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x224 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 12 gp=0x2c03328 m=nil [IO wait]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f928, 0xb60b7d28, 0x2, 0x2, 0x5) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cd6e84 sp=0x2cd6e70 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.netpollblock(0xb60b7d18, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2cd6e9c sp=0x2cd6e84 pc=0x540f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.runtime_pollWait(0xb60b7d18, 0x72) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2cd6eb0 sp=0x2cd6e9c pc=0x8eba8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).wait(0x30d4b08, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2cd6ec4 sp=0x2cd6eb0 pc=0x108108 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).waitRead(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*FD).RawRead(0x30d4af0, 0x2c9b620) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2cd6ef0 sp=0x2cd6ec4 pc=0x10d578 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*rawConn).Read(0x304c388, 0x2c9b620) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x2cd6f10 sp=0x2cd6ef0 pc=0x2b4a2c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2d9a5c0, 0x2c9b5f0, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x2cd6f40 sp=0x2cd6f10 pc=0x4c9804 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2da25a8, {0x2e72000, 0x10000, 0x10000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv4/payload_cmsg.go:32 +0x44c fp=0x2cd6f9c sp=0x2cd6f40 pc=0x4cd7ec Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/grandcat/zeroconf.(*Server).recv4(0x30184c0, 0x2da25a0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x2cd6fe0 sp=0x2cd6f9c pc=0x52407c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x2cd6fec sp=0x2cd6fe0 pc=0x523d34 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cd6fec sp=0x2cd6fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 25 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 131 gp=0x2c03448 m=nil [IO wait]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f928, 0xb60b7a08, 0x2, 0x2, 0x5) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cd7938 sp=0x2cd7924 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.netpollblock(0xb60b79f8, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2cd7950 sp=0x2cd7938 pc=0x540f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.runtime_pollWait(0xb60b79f8, 0x72) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2cd7964 sp=0x2cd7950 pc=0x8eba8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).wait(0x2f84518, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2cd7978 sp=0x2cd7964 pc=0x108108 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).waitRead(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*FD).Read(0x2f84500, {0x2fcb000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2cd79c0 sp=0x2cd7978 pc=0x1092f4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*netFD).Read(0x2f84500, {0x2fcb000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2cd79ec sp=0x2cd79c0 pc=0x2a0610 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*conn).Read(0x2d8c038, {0x2fcb000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2cd7a18 sp=0x2cd79ec pc=0x2b07f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*TCPConn).Read(0x2d8c038, {0x2fcb000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: :1 +0x44 fp=0x2cd7a38 sp=0x2cd7a18 pc=0x2c3a44 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: crypto/tls.(*atLeastReader).Read(0x2f601f0, {0x2fcb000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x2cd7a64 sp=0x2cd7a38 pc=0x386018 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: bytes.(*Buffer).ReadFrom(0x2d2c18c, {0x723a98, 0x2f601f0}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x2cd7aa0 sp=0x2cd7a64 pc=0x136d6c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: crypto/tls.(*Conn).readFromUntil(0x2d2c008, {0x722604, 0x2d8c038}, 0x5) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x2cd7ac8 sp=0x2cd7aa0 pc=0x386274 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: crypto/tls.(*Conn).readRecordOrCCS(0x2d2c008, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x2cd7c2c sp=0x2cd7ac8 pc=0x383974 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: crypto/tls.(*Conn).readRecord(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: crypto/tls.(*Conn).Read(0x2d2c008, {0x2d58000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x2cd7c5c sp=0x2cd7c2c pc=0x3897e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.(*readWriteCloserBody).Read(0x2c90a00, {0x2d58000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2429 +0xec fp=0x2cd7c7c sp=0x2cd7c5c pc=0x4538c0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: bufio.(*Reader).fill(0x3035b90) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x2cd7ca0 sp=0x2cd7c7c pc=0x15a4b8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: bufio.(*Reader).ReadByte(0x3035b90) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x2cd7cac sp=0x2cd7ca0 pc=0x15ad3c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.readFrameHeader(0x3035b90, {0x2d36538, 0x8, 0x8}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2cd7cec sp=0x2cd7cac pc=0x4771e0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2d36508, {0x725688, 0xad3b20}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2cd7dac sp=0x2cd7cec pc=0x479e44 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).readLoop(0x2d36508, {0x725688, 0xad3b20}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2cd7e70 sp=0x2cd7dac pc=0x47992c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).reader(0x2d36508, {0x725688, 0xad3b20}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2cd7efc sp=0x2cd7e70 pc=0x47ab40 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).Read(0x2d36508, {0x725688, 0xad3b20}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2cd7f24 sp=0x2cd7efc pc=0x479210 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/dealer.(*Dealer).recvLoop(0x2d02aa8) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:153 +0x58 fp=0x2cd7fe4 sp=0x2cd7f24 pc=0x4b8a14 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/dealer.(*Dealer).reconnect.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:243 +0x28 fp=0x2cd7fec sp=0x2cd7fe4 pc=0x4b9430 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cd7fec sp=0x2cd7fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by go-librespot/dealer.(*Dealer).reconnect in goroutine 92 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:243 +0x70 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 22 gp=0x2c846c8 m=nil [GC worker (idle), 22 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f7f4, 0x2cb2420, 0x1a, 0xa, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c42790 sp=0x2c4277c pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gcBgMarkWorker() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2c427ec sp=0x2c42790 pc=0x37db4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c427ec sp=0x2c427ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 23 gp=0x2c847e8 m=nil [GC worker (idle), 22 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f7f4, 0x2cb2438, 0x1a, 0xa, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c40790 sp=0x2c4077c pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gcBgMarkWorker() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2c407ec sp=0x2c40790 pc=0x37db4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c407ec sp=0x2c407ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 24 gp=0x2c84908 m=nil [GC worker (idle), 22 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f7f4, 0x2f88678, 0x1a, 0xa, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c40f90 sp=0x2c40f7c pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gcBgMarkWorker() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2c40fec sp=0x2c40f90 pc=0x37db4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c40fec sp=0x2c40fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 8 gp=0x2c03688 m=nil [GC worker (idle)]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f7f4, 0x2c28a80, 0x1a, 0xa, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c44790 sp=0x2c4477c pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gcBgMarkWorker() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2c447ec sp=0x2c44790 pc=0x37db4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c447ec sp=0x2c447ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 74 gp=0x2c03d48 m=nil [IO wait, 21 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f928, 0xb60b78c8, 0x2, 0x2, 0x5) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c548a4 sp=0x2c54890 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.netpollblock(0xb60b78b8, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c548bc sp=0x2c548a4 pc=0x540f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.runtime_pollWait(0xb60b78b8, 0x72) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c548d0 sp=0x2c548bc pc=0x8eba8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).wait(0x303c298, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c548e4 sp=0x2c548d0 pc=0x108108 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).waitRead(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*FD).Read(0x303c280, {0x2fc8000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2c5492c sp=0x2c548e4 pc=0x1092f4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*netFD).Read(0x303c280, {0x2fc8000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2c54958 sp=0x2c5492c pc=0x2a0610 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*conn).Read(0x2c2e208, {0x2fc8000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2c54984 sp=0x2c54958 pc=0x2b07f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*TCPConn).Read(0x2c2e208, {0x2fc8000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: :1 +0x44 fp=0x2c549a4 sp=0x2c54984 pc=0x2c3a44 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: io.(*multiReader).Read(0x2f60130, {0x2fc8000, 0x1000, 0x1000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/multi.go:26 +0xb4 fp=0x2c549d0 sp=0x2c549a4 pc=0x103318 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: bufio.(*Reader).fill(0x2e26240) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x2c549f4 sp=0x2c549d0 pc=0x15a4b8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: bufio.(*Reader).ReadByte(0x2e26240) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x2c54a00 sp=0x2c549f4 pc=0x15ad3c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.readFrameHeader(0x2e26240, {0x2e083f8, 0x8, 0x8}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2c54a40 sp=0x2c54a00 pc=0x4771e0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2e083c8, {0x725688, 0xad3b20}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2c54b00 sp=0x2c54a40 pc=0x479e44 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).readLoop(0x2e083c8, {0x725688, 0xad3b20}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2c54bc4 sp=0x2c54b00 pc=0x47992c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).reader(0x2e083c8, {0x725688, 0xad3b20}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2c54c50 sp=0x2c54bc4 pc=0x47ab40 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).Read(0x2e083c8, {0x725688, 0xad3b20}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2c54c78 sp=0x2c54c50 pc=0x479210 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*ApiServer).serve.func14({0x7253b0, 0x2d02148}, 0x2d02008) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:450 +0x22c fp=0x2c54cd0 sp=0x2c54c78 pc=0x570c88 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.HandlerFunc.ServeHTTP(0x2d8c270, {0x7253b0, 0x2d02148}, 0x2d02008) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c54ce0 sp=0x2c54cd0 pc=0x4395b4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.(*ServeMux).ServeHTTP(0x2da8480, {0x7253b0, 0x2d02148}, 0x2d02008) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x2c54d1c sp=0x2c54ce0 pc=0x43b414 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2d02148}, 0x2d02008) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x2c54d40 sp=0x2c54d1c pc=0x570a44 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.HandlerFunc.ServeHTTP(0x2d9a410, {0x7253b0, 0x2d02148}, 0x2d02008) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c54d50 sp=0x2c54d40 pc=0x4395b4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.serverHandler.ServeHTTP({0x2d95048}, {0x7253b0, 0x2d02148}, 0x2d02008) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x2c54d6c sp=0x2c54d50 pc=0x43cba4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.(*conn).serve(0x301f620, {0x7256a8, 0x2d8e1e0}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x2c54fdc sp=0x2c54d6c pc=0x437df4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.(*Server).Serve.gowrap3() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x2c54fec sp=0x2c54fdc pc=0x43d448 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c54fec sp=0x2c54fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by net/http.(*Server).Serve in goroutine 2 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 13 gp=0x2c84c68 m=nil [IO wait, 22 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f928, 0xb60b7c88, 0x2, 0x2, 0x5) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x307be5c sp=0x307be48 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.netpollblock(0xb60b7c78, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x307be74 sp=0x307be5c pc=0x540f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.runtime_pollWait(0xb60b7c78, 0x72) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x307be88 sp=0x307be74 pc=0x8eba8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).wait(0x30d4b58, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x307be9c sp=0x307be88 pc=0x108108 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).waitRead(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*FD).RawRead(0x30d4b40, 0x30d3620) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x307bec8 sp=0x307be9c pc=0x10d578 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*rawConn).Read(0x304c3b0, 0x30d3620) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x307bee8 sp=0x307bec8 pc=0x2b4a2c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2d9a600, 0x30d35f0, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x307bf18 sp=0x307bee8 pc=0x4c9804 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2da25d8, {0x2d5e000, 0x10000, 0x10000}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv6/payload_cmsg.go:32 +0x2ec fp=0x307bf9c sp=0x307bf18 pc=0x4d1174 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/grandcat/zeroconf.(*Server).recv6(0x30184c0, 0x2da25d0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x307bfe0 sp=0x307bf9c pc=0x524270 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x307bfec sp=0x307bfe0 pc=0x523cd8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x307bfec sp=0x307bfec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 25 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 76 gp=0x2e4e248 m=nil [select, 21 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x30776a8 sp=0x3077694 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.selectgo(0x30777a4, 0x307776c, 0x0, 0x0, 0x5, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x3077748 sp=0x30776a8 pc=0x705e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2e083c8) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x30777e4 sp=0x3077748 pc=0x47408c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.newConn.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x30777ec sp=0x30777e4 pc=0x473c80 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x30777ec sp=0x30777ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by nhooyr.io/websocket.newConn in goroutine 74 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 85 gp=0x2e4e368 m=nil [select]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c52e88 sp=0x2c52e74 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.selectgo(0x2c52fd4, 0x2c52f50, 0x0, 0x0, 0x2, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2c52f28 sp=0x2c52e88 pc=0x705e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/dealer.(*Dealer).pingTicker(0x2d02aa8) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:107 +0x8c fp=0x2c52fe4 sp=0x2c52f28 pc=0x4b85d0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/dealer.NewDealer.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x28 fp=0x2c52fec sp=0x2c52fe4 pc=0x4b80a0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c52fec sp=0x2c52fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by go-librespot/dealer.NewDealer in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x180 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 86 gp=0x2c85568 m=nil [select, 22 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c4167c sp=0x2c41668 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.selectgo(0x2c417cc, 0x2c41744, 0x0, 0x0, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2c4171c sp=0x2c4167c pc=0x705e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/player.(*Player).manageLoop(0x2d1c240) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/player/player.go:109 +0x1e0 fp=0x2c417e4 sp=0x2c4171c pc=0x4a730c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/player.NewPlayer.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x28 fp=0x2c417ec sp=0x2c417e4 pc=0x4a7040 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c417ec sp=0x2c417ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by go-librespot/player.NewPlayer in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x1e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 87 gp=0x2c85688 m=nil [select, 9 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2ccd85c sp=0x2ccd848 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.selectgo(0x2ccde34, 0x2ccda3c, 0x0, 0x0, 0x6, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2ccd8fc sp=0x2ccd85c pc=0x705e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*AppPlayer).Run(0x30d4b90, 0x2c72b80) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:430 +0x1fc fp=0x2ccdfe0 sp=0x2ccd8fc pc=0x57df50 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*App).withAppPlayer.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x30 fp=0x2ccdfec sp=0x2ccdfe0 pc=0x57944c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2ccdfec sp=0x2ccdfec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by main.(*App).withAppPlayer in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x348 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 88 gp=0x2c857a8 m=nil [chan receive, 21 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f7c8, 0x2c721f0, 0xe, 0x7, 0x2) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c44f48 sp=0x2c44f34 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.chanrecv(0x2c721c0, 0x2c44fd0, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x2c44f84 sp=0x2c44f48 pc=0x1fdc4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.chanrecv2(0x2c721c0, 0x2c44fd0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x2c44f98 sp=0x2c44f84 pc=0x1f908 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*App).withAppPlayer.func1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:245 +0x54 fp=0x2c44fec sp=0x2c44f98 pc=0x57937c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c44fec sp=0x2c44fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by main.(*App).withAppPlayer in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:242 +0x3d4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 89 gp=0x2c858c8 m=nil [chan receive, 22 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f7c8, 0x2c72170, 0xe, 0x7, 0x2) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c4573c sp=0x2c45728 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.chanrecv(0x2c72140, 0x2c457e0, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x2c45778 sp=0x2c4573c pc=0x1fdc4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.chanrecv2(0x2c72140, 0x2c457e0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x2c4578c sp=0x2c45778 pc=0x1f908 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: main.(*App).withAppPlayer.func2() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:261 +0x5c fp=0x2c457ec sp=0x2c4578c pc=0x578f90 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c457ec sp=0x2c457ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by main.(*App).withAppPlayer in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:258 +0x45c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 90 gp=0x2c859e8 m=nil [IO wait, 22 minutes]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f928, 0xb60b7dc8, 0x2, 0x2, 0x5) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c45de8 sp=0x2c45dd4 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.netpollblock(0xb60b7db8, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c45e00 sp=0x2c45de8 pc=0x540f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.runtime_pollWait(0xb60b7db8, 0x72) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c45e14 sp=0x2c45e00 pc=0x8eba8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).wait(0x30d4ab8, 0x72, 0x0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c45e28 sp=0x2c45e14 pc=0x108108 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*pollDesc).waitRead(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/poll.(*FD).Accept(0x30d4aa0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2c45e70 sp=0x2c45e28 pc=0x10c974 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*netFD).accept(0x30d4aa0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x2c45ed8 sp=0x2c45e70 pc=0x2a2504 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*TCPListener).accept(0x2cfc180) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x2c45ef0 sp=0x2c45ed8 pc=0x2ba604 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*TCPListener).Accept(0x2cfc180) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x2c45f0c sp=0x2c45ef0 pc=0x2b9670 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.(*onceCloseListener).Accept(0x2f40040) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: :1 +0x34 fp=0x2c45f24 sp=0x2c45f0c pc=0x45e914 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.(*Server).Serve(0x2ca7408, {0x72527c, 0x2cfc180}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x2c45fbc sp=0x2c45f24 pc=0x43cfd0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net/http.Serve(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x8c fp=0x2c45fec sp=0x2c45fbc pc=0x528d7c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c45fec sp=0x2c45fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x160 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 130 gp=0x2f69c28 m=nil [select]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cee6a8 sp=0x2cee694 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.selectgo(0x2cee7a4, 0x2cee76c, 0x0, 0x0, 0x5, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2cee748 sp=0x2cee6a8 pc=0x705e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2d36508) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2cee7e4 sp=0x2cee748 pc=0x47408c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: nhooyr.io/websocket.newConn.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2cee7ec sp=0x2cee7e4 pc=0x473c80 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cee7ec sp=0x2cee7ec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by nhooyr.io/websocket.newConn in goroutine 92 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 79 gp=0x2f8a488 m=nil [select]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c55904 sp=0x2c558f0 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.selectgo(0x2c55abc, 0x2c559d4, 0x0, 0x0, 0x2, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2c559a4 sp=0x2c55904 pc=0x705e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*Resolver).lookupIPAddr(0xaa8890, {0x725708, 0x2f85a90}, {0x664fb6, 0x3}, {0x2f880a8, 0x13}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/lookup.go:342 +0x394 fp=0x2c55ae0 sp=0x2c559a4 pc=0x2ae8b0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*Resolver).internetAddrList(0xaa8890, {0x725708, 0x2f85a90}, {0x664fb6, 0x3}, {0x2f880a8, 0x18}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/ipsock.go:288 +0x6bc fp=0x2c55b74 sp=0x2c55ae0 pc=0x2ac398 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*Resolver).resolveAddrList(0xaa8890, {0x725708, 0x2f85a90}, {0x66538f, 0x4}, {0x664fb6, 0x3}, {0x2f880a8, 0x18}, {0x0, ...}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/dial.go:283 +0x510 fp=0x2c55bfc sp=0x2c55b74 pc=0x296900 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*Dialer).DialContext(0x2c55d14, {0x725688, 0xad3b20}, {0x664fb6, 0x3}, {0x2f880a8, 0x18}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/dial.go:490 +0x43c fp=0x2c55ce4 sp=0x2c55bfc pc=0x297868 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*Dialer).Dial(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/dial.go:434 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.DialTimeout({0x664fb6, 0x3}, {0x2f880a8, 0x18}, 0x6fc23ac00) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/dial.go:416 +0x68 fp=0x2c55d60 sp=0x2c55ce4 pc=0x2973f0 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/ap.(*Accesspoint).init(0x2d025a8) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:89 +0x22c fp=0x2c55e0c sp=0x2c55d60 pc=0x48b974 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/ap.(*Accesspoint).reconnect(0x2d025a8) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:342 +0x28 fp=0x2c55e34 sp=0x2c55e0c pc=0x48d74c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/ap.(*Accesspoint).reconnect-fm() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: :1 +0x28 fp=0x2c55e44 sp=0x2c55e34 pc=0x491338 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:18 +0x1c fp=0x2c55e50 sp=0x2c55e44 pc=0x47fad8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x2c55ef4, {0x724b70, 0x2fa6000}, 0x0, {0x0, 0x0}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:88 +0xe8 fp=0x2c55ecc sp=0x2c55e50 pc=0x47fe84 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x2c55f64, {0x724b70, 0x2fa6000}, 0x0, {0x0, 0x0}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:61 +0x6c fp=0x2c55efc sp=0x2c55ecc pc=0x47fa90 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:49 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: github.com/cenkalti/backoff/v4.Retry(...) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:38 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/ap.(*Accesspoint).recvLoop(0x2d025a8) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:289 +0x38c fp=0x2c55fe4 sp=0x2c55efc pc=0x48d0d8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:353 +0x28 fp=0x2c55fec sp=0x2c55fe4 pc=0x48d9b8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c55fec sp=0x2c55fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by go-librespot/ap.(*Accesspoint).reconnect in goroutine 91 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:353 +0x124 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 132 gp=0x2e4e008 m=nil [select]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cd9b88 sp=0x2cd9b74 pc=0x5c82c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.selectgo(0x2cd9c74, 0x2cd9c4c, 0x0, 0x0, 0x2, 0x1) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2cd9c28 sp=0x2cd9b88 pc=0x705e4 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.doBlockingWithCtx[...]({0x7256c8, 0x2c9ad80}, 0x2cb48a0) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:60 +0x134 fp=0x2cd9c98 sp=0x2cd9c28 pc=0x2c2044 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.cgoLookupIP({0x7256c8, 0x2c9ad80}, {0x664fb6, 0x3}, {0x2f880a8, 0x13}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:216 +0x90 fp=0x2cd9cc0 sp=0x2cd9c98 pc=0x293e58 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*Resolver).lookupIP(0xaa8890, {0x7256c8, 0x2c9ad80}, {0x664fb6, 0x3}, {0x2f880a8, 0x13}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/lookup_unix.go:66 +0x90 fp=0x2cd9efc sp=0x2cd9cc0 pc=0x2aff00 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*Resolver).lookupIP-fm({0x7256c8, 0x2c9ad80}, {0x664fb6, 0x3}, {0x2f880a8, 0x13}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: :1 +0x58 fp=0x2cd9f30 sp=0x2cd9efc pc=0x2c7c7c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.init.func1({0x7256c8, 0x2c9ad80}, 0x2d8c9b8, {0x664fb6, 0x3}, {0x2f880a8, 0x13}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/hook.go:22 +0x4c fp=0x2cd9f60 sp=0x2cd9f30 pc=0x2911b8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.(*Resolver).lookupIPAddr.func1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/lookup.go:334 +0x58 fp=0x2cd9f9c sp=0x2cd9f60 pc=0x2af380 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/singleflight.(*Group).doCall(0xaa8898, 0x2c9adb0, {0x2c28900, 0x17}, 0x2f58960) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x2cd9fd4 sp=0x2cd9f9c pc=0x28f568 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: internal/singleflight.(*Group).DoChan.gowrap1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x2cd9fec sp=0x2cd9fd4 pc=0x28f520 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cd9fec sp=0x2cd9fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by internal/singleflight.(*Group).DoChan in goroutine 79 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/singleflight/singleflight.go:86 +0x2fc Jan 19 19:34:21 volumioraspdac go-librespot[1424]: goroutine 133 gp=0x2e4e128 m=5 mp=0x2c80008 [syscall]: Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.cgocall(0x580bc0, 0x3079ec8) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/cgocall.go:157 +0x50 fp=0x3079eb0 sp=0x3079e98 pc=0x1d56c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net._C2func_getaddrinfo(0x2c28948, 0x0, 0x2f58980, 0x2d8ca08) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: _cgo_gotypes.go:101 +0x48 fp=0x3079ec4 sp=0x3079eb0 pc=0x2c107c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net._C_getaddrinfo.func1(0x2c28948, 0x0, 0x2f58980, 0x2d8ca08) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix_cgo.go:78 +0x7c fp=0x3079ee4 sp=0x3079ec4 pc=0x2c13c8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net._C_getaddrinfo(0x2c28948, 0x0, 0x2f58980, 0x2d8ca08) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix_cgo.go:78 +0x34 fp=0x3079f04 sp=0x3079ee4 pc=0x2c1324 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.cgoLookupHostIP({0x664fb6, 0x3}, {0x2f880a8, 0x13}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:168 +0x248 fp=0x3079f94 sp=0x3079f04 pc=0x293828 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.cgoLookupIP.func1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:217 +0x34 fp=0x3079fbc sp=0x3079f94 pc=0x293ed8 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: net.doBlockingWithCtx[...].func1() Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:56 +0x30 fp=0x3079fec sp=0x3079fbc pc=0x2c1560 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: runtime.goexit({}) Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x3079fec sp=0x3079fec pc=0x9346c Jan 19 19:34:21 volumioraspdac go-librespot[1424]: created by net.doBlockingWithCtx[...] in goroutine 132 Jan 19 19:34:21 volumioraspdac go-librespot[1424]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:54 +0xb8 Jan 19 19:34:21 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:21 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:21 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:21 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:21 volumioraspdac go-librespot[1424]: Aborted Jan 19 19:34:21 volumioraspdac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 19 19:34:21 volumioraspdac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 19:34:21 volumioraspdac volumio[1102]: info: Connection to go-librespot Websocket closed Jan 19 19:34:22 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:22 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:22 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:22 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:23 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:23 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:23 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:23 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:24 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:24 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:24 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:24 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:24 volumioraspdac volumio[1102]: info: Initializing connection to go-librespot Websocket Jan 19 19:34:24 volumioraspdac volumio[1102]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 19:34:24 volumioraspdac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 19:34:24 volumioraspdac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 19 19:34:24 volumioraspdac systemd[1]: Stopped go-librespot Daemon. Jan 19 19:34:24 volumioraspdac systemd[1]: Started go-librespot Daemon. Jan 19 19:34:24 volumioraspdac go-librespot[16409]: Librespot-go daemon starting... Jan 19 19:34:24 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:24+01:00" level=info msg="generated new device id: 6e6b1546d0ff98410bd8e4b58567558981c9fd13" Jan 19 19:34:24 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:24+01:00" level=debug msg="stored credentials found for 11129862073" Jan 19 19:34:25 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:25 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:25 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:25 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:26 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:26 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:26 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:26 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:27 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:27 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:27 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:27 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:27 volumioraspdac volumio[1102]: info: Initializing connection to go-librespot Websocket Jan 19 19:34:27 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:27+01:00" level=debug msg="new websocket client" Jan 19 19:34:27 volumioraspdac volumio[1102]: info: Connection to go-librespot Websocket established Jan 19 19:34:28 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:28 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:28 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:28 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:29 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:29 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:29 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:29 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:30 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:30 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:30 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:30 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:30 volumioraspdac volumio[1102]: info: Getting Spotify volume Jan 19 19:34:30 volumioraspdac volumio[1102]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 19 19:34:30 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:31 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:31 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:31 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:31 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:31 volumioraspdac volumio[1102]: verbose: New Socket.io Connection to 192.168.0.180 from 192.168.0.140 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 19 19:34:31 volumioraspdac volumio[1102]: verbose: New Socket.io Connection to 192.168.0.180 from 192.168.0.140 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 19 19:34:31 volumioraspdac volumio[1102]: verbose: New Socket.io Connection to 192.168.0.180 from 192.168.0.140 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 19 19:34:31 volumioraspdac volumio[1102]: verbose: New Socket.io Connection to 192.168.0.180 from 192.168.0.140 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 19 19:34:31 volumioraspdac volumio[1102]: verbose: New Socket.io Connection to 192.168.0.180 from 192.168.0.140 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 19 19:34:31 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 19:34:31 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:31+01: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]" Jan 19 19:34:31 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:31+01: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]" Jan 19 19:34:31 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:31+01:00" level=debug msg="zeroconf server listening on port 39139" Jan 19 19:34:32 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:32+01:00" level=debug msg="obtained new client token: AAAgnuWofA0QWVkLeMlbyIqhiGmZ1x6qE6TjrZPgnrOZKQ1cUV2CiFaLUDFmHOK3hZoK2tsfb9iTMVVHN4hY1c9EgGTbTdhk52nudQ8vt7fw6SJiAa+n68g5PCl9+m7QCy3In/SfRvxJRrVkMYs3aYVFacYGFDocKzcx9s6cKOVnxtIihyfqi/ll6dOP4QOeYMLw0FFpf8Wppmzm+JalMqwhpTvfVp3wjvyg8vzeezljbvqfCbQJZqn7Rqo=" Jan 19 19:34:32 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:32+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 19:34:32 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:32+01:00" level=debug msg="completed keyexchange" Jan 19 19:34:32 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:32 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:32 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:32 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:32 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:32+01:00" level=debug msg="completed challenge" Jan 19 19:34:33 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:33 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:33 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:33 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:34 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:34 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:34 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:34 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:35 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:35 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:35 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:35 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:36 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:36 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:36 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:36 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:36 volumioraspdac volumio[1102]: verbose: New Socket.io Connection to 192.168.0.180 from 192.168.0.140 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jan 19 19:34:37 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:37 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:37 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:37 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:38 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:38 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:38 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:38 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="authenticated as 11129862073" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="authenticated as 11129862073" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="dealer connection opened" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="initializing zeroconf session, username: 11129862073" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="autoplay enabled: false" Jan 19 19:34:38 volumioraspdac volumio[1102]: info: Spotify volume: 100 Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="received connection id: ZGZmOGY5MTgtMjIyYS00NDY1LThlN2EtNTVjMjFhYjMyYTFlK2RlYWxlcit0Y3A6Ly8wYWNhNTllMi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQjU1QzcyMzEyMDc0NThENDEwNDBDQ0E4N0ZCQTU1NENEODlGRDRCMjU0MzE2QzQzQkE1MTQxMjAwMkFBMEZFRg==" Jan 19 19:34:38 volumioraspdac go-librespot[16409]: time="2025-01-19T19:34:38+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 19 19:34:39 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:39 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:39 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:39 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:40 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:40 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:40 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:40 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:41 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:41 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:41 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:41 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:42 volumioraspdac volumio[1102]: verbose: New Socket.io Connection to 192.168.0.180 from 192.168.0.140 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetVisibleSources Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:42 volumioraspdac volumio[1102]: info: Listing playlists Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 19 19:34:42 volumioraspdac volumio[1102]: info: Received Get System Info Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 19:34:42 volumioraspdac volumio[1102]: info: Discovery: Getting this device information Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:42 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 19 19:34:43 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:43 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:43 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:43 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:44 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:44 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:44 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:44 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:45 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:45 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:45 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:45 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:46 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:46 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:46 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:46 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:47 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:47 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:47 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:47 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:48 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:48 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:48 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:48 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:49 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:49 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:49 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:49 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:50 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:50 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:50 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:50 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:51 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:51 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:51 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:51 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:52 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:52 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:52 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:52 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:53 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:53 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:53 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:53 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:54 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:54 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:54 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:54 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:55 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:55 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:55 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:55 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:56 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:56 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:56 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:56 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:57 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:57 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:57 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:57 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:58 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:58 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:58 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:58 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:34:59 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:34:59 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:34:59 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:34:59 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:00 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:00 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:00 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:00 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:01 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:01 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:01 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:01 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:01 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 19:35:01 volumioraspdac volumio[1102]: info: Preload queue cleared Jan 19 19:35:02 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:02 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:02 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:02 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:03 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 19:35:03 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:03 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:03 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:03 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:03 volumioraspdac volumio[1102]: info: Preload queue cleared Jan 19 19:35:04 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:04 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:04 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:04 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:05 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:05 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:05 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:05 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:05 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 19:35:05 volumioraspdac volumio[1102]: info: Preload queue cleared Jan 19 19:35:06 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:06 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:06 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:06 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:07 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:07 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:07 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:07 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:07 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 19:35:07 volumioraspdac volumio[1102]: info: Preload queue cleared Jan 19 19:35:07 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:07 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:07 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:07 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:07 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:07 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:08 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:08 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:08 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:08 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:08 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:08 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:08 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:08 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:08 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:08 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:09 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:09 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:09 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:09 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:09 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:09 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:09 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:09 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:09 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:09 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:09 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:10 volumioraspdac volumio[1102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 19:35:10 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:10 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:10 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:10 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:11 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:11 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:11 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:11 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:12 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:12 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:12 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:12 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:13 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:13 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:13 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:13 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:14 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:14 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:14 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:14 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:15 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:15 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:15 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:15 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:15 volumioraspdac volumio[1102]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 19:35:16 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 19 19:35:16 volumioraspdac volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 19 19:35:16 volumioraspdac volumio[1102]: info: CoreStateMachine::getQueue Jan 19 19:35:16 volumioraspdac volumio[1102]: info: CorePlayQueue::getQueue Jan 19 19:35:16 volumioraspdac volumio[1102]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 19:35:16 volumioraspdac volumio[1102]: TypeError: Cannot read property 'length' of undefined Jan 19 19:35:16 volumioraspdac volumio[1102]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 19 19:35:16 volumioraspdac volumio[1102]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 19 19:35:16 volumioraspdac volumio[1102]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 19 19:35:16 volumioraspdac volumio[1102]: at Parser.emit (events.js:315:20) Jan 19 19:35:16 volumioraspdac volumio[1102]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 19 19:35:16 volumioraspdac volumio[1102]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 19 19:35:16 volumioraspdac volumio[1102]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 19 19:35:16 volumioraspdac volumio[1102]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 19 19:35:16 volumioraspdac volumio[1102]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 19 19:35:16 volumioraspdac volumio[1102]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 19 19:35:16 volumioraspdac volumio[1102]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 19 19:35:16 volumioraspdac volumio[1102]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 19 19:35:16 volumioraspdac volumio[1102]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 19 19:35:16 volumioraspdac volumio[1102]: at IncomingMessage.emit (events.js:327:22) Jan 19 19:35:16 volumioraspdac volumio[1102]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 19 19:35:16 volumioraspdac volumio[1102]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 19 19:35:16 volumioraspdac volumio[1102]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 19:35:17 volumioraspdac sudo[17022]: volumio : unable to resolve host volumioraspdac Jan 19 19:35:17 volumioraspdac sudo[17022]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 19 19:35:17 volumioraspdac sudo[17022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 19:34 Jan 19 19:35:17 volumioraspdac sudo[17022]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"