-- Logs begin at Wed 2025-10-29 21:40:16 UTC, end at Mon 2025-11-03 21:23:18 UTC. -- Nov 03 21:22:16 volumio go-librespot[1215]: time="2025-11-03T21:22:16Z" level=trace msg="sent dealer ping" Nov 03 21:22:16 volumio go-librespot[1215]: time="2025-11-03T21:22:16Z" level=trace msg="received dealer pong" Nov 03 21:22:20 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 03 21:22:20 volumio volumio[912]: info: Preload queue cleared Nov 03 21:22:29 volumio volumio[912]: info: CoreCommandRouter::volumioNext Nov 03 21:22:29 volumio volumio[912]: info: CoreStateMachine::next Nov 03 21:22:29 volumio volumio[912]: info: Spotify next Nov 03 21:22:29 volumio volumio[912]: info: Sending Spotify command to local API: /player/next Nov 03 21:22:29 volumio go-librespot[1215]: panic: runtime error: invalid memory address or nil pointer dereference Nov 03 21:22:29 volumio go-librespot[1215]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x20 pc=0x517804] Nov 03 21:22:29 volumio go-librespot[1215]: goroutine 414077 gp=0x3348248 m=3 mp=0x3048b08 [running]: Nov 03 21:22:29 volumio go-librespot[1215]: panic({0x6cfb00, 0xca02c0}) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/panic.go:779 +0x12c fp=0x3831568 sp=0x3831514 pc=0x58bf8 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.panicmem(...) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/panic.go:261 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.sigpanic() Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/signal_unix.go:881 +0x39c fp=0x3831598 sp=0x3831568 pc=0x74be4 Nov 03 21:22:29 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/tracks.(*List).AllTracks(0x0, {0x8874f0, 0xd0ad00}) Nov 03 21:22:29 volumio go-librespot[1215]: /src/tracks/tracks.go:78 +0x14 fp=0x38315f8 sp=0x383159c pc=0x517804 Nov 03 21:22:29 volumio go-librespot[1215]: main.(*AppPlayer).advanceNext(0x330ad20, {0x8874f0, 0xd0ad00}, 0x1, 0x1) Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/controls.go:604 +0x3cc fp=0x38316b8 sp=0x38315f8 pc=0x63aef0 Nov 03 21:22:29 volumio go-librespot[1215]: main.(*AppPlayer).skipNext(0x330ad20, {0x8874f0, 0xd0ad00}, 0x0) Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/controls.go:545 +0x4dc fp=0x3831754 sp=0x38316b8 pc=0x63a978 Nov 03 21:22:29 volumio go-librespot[1215]: main.(*AppPlayer).handleApiRequest(0x330ad20, {0x8874f0, 0xd0ad00}, {{0x791346, 0x4}, {0x6c9bb0, 0x0}, 0x329eac0}) Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/player.go:478 +0x12c fp=0x38318e4 sp=0x3831754 pc=0x643810 Nov 03 21:22:29 volumio go-librespot[1215]: main.(*AppPlayer).Run(0x330ad20, {0x8874f0, 0xd0ad00}, 0x320bc80) Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/player.go:610 +0x864 fp=0x3831fd8 sp=0x38318e4 pc=0x645db0 Nov 03 21:22:29 volumio go-librespot[1215]: main.(*App).withAppPlayer.func2.gowrap1() Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/main.go:309 +0x40 fp=0x3831fec sp=0x3831fd8 pc=0x63e700 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3831fec sp=0x3831fec pc=0x93d6c Nov 03 21:22:29 volumio go-librespot[1215]: created by main.(*App).withAppPlayer.func2 in goroutine 61 Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/main.go:309 +0x2c8 Nov 03 21:22:29 volumio go-librespot[1215]: goroutine 1 gp=0x3002128 m=nil [select, 14675 minutes]: Nov 03 21:22:29 volumio go-librespot[1215]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3055d00 sp=0x3055cec pc=0x5d0c0 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.selectgo(0x3055e00, 0x3113dcc, 0x0, 0x0, 0x2, 0x1) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x3055da0 sp=0x3055d00 pc=0x70e78 Nov 03 21:22:29 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x310af60, 0x3055e90) Nov 03 21:22:29 volumio go-librespot[1215]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x3055e44 sp=0x3055da0 pc=0x5a8088 Nov 03 21:22:29 volumio go-librespot[1215]: main.(*App).withAppPlayer(0x30bcb00, {0x8874f0, 0xd0ad00}, 0x300e100) Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/main.go:321 +0x554 fp=0x3055ed0 sp=0x3055e44 pc=0x63db54 Nov 03 21:22:29 volumio go-librespot[1215]: main.(*App).withCredentials(...) Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/main.go:193 Nov 03 21:22:29 volumio go-librespot[1215]: main.(*App).SpotifyToken(0x30bcb00, {0x8874f0, 0xd0ad00}, {0x30ba6a0, 0x19}, {0x309c280, 0x133}) Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x3055f00 sp=0x3055ed0 pc=0x63cfa8 Nov 03 21:22:29 volumio go-librespot[1215]: main.main() Nov 03 21:22:29 volumio go-librespot[1215]: /src/cmd/daemon/main.go:557 +0x588 fp=0x3055fa8 sp=0x3055f00 pc=0x63feb4 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.main() Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x3055fec sp=0x3055fa8 pc=0x5cb30 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3055fec sp=0x3055fec pc=0x93d6c Nov 03 21:22:29 volumio go-librespot[1215]: goroutine 18 gp=0x3084248 m=nil [force gc (idle), 2 minutes]: Nov 03 21:22:29 volumio go-librespot[1215]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x30407d4 sp=0x30407c0 pc=0x5d0c0 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.goparkunlock(...) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:408 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.forcegchelper() Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x30407ec sp=0x30407d4 pc=0x5cefc Nov 03 21:22:29 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x30407ec sp=0x30407ec pc=0x93d6c Nov 03 21:22:29 volumio go-librespot[1215]: created by runtime.init.5 in goroutine 1 Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:314 +0x1c Nov 03 21:22:29 volumio go-librespot[1215]: goroutine 19 gp=0x3084368 m=nil [GC sweep wait]: Nov 03 21:22:29 volumio go-librespot[1215]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3040fc4 sp=0x3040fb0 pc=0x5d0c0 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.goparkunlock(...) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:408 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.bgsweep(0x3092000) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x3040fe4 sp=0x3040fc4 pc=0x45848 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.gcenable.gowrap1() Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x3040fec sp=0x3040fe4 pc=0x35968 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3040fec sp=0x3040fec pc=0x93d6c Nov 03 21:22:29 volumio go-librespot[1215]: created by runtime.gcenable in goroutine 1 Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Nov 03 21:22:29 volumio go-librespot[1215]: goroutine 20 gp=0x3084488 m=nil [GC scavenge wait]: Nov 03 21:22:29 volumio go-librespot[1215]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Nov 03 21:22:29 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x30417b4 sp=0x30417a0 pc=0x5d0c0 Nov 03 21:22:29 volumio go-librespot[1215]: runtime.goparkunlock(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:408 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.(*scavengerState).park(0xce0208) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x30417c8 sp=0x30417b4 pc=0x42b68 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.bgscavenge(0x3092000) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x30417e4 sp=0x30417c8 pc=0x432a0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gcenable.gowrap2() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x30417ec sp=0x30417e4 pc=0x35914 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x30417ec sp=0x30417ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by runtime.gcenable in goroutine 1 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 21 gp=0x30845a8 m=nil [finalizer wait, 601 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3041f8c sp=0x3041f78 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.runfinq() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x3041fec sp=0x3041f8c pc=0x34754 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3041fec sp=0x3041fec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by runtime.createfing in goroutine 1 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 24 gp=0x30859e8 m=nil [IO wait]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e40f0, 0xb60a7f08, 0x2, 0x2, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x34a7d28 sp=0x34a7d14 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.netpollblock(0xb60a7ef8, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x34a7d40 sp=0x34a7d28 pc=0x548cc Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.runtime_pollWait(0xb60a7ef8, 0x72) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x34a7d54 sp=0x34a7d40 pc=0x8f4a8 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).wait(0x309f2d8, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x34a7d68 sp=0x34a7d54 pc=0x10a318 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).waitRead(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*FD).Accept(0x309f2c0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x34a7db0 sp=0x34a7d68 pc=0x10eb84 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*netFD).accept(0x309f2c0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x34a7e18 sp=0x34a7db0 pc=0x17e4c4 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*TCPListener).accept(0x30a6690) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x34a7e30 sp=0x34a7e18 pc=0x196900 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*TCPListener).Accept(0x30a6690) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x34a7e4c sp=0x34a7e30 pc=0x19596c Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*onceCloseListener).Accept(0x3097ec0) Nov 03 21:22:30 volumio go-librespot[1215]: :1 +0x34 fp=0x34a7e64 sp=0x34a7e4c pc=0x362b54 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*Server).Serve(0x31df868, {0x886cb8, 0x30a6690}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x34a7efc sp=0x34a7e64 pc=0x341210 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.Serve(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2794 Nov 03 21:22:30 volumio go-librespot[1215]: main.(*ConcreteApiServer).serve(0x30d25a0) Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x34a7fe4 sp=0x34a7efc pc=0x6330b8 Nov 03 21:22:30 volumio go-librespot[1215]: main.NewApiServer.gowrap1() Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x34a7fec sp=0x34a7fe4 pc=0x631de4 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x34a7fec sp=0x34a7fec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by main.NewApiServer in goroutine 1 Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/api_server.go:296 +0x310 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 27 gp=0x3085b08 m=nil [GC worker (idle), 3228 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e3fbc, 0x3220030, 0x1a, 0xa, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3044790 sp=0x304477c pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gcBgMarkWorker() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x30447ec sp=0x3044790 pc=0x38590 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x30447ec sp=0x30447ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 7 gp=0x3003448 m=nil [GC worker (idle), 2 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e3fbc, 0x33d4b28, 0x1a, 0xa, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3045790 sp=0x304577c pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gcBgMarkWorker() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x30457ec sp=0x3045790 pc=0x38590 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x30457ec sp=0x30457ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 28 gp=0x344a128 m=nil [GC worker (idle), 6 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e3fbc, 0x33d4b40, 0x1a, 0xa, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3042f90 sp=0x3042f7c pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gcBgMarkWorker() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x3042fec sp=0x3042f90 pc=0x38590 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3042fec sp=0x3042fec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 29 gp=0x344a248 m=nil [GC worker (idle), 685 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e3fbc, 0x33d4b58, 0x1a, 0xa, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3043790 sp=0x304377c pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gcBgMarkWorker() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x30437ec sp=0x3043790 pc=0x38590 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x30437ec sp=0x30437ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 8 gp=0x344a368 m=nil [IO wait]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e40f0, 0xb60a7d28, 0x2, 0x2, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x34a4e84 sp=0x34a4e70 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.netpollblock(0xb60a7d18, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x34a4e9c sp=0x34a4e84 pc=0x548cc Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.runtime_pollWait(0xb60a7d18, 0x72) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x34a4eb0 sp=0x34a4e9c pc=0x8f4a8 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).wait(0x30286f8, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x34a4ec4 sp=0x34a4eb0 pc=0x10a318 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).waitRead(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*FD).RawRead(0x30286e0, 0x337a900) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x34a4ef0 sp=0x34a4ec4 pc=0x10f788 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*rawConn).Read(0x3031a58, 0x337a900) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x34a4f10 sp=0x34a4ef0 pc=0x190d28 Nov 03 21:22:30 volumio go-librespot[1215]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x32def00, 0x337a8d0, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x34a4f40 sp=0x34a4f10 pc=0x548824 Nov 03 21:22:30 volumio go-librespot[1215]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Nov 03 21:22:30 volumio go-librespot[1215]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x3152b78, {0x34c0000, 0x10000, 0x10000}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x34a4f9c sp=0x34a4f40 pc=0x54c80c Nov 03 21:22:30 volumio go-librespot[1215]: github.com/grandcat/zeroconf.(*Server).recv4(0x33f9540, 0x3152b70) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x34a4fe0 sp=0x34a4f9c pc=0x5a309c Nov 03 21:22:30 volumio go-librespot[1215]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x34a4fec sp=0x34a4fe0 pc=0x5a2d54 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x34a4fec sp=0x34a4fec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 33 Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 9 gp=0x344a488 m=nil [IO wait, 14675 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e40f0, 0xb60a7c88, 0x2, 0x2, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x315f65c sp=0x315f648 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.netpollblock(0xb60a7c78, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x315f674 sp=0x315f65c pc=0x548cc Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.runtime_pollWait(0xb60a7c78, 0x72) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x315f688 sp=0x315f674 pc=0x8f4a8 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).wait(0x3028748, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x315f69c sp=0x315f688 pc=0x10a318 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).waitRead(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*FD).RawRead(0x3028730, 0x34bc030) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x315f6c8 sp=0x315f69c pc=0x10f788 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*rawConn).Read(0x3031a80, 0x34bc030) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x315f6e8 sp=0x315f6c8 pc=0x190d28 Nov 03 21:22:30 volumio go-librespot[1215]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x32def10, 0x34bc000, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x315f718 sp=0x315f6e8 pc=0x548824 Nov 03 21:22:30 volumio go-librespot[1215]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Nov 03 21:22:30 volumio go-librespot[1215]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x3152ba8, {0x34ac000, 0x10000, 0x10000}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x315f79c sp=0x315f718 pc=0x550194 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/grandcat/zeroconf.(*Server).recv6(0x33f9540, 0x3152ba0) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x315f7e0 sp=0x315f79c pc=0x5a3290 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x315f7ec sp=0x315f7e0 pc=0x5a2cf8 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x315f7ec sp=0x315f7ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 33 Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 60 gp=0x3400b48 m=nil [chan receive]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e3f90, 0x30921b0, 0xe, 0x7, 0x2) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x315cf48 sp=0x315cf34 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.chanrecv(0x3092180, 0x315cfd0, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x315cf84 sp=0x315cf48 pc=0x20078 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.chanrecv2(0x3092180, 0x315cfd0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x315cf98 sp=0x315cf84 pc=0x1fbbc Nov 03 21:22:30 volumio go-librespot[1215]: main.(*App).withAppPlayer.func1() Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/main.go:268 +0x68 fp=0x315cfec sp=0x315cf98 pc=0x63e794 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x315cfec sp=0x315cfec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by main.(*App).withAppPlayer in goroutine 1 Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/main.go:265 +0x464 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 61 gp=0x3400c68 m=nil [chan receive, 13070 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e3f90, 0x3092170, 0xe, 0x7, 0x2) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x352ff40 sp=0x352ff2c pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.chanrecv(0x3092140, 0x364dfe0, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x352ff7c sp=0x352ff40 pc=0x20078 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.chanrecv2(0x3092140, 0x364dfe0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x352ff90 sp=0x352ff7c pc=0x1fbbc Nov 03 21:22:30 volumio go-librespot[1215]: main.(*App).withAppPlayer.func2() Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/main.go:284 +0x6c fp=0x352ffec sp=0x352ff90 pc=0x63e330 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x352ffec sp=0x352ffec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by main.(*App).withAppPlayer in goroutine 1 Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/main.go:281 +0x504 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 62 gp=0x3400d88 m=nil [IO wait, 6 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e40f0, 0xb60a7dc8, 0x2, 0x2, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x315dde8 sp=0x315ddd4 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.netpollblock(0xb60a7db8, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x315de00 sp=0x315dde8 pc=0x548cc Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.runtime_pollWait(0xb60a7db8, 0x72) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x315de14 sp=0x315de00 pc=0x8f4a8 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).wait(0x30286a8, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x315de28 sp=0x315de14 pc=0x10a318 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).waitRead(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*FD).Accept(0x3028690) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x315de70 sp=0x315de28 pc=0x10eb84 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*netFD).accept(0x3028690) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x315ded8 sp=0x315de70 pc=0x17e4c4 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*TCPListener).accept(0x3147e90) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x315def0 sp=0x315ded8 pc=0x196900 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*TCPListener).Accept(0x3147e90) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x315df0c sp=0x315def0 pc=0x19596c Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*onceCloseListener).Accept(0x341a460) Nov 03 21:22:30 volumio go-librespot[1215]: :1 +0x34 fp=0x315df24 sp=0x315df0c pc=0x362b54 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*Server).Serve(0x30a5a48, {0x886cb8, 0x3147e90}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x315dfbc sp=0x315df24 pc=0x341210 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.Serve(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2794 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Nov 03 21:22:30 volumio go-librespot[1215]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x315dfec sp=0x315dfbc pc=0x5a8184 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x315dfec sp=0x315dfec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Nov 03 21:22:30 volumio go-librespot[1215]: /src/zeroconf/zeroconf.go:280 +0x160 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 65 gp=0x3400fc8 m=nil [IO wait, 14675 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e40f0, 0xb60a78c8, 0x2, 0x2, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x347387c sp=0x3473868 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.netpollblock(0xb60a78b8, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x3473894 sp=0x347387c pc=0x548cc Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.runtime_pollWait(0xb60a78b8, 0x72) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x34738a8 sp=0x3473894 pc=0x8f4a8 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).wait(0x340e108, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x34738bc sp=0x34738a8 pc=0x10a318 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).waitRead(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*FD).Read(0x340e0f0, {0x3291000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x3473904 sp=0x34738bc pc=0x10b504 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*netFD).Read(0x340e0f0, {0x3291000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x3473930 sp=0x3473904 pc=0x17c5d0 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*conn).Read(0x33644b0, {0x3291000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x347395c sp=0x3473930 pc=0x18caec Nov 03 21:22:30 volumio go-librespot[1215]: net.(*TCPConn).Read(0x33644b0, {0x3291000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: :1 +0x44 fp=0x347397c sp=0x347395c pc=0x19fd40 Nov 03 21:22:30 volumio go-librespot[1215]: io.(*multiReader).Read(0x33978b0, {0x3291000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x34739a8 sp=0x347397c pc=0x105528 Nov 03 21:22:30 volumio go-librespot[1215]: bufio.(*Reader).fill(0x3312900) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x34739cc sp=0x34739a8 pc=0x2bddb4 Nov 03 21:22:30 volumio go-librespot[1215]: bufio.(*Reader).ReadByte(0x3312900) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x34739d8 sp=0x34739cc pc=0x2be638 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.readFrameHeader(0x3312900, {0x329a038, 0x8, 0x8}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x3473a18 sp=0x34739d8 pc=0x47788c Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x329a008, {0x8874d0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x3473ad8 sp=0x3473a18 pc=0x47a4f0 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).readLoop(0x329a008, {0x8874d0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x3473b9c sp=0x3473ad8 pc=0x479fd8 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).reader(0x329a008, {0x8874d0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x3473c28 sp=0x3473b9c pc=0x47b1ec Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).Reader(...) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).Read(0x329a008, {0x8874d0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x3473c50 sp=0x3473c28 pc=0x4798bc Nov 03 21:22:30 volumio go-librespot[1215]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x30a5368}, 0x30a4be8) Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x3473ccc sp=0x3473c50 pc=0x633988 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.HandlerFunc.ServeHTTP(0x3095a20, {0x886dec, 0x30a5368}, 0x30a4be8) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x3473cdc sp=0x3473ccc pc=0x33d7f4 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*ServeMux).ServeHTTP(0x30bcb80, {0x886dec, 0x30a5368}, 0x30a4be8) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x3473d18 sp=0x3473cdc pc=0x33f654 Nov 03 21:22:30 volumio go-librespot[1215]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x30a5368}, 0x30a4be8) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x3473d40 sp=0x3473d18 pc=0x633388 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.HandlerFunc.ServeHTTP(0x3091550, {0x886dec, 0x30a5368}, 0x30a4be8) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x3473d50 sp=0x3473d40 pc=0x33d7f4 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.serverHandler.ServeHTTP({0x31df868}, {0x886dec, 0x30a5368}, 0x30a4be8) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x3473d6c sp=0x3473d50 pc=0x340de4 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*conn).serve(0x310a300, {0x887510, 0x30a6828}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x3473fdc sp=0x3473d6c pc=0x33c034 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*Server).Serve.gowrap3() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x3473fec sp=0x3473fdc pc=0x341688 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3473fec sp=0x3473fec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by net/http.(*Server).Serve in goroutine 24 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:3285 +0x468 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 99 gp=0x347c128 m=nil [select, 13070 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x315e6a8 sp=0x315e694 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.selectgo(0x315e7a4, 0x315e76c, 0x0, 0x0, 0x5, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x315e748 sp=0x315e6a8 pc=0x70e78 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x329a008) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x315e7e4 sp=0x315e748 pc=0x474738 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.newConn.gowrap1() Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x315e7ec sp=0x315e7e4 pc=0x47432c Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x315e7ec sp=0x315e7ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by nhooyr.io/websocket.newConn in goroutine 65 Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 414076 gp=0x347c368 m=nil [select]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3176674 sp=0x3176660 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.selectgo(0x3176774, 0x317673c, 0x0, 0x0, 0x3, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x3176714 sp=0x3176674 pc=0x70e78 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x309f6d0) Nov 03 21:22:30 volumio go-librespot[1215]: /src/player/player.go:201 +0x1fc fp=0x31767e4 sp=0x3176714 pc=0x513660 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Nov 03 21:22:30 volumio go-librespot[1215]: /src/player/player.go:183 +0x28 fp=0x31767ec sp=0x31767e4 pc=0x513240 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x31767ec sp=0x31767ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 61 Nov 03 21:22:30 volumio go-librespot[1215]: /src/player/player.go:183 +0x218 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 414855 gp=0x3311688 m=nil [IO wait]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e40f0, 0xb60a7be8, 0x2, 0x2, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x34a8940 sp=0x34a892c pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.netpollblock(0xb60a7bd8, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x34a8958 sp=0x34a8940 pc=0x548cc Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.runtime_pollWait(0xb60a7bd8, 0x72) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x34a896c sp=0x34a8958 pc=0x8f4a8 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).wait(0x3431418, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x34a8980 sp=0x34a896c pc=0x10a318 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).waitRead(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*FD).Read(0x3431400, {0x3250000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x34a89c8 sp=0x34a8980 pc=0x10b504 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*netFD).Read(0x3431400, {0x3250000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x34a89f4 sp=0x34a89c8 pc=0x17c5d0 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*conn).Read(0x33646b8, {0x3250000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x34a8a20 sp=0x34a89f4 pc=0x18caec Nov 03 21:22:30 volumio go-librespot[1215]: net.(*TCPConn).Read(0x33646b8, {0x3250000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: :1 +0x44 fp=0x34a8a40 sp=0x34a8a20 pc=0x19fd40 Nov 03 21:22:30 volumio go-librespot[1215]: crypto/tls.(*atLeastReader).Read(0x33fc0f0, {0x3250000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x34a8a6c sp=0x34a8a40 pc=0x27c680 Nov 03 21:22:30 volumio go-librespot[1215]: bytes.(*Buffer).ReadFrom(0x30b818c, {0x883674, 0x33fc0f0}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x34a8aa8 sp=0x34a8a6c pc=0x139bd0 Nov 03 21:22:30 volumio go-librespot[1215]: crypto/tls.(*Conn).readFromUntil(0x30b8008, {0x882848, 0x33646b8}, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x34a8ad0 sp=0x34a8aa8 pc=0x27c8dc Nov 03 21:22:30 volumio go-librespot[1215]: crypto/tls.(*Conn).readRecordOrCCS(0x30b8008, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x34a8c34 sp=0x34a8ad0 pc=0x279fdc Nov 03 21:22:30 volumio go-librespot[1215]: crypto/tls.(*Conn).readRecord(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/crypto/tls/conn.go:588 Nov 03 21:22:30 volumio go-librespot[1215]: crypto/tls.(*Conn).Read(0x30b8008, {0x327e000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x34a8c64 sp=0x34a8c34 pc=0x27fe4c Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*readWriteCloserBody).Read(0x321c7c0, {0x327e000, 0x1000, 0x1000}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/transport.go:2429 +0xec fp=0x34a8c84 sp=0x34a8c64 pc=0x357b00 Nov 03 21:22:30 volumio go-librespot[1215]: bufio.(*Reader).fill(0x4167740) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x34a8ca8 sp=0x34a8c84 pc=0x2bddb4 Nov 03 21:22:30 volumio go-librespot[1215]: bufio.(*Reader).ReadByte(0x4167740) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x34a8cb4 sp=0x34a8ca8 pc=0x2be638 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.readFrameHeader(0x4167740, {0x3218cb8, 0x8, 0x8}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x34a8cf4 sp=0x34a8cb4 pc=0x47788c Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x3218c88, {0x8874d0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x34a8db4 sp=0x34a8cf4 pc=0x47a4f0 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).readLoop(0x3218c88, {0x8874d0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x34a8e78 sp=0x34a8db4 pc=0x479fd8 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).reader(0x3218c88, {0x8874d0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x34a8f04 sp=0x34a8e78 pc=0x47b1ec Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).Reader(...) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).Read(0x3218c88, {0x8874d0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x34a8f2c sp=0x34a8f04 pc=0x4798bc Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x32aa798) Nov 03 21:22:30 volumio go-librespot[1215]: /src/dealer/dealer.go:188 +0x58 fp=0x34a8fe4 sp=0x34a8f2c pc=0x531d64 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Nov 03 21:22:30 volumio go-librespot[1215]: /src/dealer/dealer.go:296 +0x28 fp=0x34a8fec sp=0x34a8fe4 pc=0x5329bc Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x34a8fec sp=0x34a8fec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 414668 Nov 03 21:22:30 volumio go-librespot[1215]: /src/dealer/dealer.go:296 +0xf4 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 414101 gp=0x3433d48 m=nil [select]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x305be98 sp=0x305be84 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.selectgo(0x305bfd4, 0x305bf60, 0x0, 0x0, 0x2, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x305bf38 sp=0x305be98 pc=0x70e78 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x32aa798) Nov 03 21:22:30 volumio go-librespot[1215]: /src/dealer/dealer.go:138 +0x8c fp=0x305bfe4 sp=0x305bf38 pc=0x5318dc Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Nov 03 21:22:30 volumio go-librespot[1215]: /src/dealer/dealer.go:129 +0x28 fp=0x305bfec sp=0x305bfe4 pc=0x5337e8 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x305bfec sp=0x305bfec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 414077 Nov 03 21:22:30 volumio go-librespot[1215]: /src/dealer/dealer.go:129 +0x15c Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 414883 gp=0x3307328 m=nil [chan receive]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e3f90, 0x329eaf0, 0xe, 0x7, 0x2) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x34aabb0 sp=0x34aab9c pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.chanrecv(0x329eac0, 0x34aac7c, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x34aabec sp=0x34aabb0 pc=0x20078 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.chanrecv1(0x329eac0, 0x34aac7c) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x34aac00 sp=0x34aabec pc=0x1fb98 Nov 03 21:22:30 volumio go-librespot[1215]: main.(*ConcreteApiServer).handleRequest(0x30d25a0, {{0x791346, 0x4}, {0x6c9bb0, 0x0}, 0x329eac0}, {0x886dec, 0x3126648}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/api_server.go:319 +0x74 fp=0x34aac8c sp=0x34aac00 pc=0x631f94 Nov 03 21:22:30 volumio go-librespot[1215]: main.(*ConcreteApiServer).serve.func8({0x886dec, 0x3126648}, 0x38bc468) Nov 03 21:22:30 volumio go-librespot[1215]: /src/cmd/daemon/api_server.go:450 +0x12c fp=0x34aaccc sp=0x34aac8c pc=0x6347cc Nov 03 21:22:30 volumio go-librespot[1215]: net/http.HandlerFunc.ServeHTTP(0x3095948, {0x886dec, 0x3126648}, 0x38bc468) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x34aacdc sp=0x34aaccc pc=0x33d7f4 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*ServeMux).ServeHTTP(0x30bcb80, {0x886dec, 0x3126648}, 0x38bc468) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x34aad18 sp=0x34aacdc pc=0x33f654 Nov 03 21:22:30 volumio go-librespot[1215]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x3126648}, 0x38bc468) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x34aad40 sp=0x34aad18 pc=0x633388 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.HandlerFunc.ServeHTTP(0x3091550, {0x886dec, 0x3126648}, 0x38bc468) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x34aad50 sp=0x34aad40 pc=0x33d7f4 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.serverHandler.ServeHTTP({0x31df868}, {0x886dec, 0x3126648}, 0x38bc468) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x34aad6c sp=0x34aad50 pc=0x340de4 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*conn).serve(0x330c000, {0x887510, 0x30a6828}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x34aafdc sp=0x34aad6c pc=0x33c034 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*Server).Serve.gowrap3() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x34aafec sp=0x34aafdc pc=0x341688 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x34aafec sp=0x34aafec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by net/http.(*Server).Serve in goroutine 24 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:3285 +0x468 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 414099 gp=0x3489328 m=nil [select, 2 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3059eb8 sp=0x3059ea4 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.selectgo(0x3059fd4, 0x3059f7c, 0x0, 0x0, 0x2, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x3059f58 sp=0x3059eb8 pc=0x70e78 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x30f0c68) Nov 03 21:22:30 volumio go-librespot[1215]: /src/ap/ap.go:355 +0x8c fp=0x3059fe4 sp=0x3059f58 pc=0x48cb50 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Nov 03 21:22:30 volumio go-librespot[1215]: /src/ap/ap.go:264 +0x28 fp=0x3059fec sp=0x3059fe4 pc=0x48c248 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3059fec sp=0x3059fec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 414077 Nov 03 21:22:30 volumio go-librespot[1215]: /src/ap/ap.go:264 +0x15c Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 414836 gp=0x348c488 m=nil [IO wait, 2 minutes]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e40f0, 0xb60a7788, 0x2, 0x2, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x34a6d14 sp=0x34a6d00 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.netpollblock(0xb60a7778, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x34a6d2c sp=0x34a6d14 pc=0x548cc Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.runtime_pollWait(0xb60a7778, 0x72) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x34a6d40 sp=0x34a6d2c pc=0x8f4a8 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).wait(0x3431558, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x34a6d54 sp=0x34a6d40 pc=0x10a318 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).waitRead(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*FD).Read(0x3431540, {0x38e80ec, 0x3, 0x3}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x34a6d9c sp=0x34a6d54 pc=0x10b504 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*netFD).Read(0x3431540, {0x38e80ec, 0x3, 0x3}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x34a6dc8 sp=0x34a6d9c pc=0x17c5d0 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*conn).Read(0x3094c88, {0x38e80ec, 0x3, 0x3}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x34a6df4 sp=0x34a6dc8 pc=0x18caec Nov 03 21:22:30 volumio go-librespot[1215]: net.(*TCPConn).Read(0x3094c88, {0x38e80ec, 0x3, 0x3}) Nov 03 21:22:30 volumio go-librespot[1215]: :1 +0x44 fp=0x34a6e14 sp=0x34a6df4 pc=0x19fd40 Nov 03 21:22:30 volumio go-librespot[1215]: io.ReadAtLeast({0x882848, 0x3094c88}, {0x38e80ec, 0x3, 0x3}, 0x3) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x34a6e40 sp=0x34a6e14 pc=0x1042c0 Nov 03 21:22:30 volumio go-librespot[1215]: io.ReadFull(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/io/io.go:354 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x390b480, {0x8874f0, 0xd0ad00}) Nov 03 21:22:30 volumio go-librespot[1215]: /src/ap/shannon.go:124 +0x3a0 fp=0x34a6f04 sp=0x34a6e40 pc=0x48faf4 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x30f0c68) Nov 03 21:22:30 volumio go-librespot[1215]: /src/ap/ap.go:276 +0x68 fp=0x34a6fe4 sp=0x34a6f04 pc=0x48c330 Nov 03 21:22:30 volumio go-librespot[1215]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Nov 03 21:22:30 volumio go-librespot[1215]: /src/ap/ap.go:390 +0x28 fp=0x34a6fec sp=0x34a6fe4 pc=0x48d010 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x34a6fec sp=0x34a6fec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 414603 Nov 03 21:22:30 volumio go-librespot[1215]: /src/ap/ap.go:390 +0x120 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 414854 gp=0x3140d88 m=nil [select]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x34626a8 sp=0x3462694 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.selectgo(0x34627a4, 0x346276c, 0x0, 0x0, 0x5, 0x1) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x3462748 sp=0x34626a8 pc=0x70e78 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x3218c88) Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x34627e4 sp=0x3462748 pc=0x474738 Nov 03 21:22:30 volumio go-librespot[1215]: nhooyr.io/websocket.newConn.gowrap1() Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x34627ec sp=0x34627e4 pc=0x47432c Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x34627ec sp=0x34627ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by nhooyr.io/websocket.newConn in goroutine 414668 Nov 03 21:22:30 volumio go-librespot[1215]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Nov 03 21:22:30 volumio go-librespot[1215]: goroutine 414901 gp=0x3242368 m=nil [IO wait]: Nov 03 21:22:30 volumio go-librespot[1215]: runtime.gopark(0x7e40f0, 0xb60a7828, 0x2, 0x2, 0x5) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x33bc6b4 sp=0x33bc6a0 pc=0x5d0c0 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.netpollblock(0xb60a7818, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x33bc6cc sp=0x33bc6b4 pc=0x548cc Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.runtime_pollWait(0xb60a7818, 0x72) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x33bc6e0 sp=0x33bc6cc pc=0x8f4a8 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).wait(0x34309c8, 0x72, 0x0) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x33bc6f4 sp=0x33bc6e0 pc=0x10a318 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*pollDesc).waitRead(...) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Nov 03 21:22:30 volumio go-librespot[1215]: internal/poll.(*FD).Read(0x34309b0, {0x3d3000d, 0x1, 0x1}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x33bc73c sp=0x33bc6f4 pc=0x10b504 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*netFD).Read(0x34309b0, {0x3d3000d, 0x1, 0x1}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x33bc768 sp=0x33bc73c pc=0x17c5d0 Nov 03 21:22:30 volumio go-librespot[1215]: net.(*conn).Read(0x3514000, {0x3d3000d, 0x1, 0x1}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x33bc794 sp=0x33bc768 pc=0x18caec Nov 03 21:22:30 volumio go-librespot[1215]: net.(*TCPConn).Read(0x3514000, {0x3d3000d, 0x1, 0x1}) Nov 03 21:22:30 volumio go-librespot[1215]: :1 +0x44 fp=0x33bc7b4 sp=0x33bc794 pc=0x19fd40 Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*connReader).backgroundRead(0x3d30000) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:681 +0x40 fp=0x33bc7e4 sp=0x33bc7b4 pc=0x335cfc Nov 03 21:22:30 volumio go-librespot[1215]: net/http.(*connReader).startBackgroundRead.gowrap2() Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:677 +0x28 fp=0x33bc7ec sp=0x33bc7e4 pc=0x335c34 Nov 03 21:22:30 volumio go-librespot[1215]: runtime.goexit({}) Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x33bc7ec sp=0x33bc7ec pc=0x93d6c Nov 03 21:22:30 volumio go-librespot[1215]: created by net/http.(*connReader).startBackgroundRead in goroutine 414883 Nov 03 21:22:30 volumio go-librespot[1215]: /usr/local/go/src/net/http/server.go:677 +0x160 Nov 03 21:22:30 volumio volumio[912]: info: Connection to go-librespot Websocket closed Nov 03 21:22:30 volumio go-librespot[1215]: Aborted Nov 03 21:22:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Nov 03 21:22:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 03 21:22:30 volumio volumio[912]: error: Failed to send command to Spotify local API: /player/next: Error: socket hang up Nov 03 21:22:30 volumio volumio[912]: info: CoreCommandRouter::volumioNext Nov 03 21:22:30 volumio volumio[912]: info: CoreStateMachine::next Nov 03 21:22:30 volumio volumio[912]: info: Spotify next Nov 03 21:22:30 volumio volumio[912]: info: Sending Spotify command to local API: /player/next Nov 03 21:22:30 volumio volumio[912]: error: Failed to send command to Spotify local API: /player/next: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 03 21:22:33 volumio volumio[912]: info: Initializing connection to go-librespot Websocket Nov 03 21:22:33 volumio volumio[912]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 03 21:22:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 03 21:22:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Nov 03 21:22:33 volumio systemd[1]: Stopped go-librespot Daemon. Nov 03 21:22:33 volumio systemd[1]: Started go-librespot Daemon. Nov 03 21:22:33 volumio go-librespot[8782]: go-librespot daemon starting... Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=info msg="running go-librespot 0.4.0" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="app state loaded" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" 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]" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" 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]" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" 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]" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=info msg="zeroconf server listening on port 40615" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="obtained new client token: AADZiHrBdKPwrM0CitocWOVeROTBvwvYt7hsZoR/zL5c4RohBQXs+15yAiBjZASGKkTJjnV81q6qKdjnKLR8BzfasE3JRFmFupJcvvqnm2VvtgxZpTqypWdjEVmQSwLxgeb8CraJJealHBFa78HyaoJ4pq7xmlBvA3b6rlIwnp7mwbwIGT+etU51UJZmhOtpO2KdByQRSB9F70Ww+udiKsUORc9AkH103YQdNQf/561S69wojxDbP7nSqg==" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="completed keyexchange" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="completed challenge" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=info msg="authenticated AP" username="k2*********************g3" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=info msg="authenticated Login5" username="k2*********************g3" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="initializing zeroconf session" username="k2*********************g3" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="dealer connection opened" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=trace msg="starting accesspoint recv loop" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=trace msg="starting dealer recv loop" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=trace msg="received accesspoint ping" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=debug msg="received connection id: YmI5OTNiMzItY2U2...NDU0OUI2OTdERg==" Nov 03 21:22:33 volumio go-librespot[8782]: time="2025-11-03T21:22:33Z" level=trace msg="received accesspoint pong ack" Nov 03 21:22:34 volumio go-librespot[8782]: time="2025-11-03T21:22:34Z" level=debug msg="put connect state because NEW_DEVICE" Nov 03 21:22:36 volumio volumio[912]: info: Initializing connection to go-librespot Websocket Nov 03 21:22:36 volumio go-librespot[8782]: time="2025-11-03T21:22:36Z" level=debug msg="new websocket client" Nov 03 21:22:36 volumio volumio[912]: info: Connection to go-librespot Websocket established Nov 03 21:22:39 volumio volumio[912]: info: Getting Spotify volume Nov 03 21:22:39 volumio volumio[912]: info: Spotify volume: 100 Nov 03 21:22:39 volumio volumio[912]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 03 21:22:39 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Nov 03 21:22:39 volumio volumio[912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 8 Nov 03 21:22:39 volumio volumio[912]: SPOTIFY: SPOTIFY VOLUME 100 Nov 03 21:22:39 volumio volumio[912]: SPOTIFY: VOLUMIO VOLUME 8 Nov 03 21:22:39 volumio volumio[912]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 03 21:22:39 volumio volumio[912]: info: Setting Spotify Volume from Volumio: 8 Nov 03 21:22:40 volumio volumio[912]: SPOTIFY: SETTING SPOTIFY VOLUME 8 Nov 03 21:22:40 volumio volumio[912]: info: Sending Spotify command with payload to local API: /player/volume Nov 03 21:22:40 volumio go-librespot[8782]: time="2025-11-03T21:22:40Z" level=debug msg="update volume requested to 5242/65535" Nov 03 21:22:40 volumio go-librespot[8782]: time="2025-11-03T21:22:40Z" level=debug msg="put connect state because VOLUME_CHANGED" Nov 03 21:22:40 volumio go-librespot[8782]: time="2025-11-03T21:22:40Z" level=trace msg="emitting websocket event: volume" Nov 03 21:22:40 volumio volumio[912]: SPOTIFY: received: {"type":"volume","data":{"value":8,"max":100}} Nov 03 21:22:40 volumio volumio[912]: SPOTIFY: RECEIVED SPOTIFY VOLUME 8 Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:22:47 volumio volumio[912]: info: Retrieving Cloud Streaming UI Nov 03 21:22:47 volumio volumio[912]: info: Getting Tidal Cloud Configuration Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:22:47 volumio volumio[912]: info: Getting Qobuz Cloud Configuration Nov 03 21:22:47 volumio volumio[912]: info: Asking plugin for UI Config Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:22:47 volumio volumio[912]: info: Getting Spotify Cloud Configuration Nov 03 21:22:47 volumio volumio[912]: info: Asking plugin for UI Config Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:22:47 volumio volumio[912]: info: Saving Spotify Acccount Nov 03 21:22:47 volumio volumio[912]: info: Got Tidal Cloud Configuration Nov 03 21:22:47 volumio volumio[912]: info: Got it Nov 03 21:22:47 volumio volumio[912]: info: Got it Nov 03 21:22:47 volumio volumio[912]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:22:47 volumio volumio[912]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 21:22:47 volumio volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 21:22:48 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 03 21:22:48 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 03 21:22:52 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 03 21:22:59 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 21:22:59 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 03 21:22:59 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:22:59 volumio volumio[912]: info: FusionDsp - no preset used Nov 03 21:23:00 volumio volumio[912]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,1.json Nov 03 21:23:03 volumio go-librespot[8782]: time="2025-11-03T21:23:03Z" level=trace msg="sent dealer ping" Nov 03 21:23:03 volumio go-librespot[8782]: time="2025-11-03T21:23:03Z" level=trace msg="received dealer pong" Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:23:05 volumio volumio[912]: info: Retrieving Cloud Streaming UI Nov 03 21:23:05 volumio volumio[912]: info: Getting Tidal Cloud Configuration Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:23:05 volumio volumio[912]: info: Getting Qobuz Cloud Configuration Nov 03 21:23:05 volumio volumio[912]: info: Asking plugin for UI Config Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:23:05 volumio volumio[912]: info: Getting Spotify Cloud Configuration Nov 03 21:23:05 volumio volumio[912]: info: Asking plugin for UI Config Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:23:05 volumio volumio[912]: info: Saving Spotify Acccount Nov 03 21:23:05 volumio volumio[912]: info: Got Tidal Cloud Configuration Nov 03 21:23:05 volumio volumio[912]: info: Got it Nov 03 21:23:05 volumio volumio[912]: info: Got it Nov 03 21:23:05 volumio volumio[912]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 21:23:05 volumio volumio[912]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 21:23:05 volumio volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 21:23:06 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 03 21:23:06 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 03 21:23:13 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 03 21:23:16 volumio volumio[912]: info: Preload queue cleared Nov 03 21:23:17 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 03 21:23:17 volumio volumio[912]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 21:23:17 volumio volumio[912]: TypeError: Cannot read property 'length' of undefined Nov 03 21:23:17 volumio volumio[912]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 03 21:23:17 volumio volumio[912]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 03 21:23:17 volumio volumio[912]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 03 21:23:17 volumio volumio[912]: at Parser.emit (events.js:315:20) Nov 03 21:23:17 volumio volumio[912]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 03 21:23:17 volumio volumio[912]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 03 21:23:17 volumio volumio[912]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 03 21:23:17 volumio volumio[912]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 03 21:23:17 volumio volumio[912]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 03 21:23:17 volumio volumio[912]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 03 21:23:17 volumio volumio[912]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 03 21:23:17 volumio volumio[912]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 03 21:23:17 volumio volumio[912]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 03 21:23:17 volumio volumio[912]: at IncomingMessage.emit (events.js:327:22) Nov 03 21:23:17 volumio volumio[912]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 03 21:23:17 volumio volumio[912]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 03 21:23:17 volumio volumio[912]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 21:23:18 volumio sudo[8929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 21:22 Nov 03 21:23:18 volumio sudo[8929]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"