-- Logs begin at Wed 2025-01-15 00:59:29 GMT, end at Wed 2025-01-15 07:23:27 GMT. -- Jan 15 07:22:02 kitchen volumio[25712]: info: CoreCommandRouter::volumioGetState Jan 15 07:22:02 kitchen volumio[25712]: info: CorePlayQueue::getTrack 0 Jan 15 07:22:02 kitchen volumio[25712]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 15 07:22:02 kitchen volumio[25712]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 15 07:22:02 kitchen volumio[25712]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 15 07:22:02 kitchen volumio[25712]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 15 07:22:02 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:02 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 15 07:22:02 kitchen volumio[25712]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 15 07:22:02 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:02 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 15 07:22:02 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:02 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:02 kitchen volumio[25712]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 15 07:22:02 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:02 kitchen volumio[25712]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 15 07:22:02 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 15 07:22:02 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:02 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:12 kitchen volumio[25712]: info: CoreCommandRouter::volumioGetState Jan 15 07:22:12 kitchen volumio[25712]: info: CorePlayQueue::getTrack 0 Jan 15 07:22:12 kitchen volumio[25712]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 15 07:22:12 kitchen volumio[25712]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 15 07:22:12 kitchen volumio[25712]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 15 07:22:12 kitchen volumio[25712]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 15 07:22:12 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:12 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 15 07:22:12 kitchen volumio[25712]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 15 07:22:12 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:12 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 15 07:22:12 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:12 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:12 kitchen volumio[25712]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 15 07:22:12 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:12 kitchen volumio[25712]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 15 07:22:12 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 15 07:22:12 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:12 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:12 kitchen volumio[25712]: info: Listing playlists Jan 15 07:22:12 kitchen volumio[25712]: info: Listing playlists Jan 15 07:22:22 kitchen volumio[25712]: info: CoreCommandRouter::volumioGetState Jan 15 07:22:22 kitchen volumio[25712]: info: CorePlayQueue::getTrack 0 Jan 15 07:22:22 kitchen volumio[25712]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 15 07:22:22 kitchen volumio[25712]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 15 07:22:22 kitchen volumio[25712]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 15 07:22:22 kitchen volumio[25712]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 15 07:22:22 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:22 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 15 07:22:22 kitchen volumio[25712]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 15 07:22:22 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:22 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 15 07:22:22 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:22 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:22 kitchen volumio[25712]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 15 07:22:22 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:22 kitchen volumio[25712]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 15 07:22:22 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 15 07:22:22 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:22 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:32 kitchen volumio[25712]: info: CoreCommandRouter::volumioGetState Jan 15 07:22:32 kitchen volumio[25712]: info: CorePlayQueue::getTrack 0 Jan 15 07:22:32 kitchen volumio[25712]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 15 07:22:32 kitchen volumio[25712]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 15 07:22:32 kitchen volumio[25712]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 15 07:22:32 kitchen volumio[25712]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 15 07:22:32 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:32 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 15 07:22:32 kitchen volumio[25712]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 15 07:22:32 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:32 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 15 07:22:32 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:32 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:32 kitchen volumio[25712]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 15 07:22:32 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:32 kitchen volumio[25712]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 15 07:22:32 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 15 07:22:32 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:32 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:32 kitchen volumio[25712]: info: Listing playlists Jan 15 07:22:32 kitchen volumio[25712]: info: Listing playlists Jan 15 07:22:42 kitchen volumio[25712]: info: CoreCommandRouter::volumioGetState Jan 15 07:22:42 kitchen volumio[25712]: info: CorePlayQueue::getTrack 0 Jan 15 07:22:42 kitchen volumio[25712]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 15 07:22:42 kitchen volumio[25712]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 15 07:22:42 kitchen volumio[25712]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 15 07:22:42 kitchen volumio[25712]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 15 07:22:42 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:42 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 15 07:22:42 kitchen volumio[25712]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 15 07:22:42 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:42 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 15 07:22:42 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:42 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:42 kitchen volumio[25712]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 15 07:22:42 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:42 kitchen volumio[25712]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 15 07:22:42 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 15 07:22:42 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:42 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:52 kitchen volumio[25712]: info: CoreCommandRouter::volumioGetState Jan 15 07:22:52 kitchen volumio[25712]: info: CorePlayQueue::getTrack 0 Jan 15 07:22:52 kitchen volumio[25712]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 15 07:22:52 kitchen volumio[25712]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 15 07:22:52 kitchen volumio[25712]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 15 07:22:52 kitchen volumio[25712]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 15 07:22:52 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:52 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 15 07:22:52 kitchen volumio[25712]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 15 07:22:52 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:52 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 15 07:22:52 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:52 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:52 kitchen volumio[25712]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 15 07:22:52 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:22:52 kitchen volumio[25712]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 15 07:22:52 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 15 07:22:52 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:22:52 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:22:52 kitchen volumio[25712]: info: Listing playlists Jan 15 07:22:52 kitchen volumio[25712]: info: Listing playlists Jan 15 07:23:00 kitchen go-librespot[25890]: time="2025-01-15T07:23:00Z" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusGoingAway and reason = \"\"" Jan 15 07:23:00 kitchen go-librespot[25890]: time="2025-01-15T07:23:00Z" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.178.105:55158->104.199.65.9:443: use of closed network connection" Jan 15 07:23:00 kitchen go-librespot[25890]: panic: close of closed channel Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 52680 [running]: Jan 15 07:23:00 kitchen go-librespot[25890]: panic({0x5a01d0, 0x6c51e8}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:987 +0x414 fp=0x1c4cf04 sp=0x1c4cea8 pc=0x4ee28 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.closechan(0x21763c0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/chan.go:365 +0x2c8 fp=0x1c4cf20 sp=0x1c4cf04 pc=0x1ab38 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/ap.(*Accesspoint).recvLoop(0x1d9e240) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:291 +0x480 fp=0x1c4cfe4 sp=0x1c4cf20 pc=0x454b74 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/ap.(*Accesspoint).reconnect.func1() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:335 +0x28 fp=0x1c4cfec sp=0x1c4cfe4 pc=0x4552a8 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c4cfec sp=0x1c4cfec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by go-librespot/ap.(*Accesspoint).reconnect Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:335 +0x168 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 1 [runnable]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.mapiterinit(0x5fb558, 0x1e306e0, 0x1c51aa4) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/map.go:815 +0x288 fp=0x1c51a64 sp=0x1c51a64 pc=0x241ac Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.Header.Clone(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/header.go:106 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.cloneOrMakeHeader(0x1e306e0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/clone.go:69 +0x104 fp=0x1c51ad4 sp=0x1c51a64 pc=0x3bb02c Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*Client).makeHeadersCopier(0xa2e6d0, 0x1df2a00) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/client.go:745 +0x20 fp=0x1c51b1c sp=0x1c51ad4 pc=0x3b9230 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*Client).do(0xa2e6d0, 0x1df2a00) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/client.go:603 +0x208 fp=0x1c51c34 sp=0x1c51b1c pc=0x3b8180 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*Client).Do(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/client.go:582 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/session.retrieveClientToken({0x1cbe240, 0x28}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/session/client_token.go:41 +0x718 fp=0x1c51ce8 sp=0x1c51c34 pc=0x483ec8 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/session.NewSessionFromOptions(0x1c51dcc) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/session/session.go:51 +0x1ec fp=0x1c51d90 sp=0x1c51ce8 pc=0x484628 Jan 15 07:23:00 kitchen go-librespot[25890]: main.(*App).newAppPlayer(0x1c91fc0, {0x5a8590, 0x1cb1590}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:74 +0x108 fp=0x1c51dec sp=0x1c51d90 pc=0x5345c8 Jan 15 07:23:00 kitchen go-librespot[25890]: main.(*App).Zeroconf.func2({{0x1ec1518, 0x19}, {0x1cae6e0, 0x158, 0x158}, {0x1ec1833, 0x8}, 0x1ef2200}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:134 +0x13c fp=0x1c51e60 sp=0x1c51dec pc=0x534b64 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/zeroconf.(*Zeroconf).Serve(0x204a140, 0x1c51f3c) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:266 +0x1a8 fp=0x1c51f04 sp=0x1c51e60 pc=0x4e64c8 Jan 15 07:23:00 kitchen go-librespot[25890]: main.(*App).Zeroconf(0x1c91fc0) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:125 +0x280 fp=0x1c51f64 sp=0x1c51f04 pc=0x5349d8 Jan 15 07:23:00 kitchen go-librespot[25890]: main.main() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:315 +0x6dc fp=0x1c51fc0 sp=0x1c51f64 pc=0x5360ac Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.main() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:250 +0x268 fp=0x1c51fec sp=0x1c51fc0 pc=0x52288 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c51fec sp=0x1c51fec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 2 [force gc (idle), 3 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63eab4, 0xa2e130, 0x11, 0x14, 0x1) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c3cfd8 sp=0x1c3cfc4 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goparkunlock(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:387 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.forcegchelper() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:305 +0xd4 fp=0x1c3cfec sp=0x1c3cfd8 pc=0x525d0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c3cfec sp=0x1c3cfec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by runtime.init.5 Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:293 +0x1c Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 18 [GC sweep wait]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63eab4, 0xa2e730, 0xc, 0x14, 0x1) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c387c8 sp=0x1c387b4 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goparkunlock(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:387 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.bgsweep(0x1c62000) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcsweep.go:319 +0x104 fp=0x1c387e4 sp=0x1c387c8 pc=0x3c528 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gcenable.func1() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:178 +0x28 fp=0x1c387ec sp=0x1c387e4 pc=0x2cff4 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c387ec sp=0x1c387ec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by runtime.gcenable Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:178 +0x74 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 19 [GC scavenge wait]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63eab4, 0xa2ea10, 0xd, 0x14, 0x2) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c38fb4 sp=0x1c38fa0 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goparkunlock(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:387 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.(*scavengerState).park(0xa2ea10) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcscavenge.go:400 +0x64 fp=0x1c38fc8 sp=0x1c38fb4 pc=0x39e1c Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.bgscavenge(0x1c62000) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcscavenge.go:633 +0x64 fp=0x1c38fe4 sp=0x1c38fc8 pc=0x3a560 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gcenable.func2() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:179 +0x28 fp=0x1c38fec sp=0x1c38fe4 pc=0x2cfa0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c38fec sp=0x1c38fec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by runtime.gcenable Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:179 +0xbc Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 20 [finalizer wait, 751 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63e9f4, 0xa3fad4, 0x10, 0x14, 0x1) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c3c78c sp=0x1c3c778 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.runfinq() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mfinal.go:193 +0xfc fp=0x1c3c7ec sp=0x1c3c78c pc=0x2bed0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c3c7ec sp=0x1c3c7ec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by runtime.createfing Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mfinal.go:163 +0x5c Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 21 [IO wait, 755 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea9c, 0x6d2c3f40, 0x2, 0x1b, 0x5) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c395dc sp=0x1c395c8 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.netpollblock(0x6d2c3f34, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x1c395f4 sp=0x1c395dc pc=0x49f2c Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.runtime_pollWait(0x6d2c3f34, 0x72) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x1c39608 sp=0x1c395f4 pc=0x800b4 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).wait(0x1ca0b54, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c3961c sp=0x1c39608 pc=0xf7db0 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).waitRead(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*FD).Accept(0x1ca0b40) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:614 +0x2d0 fp=0x1c3966c sp=0x1c3961c pc=0xfc8d0 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*netFD).accept(0x1ca0b40) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/fd_unix.go:172 +0x20 fp=0x1c396cc sp=0x1c3966c pc=0x2816d8 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*TCPListener).accept(0x1caa500) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/tcpsock_posix.go:148 +0x20 fp=0x1c396e4 sp=0x1c396cc pc=0x29a070 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*TCPListener).Accept(0x1caa500) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/tcpsock.go:297 +0x30 fp=0x1c39700 sp=0x1c396e4 pc=0x299034 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*onceCloseListener).Accept(0x1d82020) Jan 15 07:23:00 kitchen go-librespot[25890]: :1 +0x34 fp=0x1c39718 sp=0x1c39700 pc=0x427088 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*Server).Serve(0x1d8c000, {0x6c8508, 0x1caa500}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3059 +0x344 fp=0x1c397b4 sp=0x1c39718 pc=0x403080 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.Serve(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2581 Jan 15 07:23:00 kitchen go-librespot[25890]: main.(*ApiServer).serve(0x1c939c0) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:429 +0x62c fp=0x1c397e4 sp=0x1c397b4 pc=0x52f4ec Jan 15 07:23:00 kitchen go-librespot[25890]: main.NewApiServer.func1() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:228 +0x28 fp=0x1c397ec sp=0x1c397e4 pc=0x52eba4 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c397ec sp=0x1c397ec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by main.NewApiServer Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:228 +0x1dc Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 33 [IO wait]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea9c, 0x6d2c3d6c, 0x2, 0x1b, 0x5) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1e86e8c sp=0x1e86e78 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.netpollblock(0x6d2c3d60, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x1e86ea4 sp=0x1e86e8c pc=0x49f2c Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.runtime_pollWait(0x6d2c3d60, 0x72) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x1e86eb8 sp=0x1e86ea4 pc=0x800b4 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).wait(0x204a1f4, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1e86ecc sp=0x1e86eb8 pc=0xf7db0 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).waitRead(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*FD).RawRead(0x204a1e0, 0x1c26e10) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:766 +0x168 fp=0x1e86ef8 sp=0x1e86ecc pc=0xfdcb4 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*rawConn).Read(0x1d883a8, 0x1c26e10) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/rawconn.go:43 +0x38 fp=0x1e86f18 sp=0x1e86ef8 pc=0x294908 Jan 15 07:23:00 kitchen go-librespot[25890]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1d86390, 0x1c26de0, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x19c fp=0x1e86f48 sp=0x1e86f18 pc=0x485d60 Jan 15 07:23:00 kitchen go-librespot[25890]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Jan 15 07:23:00 kitchen go-librespot[25890]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2070098, {0x2142000, 0x10000, 0x10000}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv4/payload_cmsg.go:32 +0x1a4 fp=0x1e86f9c sp=0x1e86f48 pc=0x48942c Jan 15 07:23:00 kitchen go-librespot[25890]: github.com/grandcat/zeroconf.(*Server).recv4(0x1d8e5c0, 0x2070090) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1e86fe0 sp=0x1e86f9c pc=0x4e1688 Jan 15 07:23:00 kitchen go-librespot[25890]: github.com/grandcat/zeroconf.(*Server).mainloop.func1() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1e86fec sp=0x1e86fe0 pc=0x4e133c Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1e86fec sp=0x1e86fec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by github.com/grandcat/zeroconf.(*Server).mainloop Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x80 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 50 [IO wait, 3715 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea9c, 0x6d2c3cd0, 0x2, 0x1b, 0x5) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c3d664 sp=0x1c3d650 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.netpollblock(0x6d2c3cc4, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x1c3d67c sp=0x1c3d664 pc=0x49f2c Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.runtime_pollWait(0x6d2c3cc4, 0x72) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x1c3d690 sp=0x1c3d67c pc=0x800b4 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).wait(0x204a244, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c3d6a4 sp=0x1c3d690 pc=0xf7db0 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).waitRead(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*FD).RawRead(0x204a230, 0x1d80c90) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:766 +0x168 fp=0x1c3d6d0 sp=0x1c3d6a4 pc=0xfdcb4 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*rawConn).Read(0x1d883d0, 0x1d80c90) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/rawconn.go:43 +0x38 fp=0x1c3d6f0 sp=0x1c3d6d0 pc=0x294908 Jan 15 07:23:00 kitchen go-librespot[25890]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1d863a0, 0x1d80c60, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x19c fp=0x1c3d720 sp=0x1c3d6f0 pc=0x485d60 Jan 15 07:23:00 kitchen go-librespot[25890]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Jan 15 07:23:00 kitchen go-librespot[25890]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x20700c8, {0x20ac000, 0x10000, 0x10000}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv6/payload_cmsg.go:32 +0x17c fp=0x1c3d79c sp=0x1c3d720 pc=0x48d4a8 Jan 15 07:23:00 kitchen go-librespot[25890]: github.com/grandcat/zeroconf.(*Server).recv6(0x1d8e5c0, 0x20700c0) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1c3d7e0 sp=0x1c3d79c pc=0x4e1874 Jan 15 07:23:00 kitchen go-librespot[25890]: github.com/grandcat/zeroconf.(*Server).mainloop.func2() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1c3d7ec sp=0x1c3d7e0 pc=0x4e12e0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c3d7ec sp=0x1c3d7ec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by github.com/grandcat/zeroconf.(*Server).mainloop Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xf0 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 52 [select, 755 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63eae4, 0x0, 0x9, 0x18, 0x1) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c39ea0 sp=0x1c39e8c pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.selectgo(0x1c39fbc, 0x1c39f74, 0x0, 0x0, 0x5, 0x1) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/select.go:327 +0xb70 fp=0x1c39f48 sp=0x1c39ea0 pc=0x64d80 Jan 15 07:23:00 kitchen go-librespot[25890]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x20aa120) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x110 fp=0x1c39fe4 sp=0x1c39f48 pc=0x43cbf8 Jan 15 07:23:00 kitchen go-librespot[25890]: nhooyr.io/websocket.newConn.func2() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1c39fec sp=0x1c39fe4 pc=0x43c7ec Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c39fec sp=0x1c39fec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by nhooyr.io/websocket.newConn Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x5b0 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 27 [GC worker (idle), 3644 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea00, 0x1ffa900, 0x1a, 0x14, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c3a790 sp=0x1c3a77c pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gcBgMarkWorker() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1275 +0x100 fp=0x1c3a7ec sp=0x1c3a790 pc=0x2fbb8 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c3a7ec sp=0x1c3a7ec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by runtime.gcBgMarkStartWorkers Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 28 [GC worker (idle), 357 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea00, 0x1ffa918, 0x1a, 0x14, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c3af90 sp=0x1c3af7c pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gcBgMarkWorker() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1275 +0x100 fp=0x1c3afec sp=0x1c3af90 pc=0x2fbb8 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c3afec sp=0x1c3afec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by runtime.gcBgMarkStartWorkers Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 29 [GC worker (idle), 908 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea00, 0x1ffa930, 0x1a, 0x14, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c3b790 sp=0x1c3b77c pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gcBgMarkWorker() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1275 +0x100 fp=0x1c3b7ec sp=0x1c3b790 pc=0x2fbb8 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c3b7ec sp=0x1c3b7ec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by runtime.gcBgMarkStartWorkers Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 30 [GC worker (idle), 3 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea00, 0x1ffa948, 0x1a, 0x14, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c3bf90 sp=0x1c3bf7c pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gcBgMarkWorker() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1275 +0x100 fp=0x1c3bfec sp=0x1c3bf90 pc=0x2fbb8 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c3bfec sp=0x1c3bfec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by runtime.gcBgMarkStartWorkers Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 40 [IO wait, 3715 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea9c, 0x6d2c3c34, 0x2, 0x1b, 0x5) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1c4b880 sp=0x1c4b86c pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.netpollblock(0x6d2c3c28, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x1c4b898 sp=0x1c4b880 pc=0x49f2c Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.runtime_pollWait(0x6d2c3c28, 0x72) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x1c4b8ac sp=0x1c4b898 pc=0x800b4 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).wait(0x204a294, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c4b8c0 sp=0x1c4b8ac pc=0xf7db0 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).waitRead(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*FD).Read(0x204a280, {0x20c4000, 0x1000, 0x1000}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:167 +0x240 fp=0x1c4b910 sp=0x1c4b8c0 pc=0xf8fbc Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*netFD).Read(0x204a280, {0x20c4000, 0x1000, 0x1000}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/fd_posix.go:55 +0x38 fp=0x1c4b93c sp=0x1c4b910 pc=0x27f61c Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*conn).Read(0x1d88828, {0x20c4000, 0x1000, 0x1000}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/net.go:183 +0x48 fp=0x1c4b96c sp=0x1c4b93c pc=0x2905a0 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*TCPConn).Read(0x1d88828, {0x20c4000, 0x1000, 0x1000}) Jan 15 07:23:00 kitchen go-librespot[25890]: :1 +0x44 fp=0x1c4b98c sp=0x1c4b96c pc=0x2a4c7c Jan 15 07:23:00 kitchen go-librespot[25890]: io.(*multiReader).Read(0x1caa9d0, {0x20c4000, 0x1000, 0x1000}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/io/multi.go:26 +0xac fp=0x1c4b9c0 sp=0x1c4b98c pc=0xf2de0 Jan 15 07:23:00 kitchen go-librespot[25890]: bufio.(*Reader).fill(0x1d819e0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/bufio/bufio.go:106 +0x10c fp=0x1c4b9e4 sp=0x1c4b9c0 pc=0x144f14 Jan 15 07:23:00 kitchen go-librespot[25890]: bufio.(*Reader).ReadByte(0x1d819e0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/bufio/bufio.go:265 +0x28 fp=0x1c4b9f0 sp=0x1c4b9e4 pc=0x1457c4 Jan 15 07:23:00 kitchen go-librespot[25890]: nhooyr.io/websocket.readFrameHeader(0x1d819e0, {0x20aa150, 0x8, 0x8}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1c4ba30 sp=0x1c4b9f0 pc=0x43fb54 Jan 15 07:23:00 kitchen go-librespot[25890]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x20aa120, {0x6c8804, 0x1ca2020}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1c4baf0 sp=0x1c4ba30 pc=0x4426b4 Jan 15 07:23:00 kitchen go-librespot[25890]: nhooyr.io/websocket.(*Conn).readLoop(0x20aa120, {0x6c8804, 0x1ca2020}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1c4bbb4 sp=0x1c4baf0 pc=0x44218c Jan 15 07:23:00 kitchen go-librespot[25890]: nhooyr.io/websocket.(*Conn).reader(0x20aa120, {0x6c8804, 0x1ca2020}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x10c fp=0x1c4bc40 sp=0x1c4bbb4 pc=0x4433cc Jan 15 07:23:00 kitchen go-librespot[25890]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jan 15 07:23:00 kitchen go-librespot[25890]: nhooyr.io/websocket.(*Conn).Read(0x20aa120, {0x6c8804, 0x1ca2020}) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x2c fp=0x1c4bc68 sp=0x1c4bc40 pc=0x441a58 Jan 15 07:23:00 kitchen go-librespot[25890]: main.(*ApiServer).serve.func13({0x6c863c, 0x1d8d680}, 0x1cd0980) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:409 +0x24c fp=0x1c4bcc4 sp=0x1c4bc68 pc=0x52f9a4 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.HandlerFunc.ServeHTTP(0x1d88058, {0x6c863c, 0x1d8d680}, 0x1cd0980) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2122 +0x34 fp=0x1c4bcd4 sp=0x1c4bcc4 pc=0x3ffd9c Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*ServeMux).ServeHTTP(0x1d80000, {0x6c863c, 0x1d8d680}, 0x1cd0980) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2500 +0x164 fp=0x1c4bd00 sp=0x1c4bcd4 pc=0x4015e4 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.serverHandler.ServeHTTP({0x1d8c000}, {0x6c863c, 0x1d8d680}, 0x1cd0980) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2936 +0x304 fp=0x1c4bd58 sp=0x1c4bd00 pc=0x402af0 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*conn).serve(0x1cc8180, {0x6c8844, 0x1d94000}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:1995 +0x654 fp=0x1c4bfdc sp=0x1c4bd58 pc=0x3fe664 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*Server).Serve.func3() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3089 +0x38 fp=0x1c4bfec sp=0x1c4bfdc pc=0x403524 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1c4bfec sp=0x1c4bfec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by net/http.(*Server).Serve Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3089 +0x4e0 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 38 [chan receive, 755 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63e9d4, 0x1c961b0, 0xe, 0x17, 0x2) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x209df38 sp=0x209df24 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.chanrecv(0x1c96180, 0x209dfd8, 0x1) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/chan.go:583 +0x538 fp=0x209df84 sp=0x209df38 pc=0x1b16c Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.chanrecv2(0x1c96180, 0x209dfd8) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/chan.go:447 +0x20 fp=0x209df98 sp=0x209df84 pc=0x1ac28 Jan 15 07:23:00 kitchen go-librespot[25890]: main.(*App).Zeroconf.func1() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:113 +0x54 fp=0x209dfec sp=0x209df98 pc=0x534e0c Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x209dfec sp=0x209dfec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by main.(*App).Zeroconf Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:110 +0x22c Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 39 [IO wait]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea9c, 0x6d2c3e08, 0x2, 0x1b, 0x5) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x209e5e4 sp=0x209e5d0 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.netpollblock(0x6d2c3dfc, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x209e5fc sp=0x209e5e4 pc=0x49f2c Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.runtime_pollWait(0x6d2c3dfc, 0x72) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x209e610 sp=0x209e5fc pc=0x800b4 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).wait(0x204a1a4, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x209e624 sp=0x209e610 pc=0xf7db0 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).waitRead(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*FD).Accept(0x204a190) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:614 +0x2d0 fp=0x209e674 sp=0x209e624 pc=0xfc8d0 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*netFD).accept(0x204a190) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/fd_unix.go:172 +0x20 fp=0x209e6d4 sp=0x209e674 pc=0x2816d8 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*TCPListener).accept(0x1d86310) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/tcpsock_posix.go:148 +0x20 fp=0x209e6ec sp=0x209e6d4 pc=0x29a070 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*TCPListener).Accept(0x1d86310) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/tcpsock.go:297 +0x30 fp=0x209e708 sp=0x209e6ec pc=0x299034 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*onceCloseListener).Accept(0x1d82720) Jan 15 07:23:00 kitchen go-librespot[25890]: :1 +0x34 fp=0x209e720 sp=0x209e708 pc=0x427088 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*Server).Serve(0x2054000, {0x6c8508, 0x1d86310}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3059 +0x344 fp=0x209e7bc sp=0x209e720 pc=0x403080 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.Serve(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2581 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:259 +0x8c fp=0x209e7ec sp=0x209e7bc pc=0x4e6680 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x209e7ec sp=0x209e7ec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by go-librespot/zeroconf.(*Zeroconf).Serve Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:259 +0x178 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 52685 [chan receive]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63e9d4, 0x1ef2230, 0xe, 0x17, 0x2) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x1e83aa4 sp=0x1e83a90 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.chanrecv(0x1ef2200, 0x1e83b2b, 0x1) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/chan.go:583 +0x538 fp=0x1e83af0 sp=0x1e83aa4 pc=0x1b16c Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.chanrecv1(0x1ef2200, 0x1e83b2b) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/chan.go:442 +0x20 fp=0x1e83b04 sp=0x1e83af0 pc=0x1ac04 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/zeroconf.(*Zeroconf).handleAddUser(0x204a140, {0x6c863c, 0x1cc43c0}, 0x1f98000) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:198 +0x87c fp=0x1e83c68 sp=0x1e83b04 pc=0x4e5d40 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/zeroconf.(*Zeroconf).Serve.func1({0x6c863c, 0x1cc43c0}, 0x1f98000) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:248 +0x218 fp=0x1e83cc4 sp=0x1e83c68 pc=0x4e68dc Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.HandlerFunc.ServeHTTP(0x1d88410, {0x6c863c, 0x1cc43c0}, 0x1f98000) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2122 +0x34 fp=0x1e83cd4 sp=0x1e83cc4 pc=0x3ffd9c Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*ServeMux).ServeHTTP(0x20700f0, {0x6c863c, 0x1cc43c0}, 0x1f98000) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2500 +0x164 fp=0x1e83d00 sp=0x1e83cd4 pc=0x4015e4 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.serverHandler.ServeHTTP({0x2054000}, {0x6c863c, 0x1cc43c0}, 0x1f98000) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2936 +0x304 fp=0x1e83d58 sp=0x1e83d00 pc=0x402af0 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*conn).serve(0x2132720, {0x6c8844, 0x21093e0}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:1995 +0x654 fp=0x1e83fdc sp=0x1e83d58 pc=0x3fe664 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*Server).Serve.func3() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3089 +0x38 fp=0x1e83fec sp=0x1e83fdc pc=0x403524 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x1e83fec sp=0x1e83fec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by net/http.(*Server).Serve Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3089 +0x4e0 Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 50521 [sync.Cond.Wait, 3644 minutes]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63eab4, 0x1e31190, 0x14, 0x1a, 0x3) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x2b89f38 sp=0x2b89f24 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goparkunlock(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:387 Jan 15 07:23:00 kitchen go-librespot[25890]: sync.runtime_notifyListWait(0x1e31188, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/sema.go:527 +0x1cc fp=0x2b89f60 sp=0x2b89f38 pc=0x81e00 Jan 15 07:23:00 kitchen go-librespot[25890]: sync.(*Cond).Wait(0x1e31180) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/sync/cond.go:70 +0xbc fp=0x2b89f84 sp=0x2b89f60 pc=0x8c820 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/output.(*output).loop(0x2255bc0) Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:160 +0x328 fp=0x2b89fd0 sp=0x2b89f84 pc=0x460898 Jan 15 07:23:00 kitchen go-librespot[25890]: go-librespot/output.newOutput.func1() Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:66 +0x20 fp=0x2b89fec sp=0x2b89fd0 pc=0x45f9dc Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2b89fec sp=0x2b89fec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by go-librespot/output.newOutput Jan 15 07:23:00 kitchen go-librespot[25890]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:65 +0x1cc Jan 15 07:23:00 kitchen go-librespot[25890]: goroutine 52711 [IO wait]: Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.gopark(0x63ea9c, 0x6d2c3928, 0x2, 0x1b, 0x5) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x2585ea8 sp=0x2585e94 pc=0x527b0 Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.netpollblock(0x6d2c391c, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x2585ec0 sp=0x2585ea8 pc=0x49f2c Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.runtime_pollWait(0x6d2c391c, 0x72) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x2585ed4 sp=0x2585ec0 pc=0x800b4 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).wait(0x2092f64, 0x72, 0x0) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2585ee8 sp=0x2585ed4 pc=0xf7db0 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*pollDesc).waitRead(...) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Jan 15 07:23:00 kitchen go-librespot[25890]: internal/poll.(*FD).Read(0x2092f50, {0x1e7820d, 0x1, 0x1}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:167 +0x240 fp=0x2585f38 sp=0x2585ee8 pc=0xf8fbc Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*netFD).Read(0x2092f50, {0x1e7820d, 0x1, 0x1}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/fd_posix.go:55 +0x38 fp=0x2585f64 sp=0x2585f38 pc=0x27f61c Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*conn).Read(0x32fe320, {0x1e7820d, 0x1, 0x1}) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/net.go:183 +0x48 fp=0x2585f94 sp=0x2585f64 pc=0x2905a0 Jan 15 07:23:00 kitchen go-librespot[25890]: net.(*TCPConn).Read(0x32fe320, {0x1e7820d, 0x1, 0x1}) Jan 15 07:23:00 kitchen go-librespot[25890]: :1 +0x44 fp=0x2585fb4 sp=0x2585f94 pc=0x2a4c7c Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*connReader).backgroundRead(0x1e78200) Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:674 +0x44 fp=0x2585fe4 sp=0x2585fb4 pc=0x3f8340 Jan 15 07:23:00 kitchen go-librespot[25890]: net/http.(*connReader).startBackgroundRead.func2() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:670 +0x28 fp=0x2585fec sp=0x2585fe4 pc=0x3f827c Jan 15 07:23:00 kitchen go-librespot[25890]: runtime.goexit() Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2585fec sp=0x2585fec pc=0x84730 Jan 15 07:23:00 kitchen go-librespot[25890]: created by net/http.(*connReader).startBackgroundRead Jan 15 07:23:00 kitchen go-librespot[25890]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:670 +0xf4 Jan 15 07:23:00 kitchen go-librespot[25890]: Aborted Jan 15 07:23:00 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 15 07:23:00 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 15 07:23:00 kitchen volumio[25712]: info: Connection to go-librespot Websocket closed Jan 15 07:23:02 kitchen volumio[25712]: info: CoreCommandRouter::volumioGetState Jan 15 07:23:02 kitchen volumio[25712]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:02 kitchen volumio[25712]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 15 07:23:02 kitchen volumio[25712]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 15 07:23:02 kitchen volumio[25712]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 15 07:23:02 kitchen volumio[25712]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 15 07:23:02 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:23:02 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 15 07:23:02 kitchen volumio[25712]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 15 07:23:02 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:23:02 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 15 07:23:02 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:23:02 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:23:02 kitchen volumio[25712]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 15 07:23:02 kitchen volumio[25712]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 15 07:23:02 kitchen volumio[25712]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 15 07:23:02 kitchen volumio[25712]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 15 07:23:02 kitchen volumio[25712]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 15 07:23:02 kitchen volumio[25712]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 15 07:23:03 kitchen volumio[25712]: info: Initializing connection to go-librespot Websocket Jan 15 07:23:03 kitchen volumio[25712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 15 07:23:03 kitchen systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 15 07:23:03 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 15 07:23:03 kitchen systemd[1]: Stopped go-librespot Daemon. Jan 15 07:23:03 kitchen systemd[1]: Started go-librespot Daemon. Jan 15 07:23:03 kitchen go-librespot[22153]: Librespot-go daemon starting... Jan 15 07:23:03 kitchen go-librespot[22153]: time="2025-01-15T07:23:03Z" level=info msg="generated new device id: 6e6b63ca26c692d92114c3b8cd8eda5ef5ad4b01" Jan 15 07:23:04 kitchen go-librespot[22153]: time="2025-01-15T07:23:04Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 15 07:23:04 kitchen go-librespot[22153]: time="2025-01-15T07:23:04Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 15 07:23:04 kitchen go-librespot[22153]: time="2025-01-15T07:23:04Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 15 07:23:04 kitchen go-librespot[22153]: time="2025-01-15T07:23:04Z" level=debug msg="zeroconf server listening on port 35053" Jan 15 07:23:06 kitchen volumio[25712]: info: Initializing connection to go-librespot Websocket Jan 15 07:23:06 kitchen go-librespot[22153]: time="2025-01-15T07:23:06Z" level=debug msg="new websocket client" Jan 15 07:23:06 kitchen volumio[25712]: info: Connection to go-librespot Websocket established Jan 15 07:23:09 kitchen volumio[25712]: info: Getting Spotify volume Jan 15 07:23:09 kitchen volumio[25712]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 15 07:23:09 kitchen volumio[25712]: info: CoreCommandRouter::volumioGetState Jan 15 07:23:09 kitchen volumio[25712]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:09 kitchen volumio[25712]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 07:23:09 kitchen volumio[25712]: TypeError: Cannot read property 'name' of undefined Jan 15 07:23:09 kitchen volumio[25712]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 15 07:23:09 kitchen volumio[25712]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 15 07:23:09 kitchen volumio[25712]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Jan 15 07:23:09 kitchen volumio[25712]: at Socket.emit (events.js:315:20) Jan 15 07:23:09 kitchen volumio[25712]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 15 07:23:09 kitchen volumio[25712]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 15 07:23:09 kitchen volumio[25712]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 07:23:10 kitchen sudo[22187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-15 07:22 Jan 15 07:23:10 kitchen sudo[22187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:10 kitchen sudo[22187]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:10 kitchen volumio-remote-updater[620]: [2025-01-15 07:23:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 15 07:23:10 kitchen volumio-remote-updater[620]: [2025-01-15 07:23:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 15 07:23:10 kitchen systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 15 07:23:10 kitchen go-librespot[22153]: time="2025-01-15T07:23:10Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jan 15 07:23:10 kitchen systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 15 07:23:10 kitchen systemd[1]: Started dynamicswap service. Jan 15 07:23:10 kitchen systemd[1]: dynamicswap.service: Succeeded. Jan 15 07:23:10 kitchen systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 15 07:23:10 kitchen systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jan 15 07:23:10 kitchen systemd[1]: Stopped Volumio Backend Module. Jan 15 07:23:10 kitchen systemd[1]: Started Volumio Backend Module. Jan 15 07:23:10 kitchen systemd[1]: Started dynamicswap service. Jan 15 07:23:10 kitchen systemd[1]: dynamicswap.service: Succeeded. Jan 15 07:23:12 kitchen volumio[22198]: info: ------------------------------------------- Jan 15 07:23:12 kitchen volumio[22198]: info: ----- Volumio3 ---- Jan 15 07:23:12 kitchen volumio[22198]: info: ------------------------------------------- Jan 15 07:23:12 kitchen volumio[22198]: info: ----- System startup ---- Jan 15 07:23:12 kitchen volumio[22198]: info: ------------------------------------------- Jan 15 07:23:13 kitchen volumio[22198]: info: MYVOLUMIO Environment detected Jan 15 07:23:13 kitchen volumio[22198]: info: Plugin folders cleanup Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning into folder /volumio/app/plugins/ Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning category audio_interface Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning category miscellanea Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning category music_service Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning category plugins.json Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning category system_controller Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning category user_interface Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning into folder /data/plugins/ Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning category music_service Jan 15 07:23:13 kitchen volumio[22198]: info: Scanning category system_controller Jan 15 07:23:13 kitchen volumio[22198]: info: Plugin folders cleanup completed Jan 15 07:23:13 kitchen volumio[22198]: info: ------------------------------------------- Jan 15 07:23:13 kitchen volumio[22198]: info: ----- Core plugins startup ---- Jan 15 07:23:13 kitchen volumio[22198]: info: ------------------------------------------- Jan 15 07:23:13 kitchen volumio[22198]: info: Loading plugins from folder /volumio/app/plugins/ Jan 15 07:23:13 kitchen volumio[22198]: info: Adding plugin upnp to MyMusic Plugins Jan 15 07:23:13 kitchen volumio[22198]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 15 07:23:13 kitchen volumio[22198]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 15 07:23:13 kitchen volumio[22198]: info: Loading plugins from folder /data/plugins/ Jan 15 07:23:13 kitchen volumio[22198]: info: Loading plugin "system"... Jan 15 07:23:13 kitchen volumio[22198]: info: Loading plugin "appearance"... Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "network"... Jan 15 07:23:14 kitchen volumio[22198]: info: Refreshing Cached IP Addresses Jan 15 07:23:14 kitchen sudo[22236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 15 07:23:14 kitchen sudo[22236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:14 kitchen sudo[22238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 15 07:23:14 kitchen sudo[22238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:14 kitchen sudo[22236]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:14 kitchen sudo[22238]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "services"... Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "alsa_controller"... Jan 15 07:23:14 kitchen sudo[22247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 15 07:23:14 kitchen sudo[22247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:14 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "wizard"... Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "networkfs"... Jan 15 07:23:14 kitchen volumio[22198]: info: Cannot mount NAS syno at system boot, trial number 1 ,retrying in 5 seconds Jan 15 07:23:14 kitchen volumio[22198]: info: Starting Udev Watcher for removable devices Jan 15 07:23:14 kitchen volumio[22198]: info: Ignoring mount for partition: boot Jan 15 07:23:14 kitchen volumio[22198]: info: Ignoring mount for partition: volumio Jan 15 07:23:14 kitchen volumio[22198]: info: Ignoring mount for partition: volumio_data Jan 15 07:23:14 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "volumio_command_line_client"... Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "upnp"... Jan 15 07:23:14 kitchen volumio[22198]: info: [1736925794919] Starting Upmpd Daemon Jan 15 07:23:14 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "my_music"... Jan 15 07:23:14 kitchen volumio[22198]: info: Loading plugin "mpd"... Jan 15 07:23:15 kitchen volumio[22198]: info: Loading plugin "upnp_browser"... Jan 15 07:23:15 kitchen volumio[22198]: info: Loading plugin "alarm-clock"... Jan 15 07:23:15 kitchen sudo[22247]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:15 kitchen volumio-remote-updater[620]: [2025-01-15 07:23:15] [connect] Successful connection Jan 15 07:23:15 kitchen volumio[22198]: info: Loading plugin "airplay_emulation"... Jan 15 07:23:15 kitchen volumio[22198]: info: Starting Shairport Sync Jan 15 07:23:15 kitchen volumio[22198]: info: Loading plugin "last_100"... Jan 15 07:23:15 kitchen volumio[22198]: info: Loading plugin "webradio"... Jan 15 07:23:15 kitchen volumio[22198]: info: Loading plugin "i2s_dacs"... Jan 15 07:23:15 kitchen volumio[22198]: info: I2S DAC not set, start Auto-detection Jan 15 07:23:15 kitchen volumio[22198]: info: Loading plugin "volumiodiscovery"... Jan 15 07:23:15 kitchen volumio[22198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 15 07:23:15 kitchen volumio[22198]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 15 07:23:15 kitchen volumio[22198]: *** WARNING *** For more information see Jan 15 07:23:15 kitchen volumio[22198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 15 07:23:15 kitchen volumio[22198]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 15 07:23:15 kitchen volumio[22198]: *** WARNING *** For more information see Jan 15 07:23:15 kitchen node[22198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 15 07:23:15 kitchen volumio[22198]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 15 07:23:15 kitchen node[22198]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 15 07:23:15 kitchen node[22198]: *** WARNING *** For more information see Jan 15 07:23:15 kitchen node[22198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 15 07:23:15 kitchen node[22198]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 15 07:23:15 kitchen node[22198]: *** WARNING *** For more information see Jan 15 07:23:15 kitchen volumio[22198]: info: Discovery: Started advertising with name: Kitchen Jan 15 07:23:15 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 15 07:23:15 kitchen volumio[22198]: info: Loading plugin "spop"... Jan 15 07:23:17 kitchen volumio[22198]: info: Loading plugin "outputs"... Jan 15 07:23:17 kitchen volumio[22198]: info: Loading plugin "albumart"... Jan 15 07:23:17 kitchen volumio[22198]: info: Plugin example_plugin is not enabled Jan 15 07:23:17 kitchen volumio[22198]: info: Loading plugin "inputs"... Jan 15 07:23:17 kitchen volumio[22198]: info: Loading plugin "updater_comm"... Jan 15 07:23:17 kitchen volumio[22198]: info: Plugin mpdemulation is not enabled Jan 15 07:23:17 kitchen volumio[22198]: info: Loading plugin "rest_api"... Jan 15 07:23:17 kitchen volumio[22198]: info: Loading plugin "websocket"... Jan 15 07:23:17 kitchen volumio[22198]: info: Starting Socket.io Server version 2.3.0 Jan 15 07:23:17 kitchen volumio[22198]: info: Loading plugin "backup_restore"... Jan 15 07:23:17 kitchen volumio[22198]: Forking 3 albumart workers Jan 15 07:23:17 kitchen volumio[22198]: info: Applying required configuration parameters for plugin backup_restore Jan 15 07:23:17 kitchen volumio[22198]: info: Loading i18n strings for locale en Jan 15 07:23:17 kitchen volumio[22198]: Updating browse sources language Jan 15 07:23:17 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 07:23:17 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 07:23:17 kitchen volumio[22198]: info: CoreCommandRouter::initPlayerControls Jan 15 07:23:17 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:17 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 07:23:17 kitchen volumio[22198]: Express server listening on port 3000 Jan 15 07:23:17 kitchen volumio[22198]: [Metrics] WebUI: 5s 751.29ms Jan 15 07:23:17 kitchen volumio[22198]: info: CoreStateMachine::resetVolumioState Jan 15 07:23:17 kitchen volumio[22198]: info: CoreStateMachine::getcurrentVolume Jan 15 07:23:17 kitchen volumio[22198]: info: CoreCommandRouter::volumioRetrievevolume Jan 15 07:23:17 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 07:23:18 kitchen volumio[22198]: info: Volumio Network Manager: Network status updated: 2 Jan 15 07:23:18 kitchen volumio-remote-updater[620]: [2025-01-15 07:23:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736925795 101 Jan 15 07:23:18 kitchen volumio[22198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jan 15 07:23:18 kitchen volumio[22198]: info: Reloading queue from file Jan 15 07:23:18 kitchen volumio[22198]: info: CoreStateMachine::setRepeat false single undefined Jan 15 07:23:18 kitchen volumio[22198]: info: CoreStateMachine::pushState Jan 15 07:23:18 kitchen volumio[22198]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::volumioPushState Jan 15 07:23:18 kitchen volumio[22198]: info: CoreStateMachine::setRandom false Jan 15 07:23:18 kitchen volumio[22198]: info: CoreStateMachine::pushState Jan 15 07:23:18 kitchen volumio[22198]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::volumioPushState Jan 15 07:23:18 kitchen volumio[22198]: info: Setting Device type: Raspberry PI Jan 15 07:23:18 kitchen volumio[22198]: info: VolumeController:: Volume=38 Mute =false Jan 15 07:23:18 kitchen volumio[22198]: info: CoreStateMachine::pushState Jan 15 07:23:18 kitchen volumio[22198]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::volumioPushState Jan 15 07:23:18 kitchen volumio[22198]: info: CoreStateMachine::updateTrackBlock Jan 15 07:23:18 kitchen volumio[22198]: info: CorePlayQueue::getTrackBlock Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::volumioRetrievevolume Jan 15 07:23:18 kitchen volumio[22198]: info: Completed loading Core Plugins Jan 15 07:23:18 kitchen volumio[22198]: info: Preparing to generate the ALSA configuration file Jan 15 07:23:18 kitchen volumio[22198]: info: Asound.conf file unchanged, so no further update is needed Jan 15 07:23:18 kitchen volumio[22198]: info: Output device has changed, restarting MPD Jan 15 07:23:18 kitchen sudo[22310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 15 07:23:18 kitchen sudo[22310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:18 kitchen sudo[22310]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:18 kitchen volumio[22198]: Starting albumart workers Jan 15 07:23:18 kitchen volumio[22198]: info: Output device has changed, restarting Shairport Sync Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 07:23:18 kitchen volumio[22198]: Starting albumart workers Jan 15 07:23:18 kitchen sudo[22313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 15 07:23:18 kitchen sudo[22313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:18 kitchen volumio[22198]: Starting albumart workers Jan 15 07:23:18 kitchen systemd[1]: Stopping Music Player Daemon... Jan 15 07:23:18 kitchen volumio[22198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 15 07:23:18 kitchen volumio[22198]: info: ___________ START PLUGINS ___________ Jan 15 07:23:18 kitchen volumio[22198]: info: ControllerMpd::onStart: Initializing MPD Jan 15 07:23:18 kitchen volumio[22198]: info: Creating MPD Configuration file Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 07:23:18 kitchen volumio[22198]: info: [1736925798874] CoreMusicLibrary::Adding element Media Servers Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:18 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 07:23:18 kitchen sudo[22321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 15 07:23:18 kitchen sudo[22321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:18 kitchen sudo[22319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 15 07:23:18 kitchen sudo[22319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:18 kitchen systemd[1]: mpd.service: Succeeded. Jan 15 07:23:18 kitchen systemd[1]: Stopped Music Player Daemon. Jan 15 07:23:18 kitchen sudo[22319]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:18 kitchen volumio[22198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 07:23:19 kitchen systemd[1]: Starting Music Player Daemon... Jan 15 07:23:19 kitchen systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 15 07:23:19 kitchen systemd[1]: mpd.service: Succeeded. Jan 15 07:23:19 kitchen systemd[1]: Stopped Music Player Daemon. Jan 15 07:23:19 kitchen systemd[1]: Starting Music Player Daemon... Jan 15 07:23:19 kitchen volumio[22198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 07:23:19 kitchen volumio[22198]: info: [1736925799150] CoreMusicLibrary::Adding element Last_100 Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 07:23:19 kitchen volumio[22198]: info: [1736925799155] CoreMusicLibrary::Adding element Webradio Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 07:23:19 kitchen sudo[22332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 15 07:23:19 kitchen volumio[22198]: info: Initializing BBC Radios Jan 15 07:23:19 kitchen sudo[22332]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:19 kitchen sudo[22332]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 07:23:19 kitchen volumio[22198]: info: Creating Spotify config file Jan 15 07:23:19 kitchen volumio[22198]: info: Volumio Calling Home Jan 15 07:23:19 kitchen volumio[22198]: info: Cannot mount NAS syno at system boot, trial number 2 ,retrying in 5 seconds Jan 15 07:23:19 kitchen volumio[22198]: info: Discovery: adding 832b5fd7-ee1b-41c2-9f2d-8a90d4f25c8b Jan 15 07:23:19 kitchen volumio[22198]: info: Discovery: Found device Kitchen Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::volumioGetState Jan 15 07:23:19 kitchen volumio[22198]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:19 kitchen volumio[22198]: info: MPD Permissions set Jan 15 07:23:19 kitchen volumio[22198]: info: MPD Permissions set Jan 15 07:23:19 kitchen volumio[22198]: info: VolumeController:: Volume=38 Mute =false Jan 15 07:23:19 kitchen volumio[22198]: info: CoreStateMachine::pushState Jan 15 07:23:19 kitchen volumio[22198]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:19 kitchen volumio[22198]: info: CoreCommandRouter::volumioPushState Jan 15 07:23:19 kitchen volumio[22198]: info: Volumio called home Jan 15 07:23:19 kitchen volumio[22198]: info: Spotify config file written Jan 15 07:23:20 kitchen sudo[22373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 15 07:23:20 kitchen sudo[22373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:20 kitchen volumio[22198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen systemd[1]: Stopping go-librespot Daemon... Jan 15 07:23:20 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jan 15 07:23:20 kitchen systemd[1]: go-librespot-daemon.service: Succeeded. Jan 15 07:23:20 kitchen systemd[1]: Stopped go-librespot Daemon. Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 07:23:20 kitchen systemd[1]: Started go-librespot Daemon. Jan 15 07:23:20 kitchen go-librespot[22379]: Librespot-go daemon starting... Jan 15 07:23:20 kitchen sudo[22373]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:20 kitchen go-librespot[22379]: time="2025-01-15T07:23:20Z" level=info msg="generated new device id: 7f3f4da2744be0d9fb67046826c7ae963c21008d" Jan 15 07:23:20 kitchen volumio[22198]: info: Starting Shairport Sync Jan 15 07:23:20 kitchen volumio[22198]: info: Starting Shairport Sync Jan 15 07:23:20 kitchen volumio[22198]: info: Starting Shairport Sync Jan 15 07:23:20 kitchen sudo[22392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 15 07:23:20 kitchen sudo[22392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:20 kitchen sudo[22394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 15 07:23:20 kitchen sudo[22394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:20 kitchen volumio[22198]: info: CoreCommandRouter::volumioGetState Jan 15 07:23:20 kitchen volumio[22198]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:20 kitchen systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 15 07:23:20 kitchen sudo[22399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 15 07:23:20 kitchen sudo[22399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:20 kitchen systemd[1]: shairport-sync.service: Succeeded. Jan 15 07:23:20 kitchen systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 15 07:23:20 kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 15 07:23:20 kitchen go-librespot[22379]: time="2025-01-15T07:23:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 15 07:23:20 kitchen go-librespot[22379]: time="2025-01-15T07:23:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 15 07:23:20 kitchen go-librespot[22379]: time="2025-01-15T07:23:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 15 07:23:20 kitchen sudo[22394]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:20 kitchen sudo[22392]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:20 kitchen go-librespot[22379]: time="2025-01-15T07:23:20Z" level=debug msg="zeroconf server listening on port 46543" Jan 15 07:23:20 kitchen volumio[22198]: info: Shairport-Sync Started Jan 15 07:23:20 kitchen systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 15 07:23:20 kitchen volumio[22198]: Error adding Membership: Error: addMembership EINVAL Jan 15 07:23:20 kitchen volumio[22198]: info: Shairport-Sync Started Jan 15 07:23:20 kitchen systemd[1]: shairport-sync.service: Succeeded. Jan 15 07:23:20 kitchen systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 15 07:23:20 kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 15 07:23:20 kitchen sudo[22399]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:20 kitchen volumio[22198]: info: Shairport-Sync Started Jan 15 07:23:21 kitchen mpd[22334]: Jan 15 07:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 15 07:23:22 kitchen systemd[1]: Started Music Player Daemon. Jan 15 07:23:22 kitchen sudo[22313]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:22 kitchen sudo[22321]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:22 kitchen volumio[22198]: info: CoreCommandRouter::volumioGetState Jan 15 07:23:22 kitchen volumio[22198]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:22 kitchen volumio[22198]: info: Completed starting Core Plugins Jan 15 07:23:22 kitchen volumio[22198]: info: ------------------------------------------- Jan 15 07:23:22 kitchen volumio[22198]: info: ----- MyVolumio plugins startup ---- Jan 15 07:23:22 kitchen volumio[22198]: info: ------------------------------------------- Jan 15 07:23:22 kitchen volumio[22198]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 15 07:23:22 kitchen volumio[22198]: error: MPD error: The expression evaluated to a falsy value: Jan 15 07:23:22 kitchen volumio[22198]: assert.ok(self.idling) Jan 15 07:23:22 kitchen volumio[22198]: error: The expression evaluated to a falsy value: Jan 15 07:23:22 kitchen volumio[22198]: assert.ok(self.idling) Jan 15 07:23:22 kitchen volumio[22198]: info: Listing playlists Jan 15 07:23:22 kitchen volumio[22198]: info: Listing playlists Jan 15 07:23:22 kitchen volumio[22198]: info: MPD running with PID22334 Jan 15 07:23:22 kitchen volumio[22198]: ,establishing connection Jan 15 07:23:22 kitchen volumio[22198]: error: updateQueue error: null Jan 15 07:23:22 kitchen volumio[22198]: error: updateQueue error: null Jan 15 07:23:23 kitchen volumio[22198]: info: go-librespot daemon successfully initialized Jan 15 07:23:24 kitchen go-librespot[22379]: time="2025-01-15T07:23:24Z" level=debug msg="obtained new client token: AAAbfEt+/9aPwlGn5KilaleqnwFfiqNEA1r92i4wkecwCDWjleQg7grjceaYG0srtcmLE5MboLGpYlIQrczS3qJE8vXArWe7tItNGyGGe1IwjyhxGwaZr6+jOj1ficAwOyZ6e+yVdUviciaraga/pkSpRfAJGwSY9zT3Fu15uYQhrCsDJhEp76oXAuH5Pd3I7u4VgPvO0fIRBU6N3wXF7RXrKJG90ycYqqsbCPjWIQSl31ZWNFpaOmBo0A99FSE=" Jan 15 07:23:24 kitchen go-librespot[22379]: time="2025-01-15T07:23:24Z" level=debug msg="completed keyexchange" Jan 15 07:23:24 kitchen volumio[22198]: info: Cannot mount NAS syno at system boot, trial number 3 ,retrying in 5 seconds Jan 15 07:23:24 kitchen sudo[22438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 15 07:23:24 kitchen sudo[22438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:24 kitchen sudo[22438]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:24 kitchen sudo[22440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 15 07:23:24 kitchen sudo[22440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:24 kitchen sudo[22440]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:24 kitchen sudo[22444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 15 07:23:25 kitchen sudo[22444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 07:23:25 kitchen sudo[22444]: pam_unix(sudo:session): session closed for user root Jan 15 07:23:25 kitchen volumio[22198]: info: Upmpdcli Daemon Started Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="completed challenge" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="authenticated as 3tnz25jilqkm9bkow0dwge03k" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="authenticated as 3tnz25jilqkm9bkow0dwge03k" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="dealer connection opened" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=info msg="accepted zeroconf user 3tnz25jilqkm9bkow0dwge03k from M2101K9G" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="received connection id: MTYyMGJhMDItZDYxYi00YjU3LTg4YjgtZjM4YWEyNmY0MjMwK2RlYWxlcit0Y3A6Ly8wYWNhNDA1OC5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArREFFQjc4OUFFNTY4RTYyQTg0NDlGRjVEMEI4NzA1QUM1ODIwMjg0NkEzNjlFMTdDRkYxNEZDMkE4QkI2M0UwNw==" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="put connect state because NEW_DEVICE" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="handling transfer player command from c74c66b1c114c367f7b6a4f39d4ab7455730d272" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="loading track spotify:track:6si71supnBUhddjR2FJc2L (paused: false, position: 37797ms)" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=trace msg="emitting websocket event: will_play" Jan 15 07:23:25 kitchen go-librespot[22379]: time="2025-01-15T07:23:25Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jan 15 07:23:26 kitchen go-librespot[22379]: time="2025-01-15T07:23:26Z" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:6si71supnBUhddjR2FJc2L" Jan 15 07:23:26 kitchen go-librespot[22379]: time="2025-01-15T07:23:26Z" level=debug msg="requested aes key for file a1b9841e64abf178c0752bebf8069838a8f3b72f, gid: 6si71supnBUhddjR2FJc2L" Jan 15 07:23:26 kitchen go-librespot[22379]: time="2025-01-15T07:23:26Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1376" Jan 15 07:23:26 kitchen volumio[22198]: info: Initializing connection to go-librespot Websocket Jan 15 07:23:26 kitchen go-librespot[22379]: time="2025-01-15T07:23:26Z" level=debug msg="new websocket client" Jan 15 07:23:26 kitchen volumio[22198]: info: Connection to go-librespot Websocket established Jan 15 07:23:26 kitchen go-librespot[22379]: time="2025-01-15T07:23:26Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1132" Jan 15 07:23:26 kitchen go-librespot[22379]: time="2025-01-15T07:23:26Z" level=debug msg="fetched first chunk of 19, total size is 9467388 bytes" Jan 15 07:23:26 kitchen go-librespot[22379]: time="2025-01-15T07:23:26Z" level=debug msg="fetched chunk 1/18, size: 524288" Jan 15 07:23:26 kitchen go-librespot[22379]: time="2025-01-15T07:23:26Z" level=debug msg="fetched chunk 3/18, size: 524288" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=debug msg="fetched chunk 4/18, size: 524288" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=debug msg="fetched chunk 2/18, size: 524288" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=trace msg="seek to 37797ms (diff: 171ms, samples: 1666847, bytes: 1376332)" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=info msg="loaded track \"White Flag\" (uri: spotify:track:6si71supnBUhddjR2FJc2L, paused: false, position: 37797ms, duration: 240040ms)" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=debug msg="fetched chunk 5/18, size: 524288" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=trace msg="emitting websocket event: metadata" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=trace msg="emitting websocket event: active" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=debug msg="sending successful reply for delaer request" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 15 07:23:27 kitchen volumio[22198]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6si71supnBUhddjR2FJc2L","name":"White Flag","artist_names":["Dido"],"album_name":"Life For Rent","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d41b79f27ef8d8df5a191219","position":37797,"duration":240040}} Jan 15 07:23:27 kitchen volumio[22198]: SPOTIFY: received: {"type":"active","data":null} Jan 15 07:23:27 kitchen volumio[22198]: info: Aligning Spotify Volume to Volumio Volume Jan 15 07:23:27 kitchen volumio[22198]: info: CoreCommandRouter::volumioGetState Jan 15 07:23:27 kitchen volumio[22198]: info: CorePlayQueue::getTrack 0 Jan 15 07:23:27 kitchen volumio[22198]: info: Setting Spotify Volume from Volumio: 38 Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 15 07:23:27 kitchen go-librespot[22379]: time="2025-01-15T07:23:27Z" level=trace msg="emitting websocket event: playing" Jan 15 07:23:27 kitchen volumio[22198]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6si71supnBUhddjR2FJc2L","play_origin":"search"}} Jan 15 07:23:27 kitchen volumio[22198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 07:23:27 kitchen volumio[22198]: TypeError: Cannot read property 'service' of undefined Jan 15 07:23:27 kitchen volumio[22198]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:322:50) Jan 15 07:23:27 kitchen volumio[22198]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:248:18) Jan 15 07:23:27 kitchen volumio[22198]: at WebSocket.message (/data/plugins/music_service/spop/index.js:175:14) Jan 15 07:23:27 kitchen volumio[22198]: at WebSocket.emit (events.js:315:20) Jan 15 07:23:27 kitchen volumio[22198]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1192:20) Jan 15 07:23:27 kitchen volumio[22198]: at Receiver.emit (events.js:315:20) Jan 15 07:23:27 kitchen volumio[22198]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:560:14) Jan 15 07:23:27 kitchen volumio[22198]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:478:17) Jan 15 07:23:27 kitchen volumio[22198]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:22) Jan 15 07:23:27 kitchen volumio[22198]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:180:18 Jan 15 07:23:27 kitchen volumio[22198]: at internal/process/task_queues.js:149:7 Jan 15 07:23:27 kitchen volumio[22198]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Jan 15 07:23:27 kitchen volumio[22198]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) Jan 15 07:23:27 kitchen volumio[22198]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jan 15 07:23:27 kitchen volumio[22198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 07:23:27 kitchen sudo[22459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-15 07:22 Jan 15 07:23:27 kitchen sudo[22459]: 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"