-- Logs begin at Sun 2025-06-29 19:22:20 CEST, end at Mon 2025-06-30 16:20:38 CEST. -- Jun 30 16:19:08 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:19:08 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:19:08 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:19:08 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:19:08 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:19:08 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:19:08 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:08 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:19:08 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:19:08 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:08 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:19:08 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:08 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:08 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:19:08 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:08 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:19:08 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:19:08 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:08 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:09 radio volumio[963]: info: Listing playlists Jun 30 16:19:09 radio volumio[963]: info: Listing playlists Jun 30 16:19:18 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:19:18 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:19:18 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:19:18 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:19:18 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:19:18 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:19:18 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:18 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:19:18 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:19:18 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:18 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:19:18 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:18 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:18 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:19:18 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:18 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:19:18 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:19:18 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:18 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:23 radio go-librespot[1255]: time="2025-06-30T16:19:23+02:00" level=trace msg="received accesspoint ping" Jun 30 16:19:23 radio go-librespot[1255]: time="2025-06-30T16:19:23+02:00" level=trace msg="received accesspoint pong ack" Jun 30 16:19:23 radio go-librespot[1255]: time="2025-06-30T16:19:23+02:00" level=trace msg="sent dealer ping" Jun 30 16:19:24 radio go-librespot[1255]: time="2025-06-30T16:19:24+02:00" level=trace msg="received dealer pong" Jun 30 16:19:28 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:19:28 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:19:28 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:19:28 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:19:28 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:19:28 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:19:28 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:28 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:19:28 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:19:28 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:28 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:19:28 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:28 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:28 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:19:28 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:28 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:19:28 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:19:28 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:28 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:29 radio volumio[963]: info: Listing playlists Jun 30 16:19:29 radio volumio[963]: info: Listing playlists Jun 30 16:19:38 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:19:38 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:19:38 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:19:38 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:19:38 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:19:38 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:19:38 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:38 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:19:38 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:19:38 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:38 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:19:38 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:38 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:38 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:19:38 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:38 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:19:38 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:19:38 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:38 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:48 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:19:48 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:19:48 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:19:48 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:19:48 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:19:48 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:19:48 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:48 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:19:48 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:19:48 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:48 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:19:48 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:48 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:48 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:19:48 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:48 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:19:48 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:19:48 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:48 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:49 radio volumio[963]: info: Listing playlists Jun 30 16:19:49 radio volumio[963]: info: Listing playlists Jun 30 16:19:53 radio go-librespot[1255]: time="2025-06-30T16:19:53+02:00" level=trace msg="sent dealer ping" Jun 30 16:19:54 radio go-librespot[1255]: time="2025-06-30T16:19:54+02:00" level=trace msg="received dealer pong" Jun 30 16:19:58 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:19:58 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:19:58 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:19:58 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:19:58 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:19:58 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:19:58 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:58 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:19:58 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:19:58 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:58 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:19:58 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:58 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:19:58 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:19:58 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:19:58 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:19:58 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:19:58 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:19:58 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:20:08 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:20:08 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:20:08 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:20:08 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:20:08 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:20:08 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:20:08 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:08 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:20:08 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:20:08 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:08 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:20:08 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:20:08 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:20:08 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:20:08 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:08 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:20:08 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:20:08 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:20:08 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:20:09 radio volumio[963]: info: Listing playlists Jun 30 16:20:09 radio volumio[963]: info: Listing playlists Jun 30 16:20:09 radio volumio[963]: info: Jun 30 16:20:09 radio volumio[963]: ---------------------------- MPD announces state update: player Jun 30 16:20:09 radio volumio[963]: info: ControllerMpd::getState Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Jun 30 16:20:09 radio volumio[963]: info: Jun 30 16:20:09 radio volumio[963]: ---------------------------- MPD announces state update: player Jun 30 16:20:09 radio volumio[963]: info: ControllerMpd::getState Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Jun 30 16:20:09 radio volumio[963]: info: Jun 30 16:20:09 radio volumio[963]: ---------------------------- MPD announces state update: player Jun 30 16:20:09 radio volumio[963]: info: ControllerMpd::getState Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Jun 30 16:20:09 radio volumio[963]: info: sendMpdCommand status took 6 milliseconds Jun 30 16:20:09 radio volumio[963]: info: sendMpdCommand status took 8 milliseconds Jun 30 16:20:09 radio volumio[963]: info: sendMpdCommand status took 7 milliseconds Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::parseState Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::parseState Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::parseState Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 16:20:09 radio volumio[963]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 30 16:20:09 radio volumio[963]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 30 16:20:09 radio volumio[963]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::parseTrackInfo Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::parseTrackInfo Jun 30 16:20:09 radio volumio[963]: verbose: ControllerMpd::parseTrackInfo Jun 30 16:20:09 radio volumio[963]: info: ControllerMpd::pushState Jun 30 16:20:09 radio volumio[963]: info: CoreCommandRouter::servicePushState Jun 30 16:20:09 radio volumio[963]: verbose: In UPNP mode Jun 30 16:20:09 radio volumio[963]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10281537,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"fm-evropa2-128","artist":"Evropa 2","album":null,"uri":"https://ice.actve.net/fm-evropa2-128","trackType":""} Jun 30 16:20:09 radio volumio[963]: verbose: CURRENT POSITION 0 Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::syncState stateService pause Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::syncState currentStatus play Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::pushState Jun 30 16:20:09 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:20:09 radio volumio[963]: info: ControllerMpd::pushState Jun 30 16:20:09 radio volumio[963]: info: CoreCommandRouter::servicePushState Jun 30 16:20:09 radio volumio[963]: verbose: In UPNP mode Jun 30 16:20:09 radio volumio[963]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10281537,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"fm-evropa2-128","artist":"Evropa 2","album":null,"uri":"https://ice.actve.net/fm-evropa2-128","trackType":""} Jun 30 16:20:09 radio volumio[963]: verbose: CURRENT POSITION 0 Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::syncState stateService pause Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::syncState currentStatus play Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::pushState Jun 30 16:20:09 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:20:09 radio volumio[963]: info: ControllerMpd::pushState Jun 30 16:20:09 radio volumio[963]: info: CoreCommandRouter::servicePushState Jun 30 16:20:09 radio volumio[963]: verbose: In UPNP mode Jun 30 16:20:09 radio volumio[963]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10281537,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"fm-evropa2-128","artist":"Evropa 2","album":null,"uri":"https://ice.actve.net/fm-evropa2-128","trackType":""} Jun 30 16:20:09 radio volumio[963]: verbose: CURRENT POSITION 0 Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::syncState stateService pause Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::syncState currentStatus play Jun 30 16:20:09 radio volumio[963]: info: CoreStateMachine::pushState Jun 30 16:20:09 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:20:09 radio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 30 16:20:09 radio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 30 16:20:09 radio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 30 16:20:09 radio volumio[963]: info: ------------------------------ 32ms Jun 30 16:20:09 radio volumio[963]: info: ------------------------------ 27ms Jun 30 16:20:09 radio volumio[963]: info: ------------------------------ 26ms Jun 30 16:20:18 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:20:18 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:20:18 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:20:18 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:20:18 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:20:18 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:20:18 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:18 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:20:18 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:20:18 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:18 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:20:18 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:20:18 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:20:18 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:20:18 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:18 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:20:18 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:20:18 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:20:18 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:20:23 radio go-librespot[1255]: time="2025-06-30T16:20:23+02:00" level=trace msg="sent dealer ping" Jun 30 16:20:24 radio go-librespot[1255]: time="2025-06-30T16:20:24+02:00" level=trace msg="received dealer pong" Jun 30 16:20:28 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:20:28 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:20:28 radio volumio[963]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 30 16:20:28 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:20:28 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:20:28 radio volumio[963]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 30 16:20:28 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:28 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 30 16:20:28 radio volumio[963]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 30 16:20:28 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:28 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 30 16:20:28 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:20:28 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:20:28 radio volumio[963]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 30 16:20:28 radio volumio[963]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 30 16:20:28 radio volumio[963]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 30 16:20:28 radio volumio[963]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 30 16:20:28 radio volumio[963]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 30 16:20:28 radio volumio[963]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 30 16:20:29 radio volumio[963]: info: Listing playlists Jun 30 16:20:29 radio volumio[963]: info: Listing playlists Jun 30 16:20:31 radio go-librespot[1255]: time="2025-06-30T16:20:31+02:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update Jun 30 16:20:31 radio go-librespot[1255]: time="2025-06-30T16:20:31+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 162" Jun 30 16:20:31 radio go-librespot[1255]: time="2025-06-30T16:20:31+02:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update Jun 30 16:20:31 radio go-librespot[1255]: time="2025-06-30T16:20:31+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 143" Jun 30 16:20:34 radio go-librespot[1255]: time="2025-06-30T16:20:34+02:00" level=debug msg="handling transfer player command from 4811722a5c222bf659961dc0e5be4660e0685732" Jun 30 16:20:34 radio go-librespot[1255]: time="2025-06-30T16:20:34+02:00" level=debug msg="renewing login5 access token" Jun 30 16:20:34 radio go-librespot[1255]: time="2025-06-30T16:20:34+02:00" level=info msg="authenticated Login5 as 11152257622" Jun 30 16:20:34 radio go-librespot[1255]: time="2025-06-30T16:20:34+02:00" level=debug msg="resolved context of track" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:34 radio go-librespot[1255]: time="2025-06-30T16:20:34+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:35 radio go-librespot[1255]: time="2025-06-30T16:20:35+02:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:35 radio go-librespot[1255]: time="2025-06-30T16:20:35+02:00" level=trace msg="fetched new page 1 with 50 items (list: 100)" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:35 radio go-librespot[1255]: time="2025-06-30T16:20:35+02:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:35 radio go-librespot[1255]: time="2025-06-30T16:20:35+02:00" level=trace msg="fetched new page 2 with 50 items (list: 150)" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=trace msg="fetched new page 3 with 50 items (list: 200)" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=warning msg="failed seeking to track in context spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" error="failed fetching tracks for seek: failed moving to next index 200 (page 4): failed fetching next page: invalid status code from page at radio-apollo/v5/tracks/spotify:station:playlist:7vfDky3F8rUo6jueOklt0O?salt=749108&autoplay=false&count=50&isVideo=false&prev_tracks=0E0kxko3i9b5JxxMoGH3At,6TfBA04WJ3X1d1wXhaCFVT,1Mys1gf9SkMBAVGGxpkJ7d,1Cj2vqUwlJVG27gJrun92y,6s4E1Wsbq5X9eHWprIQXIB,7zbbclmHhDSdsq63O6c8eH,1y3bE5i57eUm3hfLAc7h08,6kXNnS7JJz0nAv4XEwEmQJ,3JvKfv6T31zO0ini8iNItO,2U8g9wVcUu9wsg6i7sFSv8,0kHyKhJMukHEPfeh8s7rdM,7KkjTn0eDfjhyNLkhnEjCl,7xDd7gl6AGgpiOz5trz4dM,4eDYMhIin1pSLIG96f1aD0,60eOMEt3WNVX1m1jmApmnX,0BCPKOYdS2jbQ8iyB56Zns,3Vr3zh0r7ALn8VLqCiRR10,52yUIbj0YVqxyz9cA0WSPA,0eO2zq5fjPt41BreFmiIKw,1r1fPuhj9H4VdXr7OK6FL5,64fwanXDvwmsQrLsDSVHMv,3RiPr603aXAoi4GHyXx0uy,1Mf27cnAF1Q6Ko83XTM5d1,04aAxqtGp5pv12UXAg4pkq,3zT2616WcD9Df5m21OsCX0,2nLtzopw4rPReszdYBJU6h,3rmo8F54jFF8OgYsqTxm5d,6nek1Nin9q48AVZcWs9e9D,4rPkN1FMzQyFNP9cLUGIIB,5w6B0sAH7XauCvMOAtplQj,0LtkAAEpEp1lkLX1ZvjHZb,104buTcnP2AsxqB7U1FIZ4,0y1QJc3SJVPKJ1OvFmFqe6,7kbTZWt7DnzIzbkyzFE1PW,2kWowW0k4oFymhkr7LmvzO,5hnyJvgoWiQUYZttV4wXy6,2wXfd4Vz6pzJrrPvGhpYz0,70eDxAyAraNTiD6lx2ZEnH,3p94RpSxQdZocUfrzpemmm,3bWAqKDWg6u1davspr5IkS,0nrRP2bk19rLc0orkWPQk2,7nAfXgeHfDO50upcOjJOaq,0xBlufYjHrtf8xk0QifNn1,3dYD57lRAUcMHufyqn9GcI,7DnAm9FOTWE3cUvso43HhI,47QDvqfpUTIGckdCacLd8P,6XXoKFeQAnOsm2dj5aq4hq,18lR4BzEs7e3qzc0KVkTpU,1QO4QjFDpibHdYoOlTGH6x,1SKPmfSYaPsETbRHaiA18G&minimal=true?salt=858850&autoplay=false&count=50&isVideo=false&prev_tracks=0E0kxko3i9b5JxxMoGH3At,6TfBA04WJ3X1d1wXhaCFVT,1Mys1gf9SkMBAVGGxpkJ7d,1Cj2vqUwlJVG27gJrun92y,52yUIbj0YVqxyz9cA0WSPA,6s4E1Wsbq5X9eHWprIQXIB,3zT2616WcD9Df5m21OsCX0,0BCPKOYdS2jbQ8iyB56Zns,1Mf27cnAF1Q6Ko83XTM5d1,6kXNnS7JJz0nAv4XEwEmQJ,7zbbclmHhDSdsq63O6c8eH,60eOMEt3WNVX1m1jmApmnX,1y3bE5i57eUm3hfLAc7h08,104buTcnP2AsxqB7U1FIZ4,6nek1Nin9q48AVZcWs9e9D,4eDYMhIin1pSLIG96f1aD0,7KkjTn0eDfjhyNLkhnEjCl,5w6B0sAH7XauCvMOAtplQj,2wXfd4Vz6pzJrrPvGhpYz0,1r1fPuhj9H4VdXr7OK6FL5,3RiPr603aXAoi4GHyXx0uy,3JvKfv6T31zO0ini8iNItO,3rmo8F54jFF8OgYsqTxm5d,04aAxqtGp5pv12UXAg4pkq,0eO2zq5fjPt41BreFmiIKw,0y1QJc3SJVPKJ1OvFmFqe6,2U8g9wVcUu9wsg6i7sFSv8,0kHyKhJMukHEPfeh8s7rdM,7DnAm9FOTWE3cUvso43HhI,64fwanXDvwmsQrLsDSVHMv,7sA2SKTo1QbTSSYn5YvJC4,7pqgMEKsDMOHUdFQ7n0N9K,3Vr3zh0r7ALn8VLqCiRR10,6e13443Ve7RGcAUScTgYtl,5g7rJvWYVrloJZwKiShqlS,2nLtzopw4rPReszdYBJU6h,7xDd7gl6AGgpiOz5trz4dM,0CokSRCu5hZgPxcZBaEzVE,1SKPmfSYaPsETbRHaiA18G,2E43WFS4rRc09za2r2GmZl,3bWAqKDWg6u1davspr5IkS,7oVEtyuv9NBmnytsCIsY5I,6TEftSkmWdTm1EpThnwqh6,3CNLrZJST54nE4PUkS83Sl,0oF9jpn0n8afMpbHKgRd6J,3p94RpSxQdZocUfrzpemmm,0nrRP2bk19rLc0orkWPQk2,7nAfXgeHfDO50upcOjJOaq,0FQA22KGLQdDesHAJtrEEE,7w87IxuO7BDcJ3YUqCyMTT?salt=865729&autoplay=false&count=50&isVideo=false&prev_tracks=0E0kxko3i9b5JxxMoGH3At,6TfBA04WJ3X1d1wXhaCFVT,7zbbclmHhDSdsq63O6c8eH,1Mys1gf9SkMBAVGGxpkJ7d,1Cj2vqUwlJVG27gJrun92y,6s4E1Wsbq5X9eHWprIQXIB,1y3bE5i57eUm3hfLAc7h08,0BCPKOYdS2jbQ8iyB56Zns,3JvKfv6T31zO0ini8iNItO,52yUIbj0YVqxyz9cA0WSPA,1r1fPuhj9H4VdXr7OK6FL5,4eDYMhIin1pSLIG96f1aD0,0eO2zq5fjPt41BreFmiIKw,3rmo8F54jFF8OgYsqTxm5d,1Mf27cnAF1Q6Ko83XTM5d1,7KkjTn0eDfjhyNLkhnEjCl,3RiPr603aXAoi4GHyXx0uy,6kXNnS7JJz0nAv4XEwEmQJ,3zT2616WcD9Df5m21OsCX0,60eOMEt3WNVX1m1jmApmnX,0kHyKhJMukHEPfeh8s7rdM,5w6B0sAH7XauCvMOAtplQj,04aAxqtGp5pv12UXAg4pkq,64fwanXDvwmsQrLsDSVHMv,7xDd7gl6AGgpiOz5trz4dM,6nek1Nin9q48AVZcWs9e9D,1SKPmfSYaPsETbRHaiA18G,7pqgMEKsDMOHUdFQ7n0N9K,3dYD57lRAUcMHufyqn9GcI,3Vr3zh0r7ALn8VLqCiRR10,2wXfd4Vz6pzJrrPvGhpYz0,2U8g9wVcUu9wsg6i7sFSv8,4rPkN1FMzQyFNP9cLUGIIB,3p94RpSxQdZocUfrzpemmm,5g7rJvWYVrloJZwKiShqlS,7kbTZWt7DnzIzbkyzFE1PW,2FY7b99s15jUprqC0M5NCT,3AJwUDP919kvQ9QcozQPxg,3bWAqKDWg6u1davspr5IkS,2vzMWO0T008m86yDnXwkII,0y1QJc3SJVPKJ1OvFmFqe6,7DnAm9FOTWE3cUvso43HhI,0LtkAAEpEp1lkLX1ZvjHZb,0nrRP2bk19rLc0orkWPQk2,18lR4BzEs7e3qzc0KVkTpU,7w87IxuO7BDcJ3YUqCyMTT,3FxJXyFrSBMY3mgzHgU63b,3CNLrZJST54nE4PUkS83Sl,6TEftSkmWdTm1EpThnwqh6,21jGcNKet2qwijlDFuPiPb?salt=891477&autoplay=false&count=50&isVideo=false&prev_tracks=0E0kxko3i9b5JxxMoGH3At,6TfBA04WJ3X1d1wXhaCFVT,7zbbclmHhDSdsq63O6c8eH,1Cj2vqUwlJVG27gJrun92y,1Mys1gf9SkMBAVGGxpkJ7d,6kXNnS7JJz0nAv4XEwEmQJ,1y3bE5i57eUm3hfLAc7h08,6s4E1Wsbq5X9eHWprIQXIB,7KkjTn0eDfjhyNLkhnEjCl,1r1fPuhj9H4VdXr7OK6FL5,0kHyKhJMukHEPfeh8s7rdM,0BCPKOYdS2jbQ8iyB56Zns,3zT2616WcD9Df5m21OsCX0,3JvKfv6T31zO0ini8iNItO,52yUIbj0YVqxyz9cA0WSPA,104buTcnP2AsxqB7U1FIZ4,1Mf27cnAF1Q6Ko83XTM5d1,3rmo8F54jFF8OgYsqTxm5d,2wXfd4Vz6pzJrrPvGhpYz0,6nek1Nin9q48AVZcWs9e9D,4eDYMhIin1pSLIG96f1aD0,04aAxqtGp5pv12UXAg4pkq,5w6B0sAH7XauCvMOAtplQj,0y1QJc3SJVPKJ1OvFmFqe6,7xDd7gl6AGgpiOz5trz4dM,2U8g9wVcUu9wsg6i7sFSv8,2nLtzopw4rPReszdYBJU6h,0nrRP2bk19rLc0orkWPQk2,3bWAqKDWg6u1davspr5IkS,7DnAm9FOTWE3cUvso43HhI,0eO2zq5fjPt41BreFmiIKw,3RiPr603aXAoi4GHyXx0uy,3Vr3zh0r7ALn8VLqCiRR10,7kbTZWt7DnzIzbkyzFE1PW,1SKPmfSYaPsETbRHaiA18G,60eOMEt3WNVX1m1jmApmnX,2FY7b99s15jUprqC0M5NCT,3AJwUDP919kvQ9QcozQPxg,70eDxAyAraNTiD6lx2ZEnH,4rPkN1FMzQyFNP9cLUGIIB,6XXoKFeQAnOsm2dj5aq4hq,2NF8A7C6tICScdRaZ0BrEe,0LtkAAEpEp1lkLX1ZvjHZb,7exHT4swWOKL5addPeqkLP,3CNLrZJST54nE4PUkS83Sl,5g7rJvWYVrloJZwKiShqlS,3xkHsmpQCBMytMJNiDf3Ii,37sINbJZcFdHFAsVNsPq1i,7sA2SKTo1QbTSSYn5YvJC4,4pt5fDVTg5GhEvEtlz9dKk: 414" uri="spotify:station:playlist:7vfDky3F8rUo6jueOklt0O" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=debug msg="loading track (paused: true, position: 19339ms)" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=trace msg="emitting websocket event: will_play" Jun 30 16:20:36 radio volumio[963]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0E0kxko3i9b5JxxMoGH3At","play_origin":""}} Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=debug msg="selected format OGG_VORBIS_320 (b270ea26b42c1785809a9cb3778b41af0960161b)" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=debug msg="requested aes key for file b270ea26b42c1785809a9cb3778b41af0960161b, gid: 0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3192" Jun 30 16:20:36 radio go-librespot[1255]: time="2025-06-30T16:20:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2039" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="fetched first chunk of 19, total size is 9571108 bytes" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=trace msg="seek to 19339ms (diff: 247ms, samples: 852849, bytes: 821376)" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="created new output device" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=info msg="loaded track \"My Life\" (paused: true, position: 19339ms, duration: 224512ms, prefetched: false)" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=trace msg="emitting websocket event: metadata" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=trace msg="emitting websocket event: active" Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="sending successful reply for dealer request" Jun 30 16:20:37 radio volumio[963]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0E0kxko3i9b5JxxMoGH3At","name":"My Life","artist_names":["Imagine Dragons"],"album_name":"Mercury - Acts 1 \u0026 2","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02fc915b69600dce2991a61f13","position":19339,"duration":224512,"release_date":"year:2022 month:7 day:1","track_number":2,"disc_number":1}} Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 30 16:20:37 radio volumio[963]: SPOTIFY: received: {"type":"active","data":null} Jun 30 16:20:37 radio volumio[963]: info: Aligning Spotify Volume to Volumio Volume Jun 30 16:20:37 radio volumio[963]: info: CoreCommandRouter::volumioGetState Jun 30 16:20:37 radio volumio[963]: info: CorePlayQueue::getTrack 0 Jun 30 16:20:37 radio volumio[963]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 16:20:37 radio volumio[963]: TypeError: Cannot read property 'name' of undefined Jun 30 16:20:37 radio volumio[963]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 16:20:37 radio volumio[963]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 16:20:37 radio volumio[963]: at ControllerSpotify.alignSpotifyVolumeToVolumioVolume (/data/plugins/music_service/spop/index.js:598:36) Jun 30 16:20:37 radio volumio[963]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:288:18) Jun 30 16:20:37 radio volumio[963]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) Jun 30 16:20:37 radio volumio[963]: at WebSocket.emit (events.js:315:20) Jun 30 16:20:37 radio volumio[963]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jun 30 16:20:37 radio volumio[963]: at Receiver.emit (events.js:315:20) Jun 30 16:20:37 radio volumio[963]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jun 30 16:20:37 radio volumio[963]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jun 30 16:20:37 radio volumio[963]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jun 30 16:20:37 radio volumio[963]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jun 30 16:20:37 radio volumio[963]: at writeOrBuffer (internal/streams/writable.js:358:12) Jun 30 16:20:37 radio volumio[963]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jun 30 16:20:37 radio volumio[963]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jun 30 16:20:37 radio volumio[963]: at Socket.emit (events.js:315:20) Jun 30 16:20:37 radio volumio[963]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 16:20:37 radio go-librespot[1255]: time="2025-06-30T16:20:37+02:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=trace msg="emitting websocket event: paused" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="handling play player command from 4811722a5c222bf659961dc0e5be4660e0685732" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:5Ytqb8KX2Nta9bCIvCiiGN" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=trace msg="fetched new page 0 with 195 items (list: 195)" uri="spotify:playlist:5Ytqb8KX2Nta9bCIvCiiGN" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="shuffled context with seed 2246362506677652658 (len: 195, keep: -1)" uri="spotify:playlist:5Ytqb8KX2Nta9bCIvCiiGN" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:3yI282DoIJyERd0vGukMoB" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="fetched chunk 4/18, size: 524288" uri="spotify:track:0E0kxko3i9b5JxxMoGH3At" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=trace msg="emitting websocket event: will_play" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=warning msg="original track has no formats, alternatives have a total of 8" uri="spotify:track:3yI282DoIJyERd0vGukMoB" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="selected format OGG_VORBIS_320 (e9267dc2543c28e970ce1ead776b6aeb81f045cb)" uri="spotify:track:3yI282DoIJyERd0vGukMoB" Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="requested aes key for file e9267dc2543c28e970ce1ead776b6aeb81f045cb, gid: 3yI282DoIJyERd0vGukMoB" Jun 30 16:20:38 radio sudo[25102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 16:19 Jun 30 16:20:38 radio sudo[25102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 16:20:38 radio go-librespot[1255]: time="2025-06-30T16:20:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 170" 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"