-- Logs begin at Sat 2025-08-30 07:01:01 CEST, end at Sat 2025-08-30 12:36:05 CEST. -- Aug 30 12:35:01 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 30 12:35:01 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 Aug 30 12:35:01 volumio volumio[1071]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Aug 30 12:35:01 volumio volumio[1071]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 30 12:35:01 volumio volumio[1071]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Aug 30 12:35:01 volumio volumio[1071]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Aug 30 12:35:01 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:01 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Aug 30 12:35:01 volumio volumio[1071]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Aug 30 12:35:01 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:01 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:281:22 Aug 30 12:35:01 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:01 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:01 volumio volumio[1071]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Aug 30 12:35:01 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:01 volumio volumio[1071]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Aug 30 12:35:01 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:284:7 Aug 30 12:35:01 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:01 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:01 volumio volumio[1071]: info: Listing playlists Aug 30 12:35:01 volumio volumio[1071]: info: Listing playlists Aug 30 12:35:01 volumio go-librespot[2070]: time="2025-08-30T12:35:01+02:00" level=debug msg="renewing login5 access token" Aug 30 12:35:11 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 30 12:35:11 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 Aug 30 12:35:11 volumio volumio[1071]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Aug 30 12:35:11 volumio volumio[1071]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 30 12:35:11 volumio volumio[1071]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Aug 30 12:35:11 volumio volumio[1071]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Aug 30 12:35:11 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:11 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Aug 30 12:35:11 volumio volumio[1071]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Aug 30 12:35:11 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:11 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:281:22 Aug 30 12:35:11 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:11 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:11 volumio volumio[1071]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Aug 30 12:35:11 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:11 volumio volumio[1071]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Aug 30 12:35:11 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:284:7 Aug 30 12:35:11 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:11 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:21 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 30 12:35:21 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 Aug 30 12:35:21 volumio volumio[1071]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Aug 30 12:35:21 volumio volumio[1071]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 30 12:35:21 volumio volumio[1071]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Aug 30 12:35:21 volumio volumio[1071]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Aug 30 12:35:21 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:21 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Aug 30 12:35:21 volumio volumio[1071]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Aug 30 12:35:21 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:21 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:281:22 Aug 30 12:35:21 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:21 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:21 volumio volumio[1071]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Aug 30 12:35:21 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:21 volumio volumio[1071]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Aug 30 12:35:21 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:284:7 Aug 30 12:35:21 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:21 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:21 volumio volumio[1071]: info: Listing playlists Aug 30 12:35:21 volumio volumio[1071]: info: Listing playlists Aug 30 12:35:31 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 30 12:35:31 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 Aug 30 12:35:31 volumio volumio[1071]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Aug 30 12:35:31 volumio volumio[1071]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 30 12:35:31 volumio volumio[1071]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Aug 30 12:35:31 volumio volumio[1071]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Aug 30 12:35:31 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:31 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Aug 30 12:35:31 volumio volumio[1071]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Aug 30 12:35:31 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:31 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:281:22 Aug 30 12:35:31 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:31 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:31 volumio volumio[1071]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Aug 30 12:35:31 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:31 volumio volumio[1071]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Aug 30 12:35:31 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:284:7 Aug 30 12:35:31 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:31 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:41 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 30 12:35:41 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 Aug 30 12:35:41 volumio volumio[1071]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Aug 30 12:35:41 volumio volumio[1071]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 30 12:35:41 volumio volumio[1071]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Aug 30 12:35:41 volumio volumio[1071]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Aug 30 12:35:41 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:41 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Aug 30 12:35:41 volumio volumio[1071]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Aug 30 12:35:41 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:41 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:281:22 Aug 30 12:35:41 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:41 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:41 volumio volumio[1071]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Aug 30 12:35:41 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:41 volumio volumio[1071]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Aug 30 12:35:41 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:284:7 Aug 30 12:35:41 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:41 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:41 volumio volumio[1071]: info: Listing playlists Aug 30 12:35:41 volumio volumio[1071]: info: Listing playlists Aug 30 12:35:42 volumio go-librespot[2070]: time="2025-08-30T12:35:42+02:00" level=trace msg="received accesspoint ping" Aug 30 12:35:42 volumio go-librespot[2070]: time="2025-08-30T12:35:42+02:00" level=trace msg="received accesspoint pong ack" Aug 30 12:35:51 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 30 12:35:51 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 Aug 30 12:35:51 volumio volumio[1071]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Aug 30 12:35:51 volumio volumio[1071]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 30 12:35:51 volumio volumio[1071]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Aug 30 12:35:51 volumio volumio[1071]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Aug 30 12:35:51 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:51 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Aug 30 12:35:51 volumio volumio[1071]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Aug 30 12:35:51 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:51 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:281:22 Aug 30 12:35:51 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:51 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:51 volumio volumio[1071]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Aug 30 12:35:51 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:35:51 volumio volumio[1071]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Aug 30 12:35:51 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:284:7 Aug 30 12:35:51 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:35:51 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:35:56 volumio go-librespot[2070]: time="2025-08-30T12:35:56+02:00" level=debug msg="renewing login5 access token" Aug 30 12:35:56 volumio go-librespot[2070]: time="2025-08-30T12:35:56+02:00" level=error msg="failed reconnecting dealer" error="failed obtaining dealer access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Aug 30 12:35:56 volumio go-librespot[2070]: time="2025-08-30T12:35:56+02:00" level=trace msg="sent dealer ping" Aug 30 12:35:56 volumio go-librespot[2070]: time="2025-08-30T12:35:56+02:00" level=warning msg="failed sending dealer ping" error="failed to write msg: WebSocket closed: failed to read frame header: EOF" Aug 30 12:35:56 volumio go-librespot[2070]: fatal error: sync: Unlock of unlocked RWMutex Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 810 gp=0x1efd9e8 m=5 mp=0x1c80008 [running]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.fatal({0x71be82, 0x20}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/panic.go:1042 +0x4c fp=0x20bef08 sp=0x20beef4 pc=0x596e8 Aug 30 12:35:56 volumio go-librespot[2070]: sync.fatal({0x71be82, 0x20}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/panic.go:1007 +0x24 fp=0x20bef14 sp=0x20bef08 pc=0x8fdec Aug 30 12:35:56 volumio go-librespot[2070]: sync.(*RWMutex).Unlock(0x1daa104) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/sync/rwmutex.go:208 +0x50 fp=0x20bef2c sp=0x20bef14 pc=0xa0da0 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x1daa0b8) Aug 30 12:35:56 volumio go-librespot[2070]: /src/dealer/dealer.go:244 +0x758 fp=0x20befe4 sp=0x20bef2c pc=0x4e9214 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /src/dealer/dealer.go:295 +0x28 fp=0x20befec sp=0x20befe4 pc=0x4e9768 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20befec sp=0x20befec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 733 Aug 30 12:35:56 volumio go-librespot[2070]: /src/dealer/dealer.go:295 +0xf4 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 1 gp=0x1c02128 m=nil [select, 21938 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c59cfc sp=0x1c59ce8 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.selectgo(0x1c59dfc, 0x1d55dc8, 0x0, 0x0, 0x2, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1c59d9c sp=0x1c59cfc pc=0x70d80 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x1e2fda0, 0x1c59e88) Aug 30 12:35:56 volumio go-librespot[2070]: /src/zeroconf/zeroconf.go:270 +0x228 fp=0x1c59e40 sp=0x1c59d9c pc=0x55dd34 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*App).withAppPlayer(0x1c95b80, {0x7d7634, 0xbf6000}, 0x1cab3c0) Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:316 +0x514 fp=0x1c59ec8 sp=0x1c59e40 pc=0x5f2dcc Aug 30 12:35:56 volumio go-librespot[2070]: main.(*App).withCredentials(...) Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:190 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*App).SpotifyToken(0x1c95b80, {0x7d7634, 0xbf6000}, {0x1cc27c0, 0x1c}, {0x1ca43c0, 0x133}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:182 +0xd8 fp=0x1c59ef8 sp=0x1c59ec8 pc=0x5f22c0 Aug 30 12:35:56 volumio go-librespot[2070]: main.main() Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:618 +0x61c fp=0x1c59fa8 sp=0x1c59ef8 pc=0x5f5a58 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.main() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x1c59fec sp=0x1c59fa8 pc=0x5ca38 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c59fec sp=0x1c59fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 2 gp=0x1c027e8 m=nil [force gc (idle), 3 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736864, 0xbca928, 0x11, 0xa, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c44fd4 sp=0x1c44fc0 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goparkunlock(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:408 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.forcegchelper() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x1c44fec sp=0x1c44fd4 pc=0x5ce04 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c44fec sp=0x1c44fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by runtime.init.5 in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:314 +0x1c Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 18 gp=0x1c84248 m=nil [GC sweep wait]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736864, 0xbcaee0, 0xc, 0x9, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c407c4 sp=0x1c407b0 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goparkunlock(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:408 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.bgsweep(0x1c6c000) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x1c407e4 sp=0x1c407c4 pc=0x45750 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gcenable.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x1c407ec sp=0x1c407e4 pc=0x35870 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c407ec sp=0x1c407ec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by runtime.gcenable in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 19 gp=0x1c84368 m=nil [GC scavenge wait]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736864, 0xbcb508, 0xd, 0xa, 0x2) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c40fb4 sp=0x1c40fa0 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goparkunlock(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:408 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.(*scavengerState).park(0xbcb508) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1c40fc8 sp=0x1c40fb4 pc=0x42a70 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.bgscavenge(0x1c6c000) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1c40fe4 sp=0x1c40fc8 pc=0x431a8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gcenable.gowrap2() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x1c40fec sp=0x1c40fe4 pc=0x3581c Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c40fec sp=0x1c40fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by runtime.gcenable in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 20 gp=0x1c846c8 m=nil [finalizer wait, 1127 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736710, 0xbf609c, 0x10, 0xa, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c4178c sp=0x1c41778 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.runfinq() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x1c417ec sp=0x1c4178c pc=0x3465c Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c417ec sp=0x1c417ec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by runtime.createfing in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 23 gp=0x1c85c28 m=nil [IO wait, 1174 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736854, 0xb600cf08, 0x2, 0x2, 0x5) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20bcd28 sp=0x20bcd14 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.netpollblock(0xb600cef8, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x20bcd40 sp=0x20bcd28 pc=0x547d4 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.runtime_pollWait(0xb600cef8, 0x72) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20bcd54 sp=0x20bcd40 pc=0x8f388 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).wait(0x1ca72d8, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20bcd68 sp=0x20bcd54 pc=0x10a2d8 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).waitRead(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*FD).Accept(0x1ca72c0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x20bcdb0 sp=0x20bcd68 pc=0x10eb44 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*netFD).accept(0x1ca72c0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x20bce18 sp=0x20bcdb0 pc=0x17e32c Aug 30 12:35:56 volumio go-librespot[2070]: net.(*TCPListener).accept(0x1cb0810) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x20bce30 sp=0x20bce18 pc=0x19642c Aug 30 12:35:56 volumio go-librespot[2070]: net.(*TCPListener).Accept(0x1cb0810) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x20bce4c sp=0x20bce30 pc=0x195498 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*onceCloseListener).Accept(0x1dc4340) Aug 30 12:35:56 volumio go-librespot[2070]: :1 +0x34 fp=0x20bce64 sp=0x20bce4c pc=0x362680 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*Server).Serve(0x1dbc0a8, {0x7d6e18, 0x1cb0810}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x20bcefc sp=0x20bce64 pc=0x340d3c Aug 30 12:35:56 volumio go-librespot[2070]: net/http.Serve(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:2794 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*ConcreteApiServer).serve(0x1cdafc0) Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/api_server.go:610 +0x974 fp=0x20bcfe4 sp=0x20bcefc pc=0x5e8bc0 Aug 30 12:35:56 volumio go-librespot[2070]: main.NewApiServer.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/api_server.go:289 +0x28 fp=0x20bcfec sp=0x20bcfe4 pc=0x5e7a90 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20bcfec sp=0x20bcfec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by main.NewApiServer in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/api_server.go:289 +0x310 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 34 gp=0x1d7a008 m=nil [GC worker (idle), 119 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736720, 0x1de05e8, 0x1a, 0xa, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c44790 sp=0x1c4477c pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gcBgMarkWorker() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c447ec sp=0x1c44790 pc=0x38498 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c447ec sp=0x1c447ec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by runtime.gcBgMarkStartWorkers in goroutine 3 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 4 gp=0x1d7a128 m=nil [GC worker (idle), 3 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736720, 0x1cbdfc8, 0x1a, 0xa, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c42790 sp=0x1c4277c pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gcBgMarkWorker() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c427ec sp=0x1c42790 pc=0x38498 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c427ec sp=0x1c427ec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by runtime.gcBgMarkStartWorkers in goroutine 3 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 29 gp=0x1d7a5a8 m=nil [GC worker (idle), 119 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736720, 0x1cbdfe0, 0x1a, 0xa, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c42f90 sp=0x1c42f7c pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gcBgMarkWorker() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c42fec sp=0x1c42f90 pc=0x38498 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c42fec sp=0x1c42fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by runtime.gcBgMarkStartWorkers in goroutine 3 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 30 gp=0x1d7a6c8 m=nil [GC worker (idle), 90 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736720, 0x1f8e000, 0x1a, 0xa, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c43790 sp=0x1c4377c pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gcBgMarkWorker() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c437ec sp=0x1c43790 pc=0x38498 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c437ec sp=0x1c437ec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by runtime.gcBgMarkStartWorkers in goroutine 3 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 5 gp=0x204e248 m=nil [IO wait]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736854, 0xb600cd28, 0x2, 0x2, 0x5) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c55e84 sp=0x1c55e70 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.netpollblock(0xb600cd18, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1c55e9c sp=0x1c55e84 pc=0x547d4 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.runtime_pollWait(0xb600cd18, 0x72) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1c55eb0 sp=0x1c55e9c pc=0x8f388 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).wait(0x1fce568, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c55ec4 sp=0x1c55eb0 pc=0x10a2d8 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).waitRead(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*FD).RawRead(0x1fce550, 0x1ddeb70) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1c55ef0 sp=0x1c55ec4 pc=0x10f748 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*rawConn).Read(0x2140708, 0x1ddeb70) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1c55f10 sp=0x1c55ef0 pc=0x190854 Aug 30 12:35:56 volumio go-librespot[2070]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1cab070, 0x1dde930, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1c55f40 sp=0x1c55f10 pc=0x4fe72c Aug 30 12:35:56 volumio go-librespot[2070]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Aug 30 12:35:56 volumio go-librespot[2070]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x1db3d78, {0x1d7e000, 0x10000, 0x10000}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x1c55f9c sp=0x1c55f40 pc=0x502714 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/grandcat/zeroconf.(*Server).recv4(0x1dccd80, 0x1db3d70) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1c55fe0 sp=0x1c55f9c pc=0x558fa4 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1c55fec sp=0x1c55fe0 pc=0x558c5c Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c55fec sp=0x1c55fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 50 Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 6 gp=0x204e368 m=nil [IO wait, 21938 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736854, 0xb600cc88, 0x2, 0x2, 0x5) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1fd3e5c sp=0x1fd3e48 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.netpollblock(0xb600cc78, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1fd3e74 sp=0x1fd3e5c pc=0x547d4 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.runtime_pollWait(0xb600cc78, 0x72) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1fd3e88 sp=0x1fd3e74 pc=0x8f388 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).wait(0x1fce5b8, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1fd3e9c sp=0x1fd3e88 pc=0x10a2d8 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).waitRead(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*FD).RawRead(0x1fce5a0, 0x20d2030) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1fd3ec8 sp=0x1fd3e9c pc=0x10f748 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*rawConn).Read(0x2140730, 0x20d2030) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1fd3ee8 sp=0x1fd3ec8 pc=0x190854 Aug 30 12:35:56 volumio go-librespot[2070]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1cab080, 0x20d2000, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1fd3f18 sp=0x1fd3ee8 pc=0x4fe72c Aug 30 12:35:56 volumio go-librespot[2070]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Aug 30 12:35:56 volumio go-librespot[2070]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1db3da8, {0x20c2000, 0x10000, 0x10000}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x1fd3f9c sp=0x1fd3f18 pc=0x50609c Aug 30 12:35:56 volumio go-librespot[2070]: github.com/grandcat/zeroconf.(*Server).recv6(0x1dccd80, 0x1db3da0) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1fd3fe0 sp=0x1fd3f9c pc=0x559198 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1fd3fec sp=0x1fd3fe0 pc=0x558c00 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1fd3fec sp=0x1fd3fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 50 Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 70 gp=0x1c03c28 m=nil [select, 1174 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1fd7e74 sp=0x1fd7e60 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.selectgo(0x1fd7f74, 0x1fd7f3c, 0x0, 0x0, 0x3, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1fd7f14 sp=0x1fd7e74 pc=0x70d80 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1c94b40) Aug 30 12:35:56 volumio go-librespot[2070]: /src/player/player.go:181 +0x1fc fp=0x1fd7fe4 sp=0x1fd7f14 pc=0x4cbbd8 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /src/player/player.go:163 +0x28 fp=0x1fd7fec sp=0x1fd7fe4 pc=0x4cb7b8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1fd7fec sp=0x1fd7fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /src/player/player.go:163 +0x1c8 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 86 gp=0x1c03d48 m=nil [select, 3 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c57eb8 sp=0x1c57ea4 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.selectgo(0x1c57fd4, 0x1c57f7c, 0x0, 0x0, 0x2, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1c57f58 sp=0x1c57eb8 pc=0x70d80 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x1daa008) Aug 30 12:35:56 volumio go-librespot[2070]: /src/ap/ap.go:355 +0x8c fp=0x1c57fe4 sp=0x1c57f58 pc=0x4859a8 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Aug 30 12:35:56 volumio go-librespot[2070]: /src/ap/ap.go:264 +0x28 fp=0x1c57fec sp=0x1c57fe4 pc=0x4850a0 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c57fec sp=0x1c57fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 71 Aug 30 12:35:56 volumio go-librespot[2070]: /src/ap/ap.go:264 +0x15c Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 71 gp=0x1c03e68 m=nil [select, 90 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1d53854 sp=0x1d53840 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.selectgo(0x1d53e10, 0x1d53a34, 0x0, 0x0, 0x8, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1d538f4 sp=0x1d53854 pc=0x70d80 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*AppPlayer).Run(0x1fce5f0, {0x7d7634, 0xbf6000}, 0x1c98bc0) Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/player.go:570 +0x370 fp=0x1d53fd8 sp=0x1d538f4 pc=0x5faf20 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*App).withAppPlayer.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:254 +0x40 fp=0x1d53fec sp=0x1d53fd8 pc=0x5f3a44 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1d53fec sp=0x1d53fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by main.(*App).withAppPlayer in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:254 +0x398 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 72 gp=0x1d7a7e8 m=nil [chan receive, 1174 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x7366f4, 0x1c98270, 0xe, 0x7, 0x2) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c46748 sp=0x1c46734 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.chanrecv(0x1c98240, 0x1c467d0, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1c46784 sp=0x1c46748 pc=0x1ff80 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.chanrecv2(0x1c98240, 0x1c467d0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1c46798 sp=0x1c46784 pc=0x1fac4 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*App).withAppPlayer.func1() Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:264 +0x68 fp=0x1c467ec sp=0x1c46798 pc=0x5f3964 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c467ec sp=0x1c467ec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by main.(*App).withAppPlayer in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:261 +0x424 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 73 gp=0x1d7b448 m=nil [chan receive, 21938 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x7366f4, 0x1c98230, 0xe, 0x7, 0x2) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c45f38 sp=0x1c45f24 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.chanrecv(0x1c98200, 0x1c45fe0, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1c45f74 sp=0x1c45f38 pc=0x1ff80 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.chanrecv2(0x1c98200, 0x1c45fe0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1c45f88 sp=0x1c45f74 pc=0x1fac4 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*App).withAppPlayer.func2() Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:280 +0x6c fp=0x1c45fec sp=0x1c45f88 pc=0x5f352c Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c45fec sp=0x1c45fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by main.(*App).withAppPlayer in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/main.go:277 +0x4c4 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 74 gp=0x1d7b568 m=nil [IO wait, 5450 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736854, 0xb600cdc8, 0x2, 0x2, 0x5) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1df5de8 sp=0x1df5dd4 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.netpollblock(0xb600cdb8, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1df5e00 sp=0x1df5de8 pc=0x547d4 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.runtime_pollWait(0xb600cdb8, 0x72) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1df5e14 sp=0x1df5e00 pc=0x8f388 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).wait(0x1fce518, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1df5e28 sp=0x1df5e14 pc=0x10a2d8 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).waitRead(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*FD).Accept(0x1fce500) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1df5e70 sp=0x1df5e28 pc=0x10eb44 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*netFD).accept(0x1fce500) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1df5ed8 sp=0x1df5e70 pc=0x17e32c Aug 30 12:35:56 volumio go-librespot[2070]: net.(*TCPListener).accept(0x1fc5ad0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1df5ef0 sp=0x1df5ed8 pc=0x19642c Aug 30 12:35:56 volumio go-librespot[2070]: net.(*TCPListener).Accept(0x1fc5ad0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1df5f0c sp=0x1df5ef0 pc=0x195498 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*onceCloseListener).Accept(0x214db20) Aug 30 12:35:56 volumio go-librespot[2070]: :1 +0x34 fp=0x1df5f24 sp=0x1df5f0c pc=0x362680 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*Server).Serve(0x1d6b0e8, {0x7d6e18, 0x1fc5ad0}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1df5fbc sp=0x1df5f24 pc=0x340d3c Aug 30 12:35:56 volumio go-librespot[2070]: net/http.Serve(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:2794 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Aug 30 12:35:56 volumio go-librespot[2070]: /src/zeroconf/zeroconf.go:267 +0x8c fp=0x1df5fec sp=0x1df5fbc pc=0x55de30 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1df5fec sp=0x1df5fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Aug 30 12:35:56 volumio go-librespot[2070]: /src/zeroconf/zeroconf.go:267 +0x160 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 1111 gp=0x1d7b7a8 m=nil [IO wait]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736854, 0xb600cbe8, 0x2, 0x2, 0x5) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20c0ad4 sp=0x20c0ac0 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.netpollblock(0xb600cbd8, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x20c0aec sp=0x20c0ad4 pc=0x547d4 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.runtime_pollWait(0xb600cbd8, 0x72) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20c0b00 sp=0x20c0aec pc=0x8f388 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).wait(0x200a568, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20c0b14 sp=0x20c0b00 pc=0x10a2d8 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).waitRead(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*FD).Read(0x200a550, {0x1e95000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20c0b5c sp=0x20c0b14 pc=0x10b4c4 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*netFD).Read(0x200a550, {0x1e95000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x20c0b88 sp=0x20c0b5c pc=0x17c438 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*conn).Read(0x1f84070, {0x1e95000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x20c0bb4 sp=0x20c0b88 pc=0x18c618 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*TCPConn).Read(0x1f84070, {0x1e95000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: :1 +0x44 fp=0x20c0bd4 sp=0x20c0bb4 pc=0x19f86c Aug 30 12:35:56 volumio go-librespot[2070]: crypto/tls.(*atLeastReader).Read(0x1c0e4b0, {0x1e95000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x20c0c00 sp=0x20c0bd4 pc=0x27c1ac Aug 30 12:35:56 volumio go-librespot[2070]: bytes.(*Buffer).ReadFrom(0x20f714c, {0x7d4554, 0x1c0e4b0}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x20c0c3c sp=0x20c0c00 pc=0x139b90 Aug 30 12:35:56 volumio go-librespot[2070]: crypto/tls.(*Conn).readFromUntil(0x20f6fc8, {0x7d373c, 0x1f84070}, 0x5) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x20c0c64 sp=0x20c0c3c pc=0x27c408 Aug 30 12:35:56 volumio go-librespot[2070]: crypto/tls.(*Conn).readRecordOrCCS(0x20f6fc8, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x20c0dc8 sp=0x20c0c64 pc=0x279b08 Aug 30 12:35:56 volumio go-librespot[2070]: crypto/tls.(*Conn).readRecord(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/crypto/tls/conn.go:588 Aug 30 12:35:56 volumio go-librespot[2070]: crypto/tls.(*Conn).Read(0x20f6fc8, {0x1e52000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x20c0df8 sp=0x20c0dc8 pc=0x27f978 Aug 30 12:35:56 volumio go-librespot[2070]: bufio.(*Reader).Read(0x20d3950, {0x20f4324, 0x9, 0x9}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x20c0e1c sp=0x20c0df8 pc=0x2bdf6c Aug 30 12:35:56 volumio go-librespot[2070]: io.ReadAtLeast({0x7d3930, 0x20d3950}, {0x20f4324, 0x9, 0x9}, 0x9) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x20c0e48 sp=0x20c0e1c pc=0x104280 Aug 30 12:35:56 volumio go-librespot[2070]: io.ReadFull(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/io/io.go:354 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.http2readFrameHeader({0x20f4324, 0x9, 0x9}, {0x7d3930, 0x20d3950}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x20c0e70 sp=0x20c0e48 pc=0x2f6e68 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*http2Framer).ReadFrame(0x20f4300) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x20c0eec sp=0x20c0e70 pc=0x2f7600 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*http2clientConnReadLoop).run(0x20c0fdc) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x20c0fa4 sp=0x20c0eec pc=0x31be1c Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*http2ClientConn).readLoop(0x1cef608) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x20c0fe4 sp=0x20c0fa4 pc=0x31b310 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*http2Transport).newClientConn.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x20c0fec sp=0x20c0fe4 pc=0x31435c Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20c0fec sp=0x20c0fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by net/http.(*http2Transport).newClientConn in goroutine 1110 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 89 gp=0x1c84ea8 m=nil [IO wait, 21937 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736854, 0xb600c8c8, 0x2, 0x2, 0x5) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c5487c sp=0x1c54868 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.netpollblock(0xb600c8b8, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1c54894 sp=0x1c5487c pc=0x547d4 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.runtime_pollWait(0xb600c8b8, 0x72) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1c548a8 sp=0x1c54894 pc=0x8f388 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).wait(0x1c28068, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c548bc sp=0x1c548a8 pc=0x10a2d8 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).waitRead(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*FD).Read(0x1c28050, {0x1d6e000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1c54904 sp=0x1c548bc pc=0x10b4c4 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*netFD).Read(0x1c28050, {0x1d6e000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1c54930 sp=0x1c54904 pc=0x17c438 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*conn).Read(0x1ec61c0, {0x1d6e000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1c5495c sp=0x1c54930 pc=0x18c618 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*TCPConn).Read(0x1ec61c0, {0x1d6e000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: :1 +0x44 fp=0x1c5497c sp=0x1c5495c pc=0x19f86c Aug 30 12:35:56 volumio go-librespot[2070]: io.(*multiReader).Read(0x1e6c590, {0x1d6e000, 0x1000, 0x1000}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1c549a8 sp=0x1c5497c pc=0x1054e8 Aug 30 12:35:56 volumio go-librespot[2070]: bufio.(*Reader).fill(0x1c125a0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1c549cc sp=0x1c549a8 pc=0x2bd8e0 Aug 30 12:35:56 volumio go-librespot[2070]: bufio.(*Reader).ReadByte(0x1c125a0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x1c549d8 sp=0x1c549cc pc=0x2be164 Aug 30 12:35:56 volumio go-librespot[2070]: nhooyr.io/websocket.readFrameHeader(0x1c125a0, {0x1ea22b8, 0x8, 0x8}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1c54a18 sp=0x1c549d8 pc=0x470950 Aug 30 12:35:56 volumio go-librespot[2070]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1ea2288, {0x7d7614, 0xbf6000}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1c54ad8 sp=0x1c54a18 pc=0x4735b4 Aug 30 12:35:56 volumio go-librespot[2070]: nhooyr.io/websocket.(*Conn).readLoop(0x1ea2288, {0x7d7614, 0xbf6000}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1c54b9c sp=0x1c54ad8 pc=0x47309c Aug 30 12:35:56 volumio go-librespot[2070]: nhooyr.io/websocket.(*Conn).reader(0x1ea2288, {0x7d7614, 0xbf6000}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1c54c28 sp=0x1c54b9c pc=0x4742b0 Aug 30 12:35:56 volumio go-librespot[2070]: nhooyr.io/websocket.(*Conn).Reader(...) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Aug 30 12:35:56 volumio go-librespot[2070]: nhooyr.io/websocket.(*Conn).Read(0x1ea2288, {0x7d7614, 0xbf6000}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1c54c50 sp=0x1c54c28 pc=0x472980 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*ConcreteApiServer).serve.func17({0x7d6f4c, 0x1dbc788}, 0x1dbc008) Aug 30 12:35:56 volumio go-librespot[2070]: /src/cmd/daemon/api_server.go:580 +0x3c0 fp=0x1c54ccc sp=0x1c54c50 pc=0x5e9490 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.HandlerFunc.ServeHTTP(0x1dba180, {0x7d6f4c, 0x1dbc788}, 0x1dbc008) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1c54cdc sp=0x1c54ccc pc=0x33d320 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*ServeMux).ServeHTTP(0x1db0000, {0x7d6f4c, 0x1dbc788}, 0x1dbc008) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1c54d18 sp=0x1c54cdc pc=0x33f180 Aug 30 12:35:56 volumio go-librespot[2070]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d6f4c, 0x1dbc788}, 0x1dbc008) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1c54d40 sp=0x1c54d18 pc=0x5e8e90 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.HandlerFunc.ServeHTTP(0x1db4160, {0x7d6f4c, 0x1dbc788}, 0x1dbc008) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1c54d50 sp=0x1c54d40 pc=0x33d320 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.serverHandler.ServeHTTP({0x1dbc0a8}, {0x7d6f4c, 0x1dbc788}, 0x1dbc008) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1c54d6c sp=0x1c54d50 pc=0x340910 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*conn).serve(0x1f96120, {0x7d7654, 0x1dc6180}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1c54fdc sp=0x1c54d6c pc=0x33bb60 Aug 30 12:35:56 volumio go-librespot[2070]: net/http.(*Server).Serve.gowrap3() Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1c54fec sp=0x1c54fdc pc=0x3411b4 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c54fec sp=0x1c54fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by net/http.(*Server).Serve in goroutine 23 Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/http/server.go:3285 +0x468 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 101 gp=0x1c85328 m=nil [select, 1129 minutes]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1df1ea8 sp=0x1df1e94 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.selectgo(0x1df1fa4, 0x1df1f6c, 0x0, 0x0, 0x5, 0x1) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1df1f48 sp=0x1df1ea8 pc=0x70d80 Aug 30 12:35:56 volumio go-librespot[2070]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1ea2288) Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1df1fe4 sp=0x1df1f48 pc=0x46d7fc Aug 30 12:35:56 volumio go-librespot[2070]: nhooyr.io/websocket.newConn.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1df1fec sp=0x1df1fe4 pc=0x46d3f0 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1df1fec sp=0x1df1fec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by nhooyr.io/websocket.newConn in goroutine 89 Aug 30 12:35:56 volumio go-librespot[2070]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Aug 30 12:35:56 volumio go-librespot[2070]: goroutine 1079 gp=0x204fb08 m=nil [IO wait]: Aug 30 12:35:56 volumio go-librespot[2070]: runtime.gopark(0x736854, 0xb600ca08, 0x2, 0x2, 0x5) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20bdd14 sp=0x20bdd00 pc=0x5cfc8 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.netpollblock(0xb600c9f8, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x20bdd2c sp=0x20bdd14 pc=0x547d4 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.runtime_pollWait(0xb600c9f8, 0x72) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20bdd40 sp=0x20bdd2c pc=0x8f388 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).wait(0x1ca61f8, 0x72, 0x0) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20bdd54 sp=0x20bdd40 pc=0x10a2d8 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*pollDesc).waitRead(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 30 12:35:56 volumio go-librespot[2070]: internal/poll.(*FD).Read(0x1ca61e0, {0x1f3a2ec, 0x3, 0x3}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20bdd9c sp=0x20bdd54 pc=0x10b4c4 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*netFD).Read(0x1ca61e0, {0x1f3a2ec, 0x3, 0x3}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x20bddc8 sp=0x20bdd9c pc=0x17c438 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*conn).Read(0x1f84020, {0x1f3a2ec, 0x3, 0x3}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x20bddf4 sp=0x20bddc8 pc=0x18c618 Aug 30 12:35:56 volumio go-librespot[2070]: net.(*TCPConn).Read(0x1f84020, {0x1f3a2ec, 0x3, 0x3}) Aug 30 12:35:56 volumio go-librespot[2070]: :1 +0x44 fp=0x20bde14 sp=0x20bddf4 pc=0x19f86c Aug 30 12:35:56 volumio go-librespot[2070]: io.ReadAtLeast({0x7d373c, 0x1f84020}, {0x1f3a2ec, 0x3, 0x3}, 0x3) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x20bde40 sp=0x20bde14 pc=0x104280 Aug 30 12:35:56 volumio go-librespot[2070]: io.ReadFull(...) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/io/io.go:354 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x1dcd340, {0x7d7634, 0xbf6000}) Aug 30 12:35:56 volumio go-librespot[2070]: /src/ap/shannon.go:124 +0x3a0 fp=0x20bdf04 sp=0x20bde40 pc=0x488a10 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x1daa008) Aug 30 12:35:56 volumio go-librespot[2070]: /src/ap/ap.go:276 +0x68 fp=0x20bdfe4 sp=0x20bdf04 pc=0x485188 Aug 30 12:35:56 volumio go-librespot[2070]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Aug 30 12:35:56 volumio go-librespot[2070]: /src/ap/ap.go:390 +0x28 fp=0x20bdfec sp=0x20bdfe4 pc=0x485e68 Aug 30 12:35:56 volumio go-librespot[2070]: runtime.goexit({}) Aug 30 12:35:56 volumio go-librespot[2070]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20bdfec sp=0x20bdfec pc=0x93c4c Aug 30 12:35:56 volumio go-librespot[2070]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 657 Aug 30 12:35:56 volumio go-librespot[2070]: /src/ap/ap.go:390 +0x120 Aug 30 12:35:56 volumio go-librespot[2070]: Aborted Aug 30 12:35:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Aug 30 12:35:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 12:35:56 volumio volumio[1071]: info: Connection to go-librespot Websocket closed Aug 30 12:35:59 volumio volumio[1071]: info: Initializing connection to go-librespot Websocket Aug 30 12:35:59 volumio volumio[1071]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 12:35:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 12:35:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 30 12:35:59 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 12:35:59 volumio systemd[1]: Started go-librespot Daemon. Aug 30 12:35:59 volumio go-librespot[13423]: go-librespot daemon starting... Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=info msg="running go-librespot 0.2.0" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="app state loaded" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=info msg="zeroconf server listening on port 37561" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="obtained new client token: AADwF3GUED6V1dh3vpbJYVDCWyAuCWJK827+ZVtNPvus0lSRdVBeMSto5geTSCg8Nd9r48Ppo0n9wagDhGfZo8OCs5Te3zM1KHZCgXFr2Te4zIfuzyqLBLxjo3FIXb3xg4urMKrpZ2bpgaXzO8F3ZLBX/S4ZsRNlUvAxsco4QF5rW5DKUCn/YNx7ztLdak5pMKgGofnCTUE5ivmNpDMT3klzGIAkM9KosZ4CeclOrK+o6a5kkDoMKcWCisA=" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="completed keyexchange" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="completed challenge" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=info msg="authenticated AP as 31g6rlr3bjgpast6eodg3eh2bnbu" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=info msg="authenticated Login5 as 31g6rlr3bjgpast6eodg3eh2bnbu" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="initializing zeroconf session, username: 31g6rlr3bjgpast6eodg3eh2bnbu" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="dealer connection opened" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=trace msg="starting accesspoint recv loop" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=trace msg="starting dealer recv loop" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=trace msg="received accesspoint ping" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=debug msg="received connection id: OWJhMjE2YTAtM2M1OS00YTkwLWJiMWItZTU0ZGQ3OWMxMDQxK2RlYWxlcit0Y3A6Ly8wYWIxNTA5Zi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQTIzRjEzRTcxNzhFREYzMzMzRkIzRkNCNjkzREJDNTI0MDJBRjgwNzVGQTlDQzAxNzkxRTY2NjAzRjMwQTlGRg==" Aug 30 12:35:59 volumio go-librespot[13423]: time="2025-08-30T12:35:59+02:00" level=trace msg="received accesspoint pong ack" Aug 30 12:36:00 volumio go-librespot[13423]: time="2025-08-30T12:36:00+02:00" level=debug msg="put connect state because NEW_DEVICE" Aug 30 12:36:01 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 30 12:36:01 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 Aug 30 12:36:01 volumio volumio[1071]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Aug 30 12:36:01 volumio volumio[1071]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 30 12:36:01 volumio volumio[1071]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Aug 30 12:36:01 volumio volumio[1071]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Aug 30 12:36:01 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:36:01 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Aug 30 12:36:01 volumio volumio[1071]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Aug 30 12:36:01 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:36:01 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:281:22 Aug 30 12:36:01 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:36:01 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:36:01 volumio volumio[1071]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Aug 30 12:36:01 volumio volumio[1071]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 30 12:36:01 volumio volumio[1071]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Aug 30 12:36:01 volumio volumio[1071]: at /volumio/node_modules/express/lib/router/index.js:284:7 Aug 30 12:36:01 volumio volumio[1071]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 30 12:36:01 volumio volumio[1071]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 30 12:36:01 volumio volumio[1071]: info: Listing playlists Aug 30 12:36:01 volumio volumio[1071]: info: Listing playlists Aug 30 12:36:02 volumio volumio[1071]: info: Initializing connection to go-librespot Websocket Aug 30 12:36:02 volumio go-librespot[13423]: time="2025-08-30T12:36:02+02:00" level=debug msg="new websocket client" Aug 30 12:36:02 volumio volumio[1071]: info: Connection to go-librespot Websocket established Aug 30 12:36:05 volumio volumio[1071]: info: Getting Spotify volume Aug 30 12:36:05 volumio volumio[1071]: info: Spotify volume: 100 Aug 30 12:36:05 volumio volumio[1071]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 30 12:36:05 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 30 12:36:05 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 Aug 30 12:36:05 volumio volumio[1071]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 12:36:05 volumio volumio[1071]: TypeError: Cannot read property 'name' of undefined Aug 30 12:36:05 volumio volumio[1071]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 30 12:36:05 volumio volumio[1071]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Aug 30 12:36:05 volumio volumio[1071]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Aug 30 12:36:05 volumio volumio[1071]: at Socket.emit (events.js:315:20) Aug 30 12:36:05 volumio volumio[1071]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 30 12:36:05 volumio volumio[1071]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 30 12:36:05 volumio volumio[1071]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 12:36:05 volumio sudo[13466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 12:35 Aug 30 12:36:05 volumio sudo[13466]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"