-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2025-06-15 17:54:09 CEST. -- Jun 15 17:53:03 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:03 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:03 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:03 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:03 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:03 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:53:03 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:03 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:03 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:03 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:03 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:03 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:03 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:03 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:03 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:03 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:53:03 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:53:03 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:03 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:13 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:13 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:13 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:13 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:13 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:13 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:53:13 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:13 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:13 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:13 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:13 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:13 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:13 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:13 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:13 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:13 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:53:13 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:53:13 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:13 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:13 garaz-volumio volumio[905]: info: Listing playlists Jun 15 17:53:13 garaz-volumio volumio[905]: info: Listing playlists Jun 15 17:53:21 garaz-volumio volumio[905]: info: Jun 15 17:53:21 garaz-volumio volumio[905]: ---------------------------- Client requests Volume 77 Jun 15 17:53:21 garaz-volumio volumio[905]: info: VolumeController::SetAlsaVolume77 Jun 15 17:53:21 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:21 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:21 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:21 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:21 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:21 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:21 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:21 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:53:21 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:21 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:21 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:21 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:21 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:21 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:21 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:21 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:21 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:21 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:53:21 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:53:21 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:21 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:21 garaz-volumio volumio[905]: info: Clearing queue after UPNP request Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreStateMachine::ClearQueue Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreStateMachine::stop Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreStateMachine::stPlaybackTimer Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreStateMachine::updateTrackBlock Jun 15 17:53:22 garaz-volumio volumio[905]: info: CorePlayQueue::getTrackBlock Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:22 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioPushState Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreStateMachine::serviceStop Jun 15 17:53:22 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:22 garaz-volumio volumio[905]: info: ControllerMpd::stop Jun 15 17:53:22 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 17:53:22 garaz-volumio volumio[905]: info: CorePlayQueue::clearPlayQueue Jun 15 17:53:22 garaz-volumio volumio[905]: info: CorePlayQueue::saveQueue Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioPushState Jun 15 17:53:22 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioPushQueue Jun 15 17:53:22 garaz-volumio volumio[905]: error: Upnp client error: Error: This socket has been ended by the other party Jun 15 17:53:22 garaz-volumio volumio[905]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Jun 15 17:53:22 garaz-volumio volumio[905]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Jun 15 17:53:22 garaz-volumio volumio[905]: info: sendMpdCommand stop took 37 milliseconds Jun 15 17:53:23 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:23 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:23 garaz-volumio volumio[905]: info: Starting UPNP Playback Jun 15 17:53:23 garaz-volumio volumio[905]: info: Preparing playback through UPNP Jun 15 17:53:23 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:23 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:23 garaz-volumio volumio[905]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces system playlist update Jun 15 17:53:24 garaz-volumio volumio[905]: info: Ignoring MPD Status Update Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces system playlist update Jun 15 17:53:24 garaz-volumio volumio[905]: info: Ignoring MPD Status Update Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces system playlist update Jun 15 17:53:24 garaz-volumio volumio[905]: info: Ignoring MPD Status Update Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 10ms Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 8ms Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 6ms Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand status took 17 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand status took 13 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand status took 8 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:24 garaz-volumio volumio[905]: info: Jun 15 17:53:24 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand status took 18 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand status took 9 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand status took 7 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ypsOwZE-zBvJvJkMG4MeDA.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/ypsOwZE-zBvJvJkMG4MeDA.mp3","trackType":"mp3"} Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus stop Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"ypsOwZE-zBvJvJkMG4MeDA.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/ypsOwZE-zBvJvJkMG4MeDA.mp3","trackType":"mp3"} Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:24 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"ypsOwZE-zBvJvJkMG4MeDA.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/ypsOwZE-zBvJvJkMG4MeDA.mp3","trackType":"mp3"} Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:24 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 67ms Jun 15 17:53:24 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:24 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 63ms Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 57ms Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"ypsOwZE-zBvJvJkMG4MeDA.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/ypsOwZE-zBvJvJkMG4MeDA.mp3","trackType":"mp3"} Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:24 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"ypsOwZE-zBvJvJkMG4MeDA.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/ypsOwZE-zBvJvJkMG4MeDA.mp3","trackType":"mp3"} Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:24 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"ypsOwZE-zBvJvJkMG4MeDA.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/ypsOwZE-zBvJvJkMG4MeDA.mp3","trackType":"mp3"} Jun 15 17:53:24 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:24 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:24 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:24 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:24 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:24 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:24 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 75ms Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 64ms Jun 15 17:53:24 garaz-volumio volumio[905]: info: ------------------------------ 61ms Jun 15 17:53:25 garaz-volumio volumio[905]: info: Jun 15 17:53:25 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:25 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:25 garaz-volumio volumio[905]: info: Jun 15 17:53:25 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:25 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:25 garaz-volumio volumio[905]: info: Jun 15 17:53:25 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:25 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:25 garaz-volumio volumio[905]: info: sendMpdCommand status took 8 milliseconds Jun 15 17:53:25 garaz-volumio volumio[905]: info: sendMpdCommand status took 6 milliseconds Jun 15 17:53:25 garaz-volumio volumio[905]: info: sendMpdCommand status took 4 milliseconds Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:25 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 15 17:53:25 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 15 17:53:25 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:25 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"tTsbbmFEZsL_uEYztPqoZg.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/tTsbbmFEZsL_uEYztPqoZg.mp3","trackType":"mp3"} Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:25 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:25 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:25 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"tTsbbmFEZsL_uEYztPqoZg.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/tTsbbmFEZsL_uEYztPqoZg.mp3","trackType":"mp3"} Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:25 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:25 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:25 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"tTsbbmFEZsL_uEYztPqoZg.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/tTsbbmFEZsL_uEYztPqoZg.mp3","trackType":"mp3"} Jun 15 17:53:25 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:25 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:25 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:25 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:25 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:25 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:25 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:25 garaz-volumio volumio[905]: info: ------------------------------ 43ms Jun 15 17:53:25 garaz-volumio volumio[905]: info: ------------------------------ 40ms Jun 15 17:53:25 garaz-volumio volumio[905]: info: ------------------------------ 37ms Jun 15 17:53:27 garaz-volumio volumio[905]: info: Jun 15 17:53:27 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:27 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:27 garaz-volumio volumio[905]: info: Jun 15 17:53:27 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:27 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:27 garaz-volumio volumio[905]: info: Jun 15 17:53:27 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:27 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:27 garaz-volumio volumio[905]: info: sendMpdCommand status took 10 milliseconds Jun 15 17:53:27 garaz-volumio volumio[905]: info: sendMpdCommand status took 7 milliseconds Jun 15 17:53:27 garaz-volumio volumio[905]: info: sendMpdCommand status took 4 milliseconds Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 17:53:27 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 15 17:53:27 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 15 17:53:27 garaz-volumio volumio[905]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: ControllerMpd::parseTrackInfo Jun 15 17:53:27 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:27 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Jrx_0AyjvI3MUfY828DEqQ.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/Jrx_0AyjvI3MUfY828DEqQ.mp3","trackType":"mp3"} Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:27 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:27 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:27 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:27 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:27 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:27 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:27 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:27 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:28 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Jrx_0AyjvI3MUfY828DEqQ.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/Jrx_0AyjvI3MUfY828DEqQ.mp3","trackType":"mp3"} Jun 15 17:53:28 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:28 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:28 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:28 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:28 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:28 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:28 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:28 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:28 garaz-volumio volumio[905]: verbose: In UPNP mode Jun 15 17:53:28 garaz-volumio volumio[905]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Jrx_0AyjvI3MUfY828DEqQ.mp3","artist":null,"album":null,"uri":"http://assist-home.local:8123/api/tts_proxy/Jrx_0AyjvI3MUfY828DEqQ.mp3","trackType":"mp3"} Jun 15 17:53:28 garaz-volumio volumio[905]: verbose: CURRENT POSITION 0 Jun 15 17:53:28 garaz-volumio volumio[905]: info: CoreStateMachine::syncState stateService play Jun 15 17:53:28 garaz-volumio volumio[905]: info: CoreStateMachine::syncState currentStatus play Jun 15 17:53:28 garaz-volumio volumio[905]: info: Received an update from plugin. extracting info from payload Jun 15 17:53:28 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:28 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:28 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:28 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:28 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:28 garaz-volumio volumio[905]: info: ------------------------------ 71ms Jun 15 17:53:28 garaz-volumio volumio[905]: info: ------------------------------ 69ms Jun 15 17:53:28 garaz-volumio volumio[905]: info: ------------------------------ 65ms Jun 15 17:53:29 garaz-volumio volumio[905]: info: Jun 15 17:53:29 garaz-volumio volumio[905]: ---------------------------- Client requests Volume 49 Jun 15 17:53:29 garaz-volumio volumio[905]: info: VolumeController::SetAlsaVolume49 Jun 15 17:53:30 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:30 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:30 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:30 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:30 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:30 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:30 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:30 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:53:30 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:30 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:30 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:30 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:30 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:30 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:30 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:30 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:30 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:30 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:53:30 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:53:30 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:30 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:31 garaz-volumio volumio[905]: info: Jun 15 17:53:31 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:31 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:31 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:31 garaz-volumio volumio[905]: info: Jun 15 17:53:31 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:31 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:31 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:31 garaz-volumio volumio[905]: info: Jun 15 17:53:31 garaz-volumio volumio[905]: ---------------------------- MPD announces state update: player Jun 15 17:53:31 garaz-volumio volumio[905]: info: ControllerMpd::getState Jun 15 17:53:31 garaz-volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Jun 15 17:53:31 garaz-volumio volumio[905]: info: sendMpdCommand status took 10 milliseconds Jun 15 17:53:31 garaz-volumio volumio[905]: info: sendMpdCommand status took 7 milliseconds Jun 15 17:53:31 garaz-volumio volumio[905]: info: sendMpdCommand status took 4 milliseconds Jun 15 17:53:31 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:31 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:31 garaz-volumio volumio[905]: verbose: ControllerMpd::parseState Jun 15 17:53:31 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:31 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:31 garaz-volumio volumio[905]: info: ControllerMpd::pushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CoreCommandRouter::servicePushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:31 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:31 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:31 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:31 garaz-volumio volumio[905]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:31 garaz-volumio volumio[905]: info: ------------------------------ 33ms Jun 15 17:53:31 garaz-volumio volumio[905]: info: ------------------------------ 28ms Jun 15 17:53:31 garaz-volumio volumio[905]: info: ------------------------------ 26ms Jun 15 17:53:31 garaz-volumio volumio[905]: info: Listing playlists Jun 15 17:53:31 garaz-volumio volumio[905]: info: Listing playlists Jun 15 17:53:33 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:33 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:33 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:33 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:33 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:33 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:53:33 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:33 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:33 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:33 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:33 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:33 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:33 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:33 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:33 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:33 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:53:33 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:53:33 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:33 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:38 garaz-volumio volumio[905]: info: Jun 15 17:53:38 garaz-volumio volumio[905]: ---------------------------- Client requests Volume 5 Jun 15 17:53:38 garaz-volumio volumio[905]: info: VolumeController::SetAlsaVolume5 Jun 15 17:53:38 garaz-volumio volumio[905]: info: CoreStateMachine::pushState Jun 15 17:53:38 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:38 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:38 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:38 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:38 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:38 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:38 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:53:38 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:38 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:38 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:38 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:38 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:38 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:38 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:38 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:38 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:38 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:53:38 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:53:38 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:38 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:43 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:43 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:43 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:43 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:43 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:43 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:53:43 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:43 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:43 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:43 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:43 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:43 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:43 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:43 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:43 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:43 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:53:43 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:53:43 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:43 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:53 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:53 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:53 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:53 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:53 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:53 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:53:53 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:53 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:53 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:53 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:53 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:53 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:53 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:53 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:53 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:53 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:53:53 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:53:53 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:53 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:54 garaz-volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 17:53:54 garaz-volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 17:53:54 garaz-volumio volumio[905]: info: Discovery: Getting this device information Jun 15 17:53:54 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:54 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:54 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:54 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:54 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:54 garaz-volumio volumio[905]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) Jun 15 17:53:54 garaz-volumio volumio[905]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 15 17:53:54 garaz-volumio volumio[905]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:617:45) Jun 15 17:53:54 garaz-volumio volumio[905]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 15 17:53:54 garaz-volumio volumio[905]: at RESTApiSystem.getSystemInfo (/volumio/app/plugins/user_interface/rest_api/system.js:38:41) Jun 15 17:53:54 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:54 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:54 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:54 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:54 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:54 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:54 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:54 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:54 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:54 garaz-volumio volumio[905]: info: Listing playlists Jun 15 17:53:54 garaz-volumio volumio[905]: info: Listing playlists Jun 15 17:53:55 garaz-volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 17:53:55 garaz-volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 17:53:55 garaz-volumio volumio[905]: info: Discovery: Getting this device information Jun 15 17:53:55 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:53:55 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:53:55 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:53:55 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:53:55 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:53:55 garaz-volumio volumio[905]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) Jun 15 17:53:55 garaz-volumio volumio[905]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 15 17:53:55 garaz-volumio volumio[905]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:617:45) Jun 15 17:53:55 garaz-volumio volumio[905]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 15 17:53:55 garaz-volumio volumio[905]: at RESTApiSystem.getSystemInfo (/volumio/app/plugins/user_interface/rest_api/system.js:38:41) Jun 15 17:53:55 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:55 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:53:55 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:53:55 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:53:55 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:53:55 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:53:55 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:53:55 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:53:55 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:54:03 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:54:03 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:54:03 garaz-volumio volumio[905]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 15 17:54:03 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:54:03 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:54:03 garaz-volumio volumio[905]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jun 15 17:54:03 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:54:03 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 15 17:54:03 garaz-volumio volumio[905]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 15 17:54:03 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:54:03 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 15 17:54:03 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:54:03 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:54:03 garaz-volumio volumio[905]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 15 17:54:03 garaz-volumio volumio[905]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 15 17:54:03 garaz-volumio volumio[905]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jun 15 17:54:03 garaz-volumio volumio[905]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jun 15 17:54:03 garaz-volumio volumio[905]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 15 17:54:03 garaz-volumio volumio[905]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 15 17:54:03 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:03+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 15 17:54:03 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1422" Jun 15 17:54:03 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:03+02:00" level=trace msg="closed output device because of stop command" Jun 15 17:54:04 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:04+02:00" level=debug msg="put connect state inactive" Jun 15 17:54:04 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:04+02:00" level=trace msg="emitting websocket event: inactive" Jun 15 17:54:04 garaz-volumio volumio[905]: SPOTIFY: received: {"type":"inactive","data":null} Jun 15 17:54:04 garaz-volumio volumio[905]: error: Failed to decode event: inactive Jun 15 17:54:04 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:04+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.100.123:44068->104.199.65.9:4070: use of closed network connection" Jun 15 17:54:05 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:05+02:00" level=debug msg="obtained new client token: AAA6nabbxWA0ESxpnS2Un8NhwypXnGTI7Z6hTvH7yaGIOecgsUGxBi8t9j3YjpmWPRTRdyYfjz6R1AoVJlxGenjlZJSg1PGhPZcQ65pyZJqo1ikVOwQ61XsQeP2PIcqn72aAaX9PStBOalgmI6o3Be77071OKrdQX5Ho1VYziXMJESvVKbUM87QFtmWNrFcJgMG6p4zYanKt7NM0uWWTJITI6Gs3YFmaNIlXA1jIT2t5bg99bzFSIiYx" Jun 15 17:54:05 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:05+02:00" level=debug msg="completed keyexchange" Jun 15 17:54:05 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:05+02:00" level=debug msg="completed challenge" Jun 15 17:54:05 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:05+02:00" level=debug msg="authenticated as 31ykrfcoejvq3eri6uosxaghgvou" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:05+02:00" level=debug msg="authenticated as 31ykrfcoejvq3eri6uosxaghgvou" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="dealer connection opened" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="restored session after logout, username: 31ykrfcoejvq3eri6uosxaghgvou" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="autoplay enabled: false" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="received connection id: NTcwNTkzMzItYmVmMy00ODE0LWFmNWMtZWU5ZjUxYjJjYmJjK2RlYWxlcit0Y3A6Ly8wYWNhNDE1Yi5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArOEI0QTcxNTNDOUJDMUMxQzMyRUNGMTNENDMzMzBGQzY3QjMxRkQ5MjIyOTI1RjQ0NzBDMTExN0NEQzBGRDE3Rg==" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="handling transfer player command from 1cf47eeb6556017035403d4c1f1151f8d37ec976" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="resolved context of track" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=trace msg="emitting websocket event: will_play" Jun 15 17:54:06 garaz-volumio volumio[905]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0CgGl8mZvzHvyHmNhNAIUv","play_origin":"go-librespot"}} Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="selected format OGG_VORBIS_320 (4052a8d047e71039b0ae4fc8230df99f76fad8dc)" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="requested aes key for file 4052a8d047e71039b0ae4fc8230df99f76fad8dc, gid: 0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:06 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1418" Jun 15 17:54:07 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:07+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1156" Jun 15 17:54:07 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:07+02:00" level=debug msg="fetched first chunk of 19, total size is 9944008 bytes" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:07 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:07+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:07 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:07+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10068 us, period size = 444 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 49 frames" Jun 15 17:54:07 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:07+02:00" level=debug msg="created new output device" Jun 15 17:54:07 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:07+02:00" level=info msg="loaded track \"Slepic pirka\" (paused: true, position: 0ms, duration: 217333ms, prefetched: false)" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=trace msg="emitting websocket event: metadata" Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=trace msg="emitting websocket event: active" Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=debug msg="sending successful reply for dealer request" Jun 15 17:54:08 garaz-volumio volumio[905]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0CgGl8mZvzHvyHmNhNAIUv","name":"Slepic pirka","artist_names":["Mig 21"],"album_name":"Best Of","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020c02bf09830aa18d27130e25","position":0,"duration":217333,"release_date":"year:2006 month:1 day:1","track_number":17,"disc_number":1}} Jun 15 17:54:08 garaz-volumio volumio[905]: SPOTIFY: received: {"type":"active","data":null} Jun 15 17:54:08 garaz-volumio volumio[905]: info: Aligning Spotify Volume to Volumio Volume Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 15 17:54:08 garaz-volumio volumio[905]: info: CoreCommandRouter::volumioGetState Jun 15 17:54:08 garaz-volumio volumio[905]: info: CorePlayQueue::getTrack 0 Jun 15 17:54:08 garaz-volumio volumio[905]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 17:54:08 garaz-volumio volumio[905]: TypeError: Cannot read property 'name' of undefined Jun 15 17:54:08 garaz-volumio volumio[905]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 15 17:54:08 garaz-volumio volumio[905]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 15 17:54:08 garaz-volumio volumio[905]: at ControllerSpotify.alignSpotifyVolumeToVolumioVolume (/data/plugins/music_service/spop/index.js:594:36) Jun 15 17:54:08 garaz-volumio volumio[905]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:284:18) Jun 15 17:54:08 garaz-volumio volumio[905]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jun 15 17:54:08 garaz-volumio volumio[905]: at WebSocket.emit (events.js:315:20) Jun 15 17:54:08 garaz-volumio volumio[905]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jun 15 17:54:08 garaz-volumio volumio[905]: at Receiver.emit (events.js:315:20) Jun 15 17:54:08 garaz-volumio volumio[905]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jun 15 17:54:08 garaz-volumio volumio[905]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jun 15 17:54:08 garaz-volumio volumio[905]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jun 15 17:54:08 garaz-volumio volumio[905]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jun 15 17:54:08 garaz-volumio volumio[905]: at writeOrBuffer (internal/streams/writable.js:358:12) Jun 15 17:54:08 garaz-volumio volumio[905]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jun 15 17:54:08 garaz-volumio volumio[905]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jun 15 17:54:08 garaz-volumio volumio[905]: at Socket.emit (events.js:315:20) Jun 15 17:54:08 garaz-volumio volumio[905]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=trace msg="emitting websocket event: paused" Jun 15 17:54:08 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:08+02:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:09 garaz-volumio sudo[16982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 17:53 Jun 15 17:54:09 garaz-volumio sudo[16982]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 17:54:09 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:09+02:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" Jun 15 17:54:09 garaz-volumio go-librespot[13153]: time="2025-06-15T17:54:09+02:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:0CgGl8mZvzHvyHmNhNAIUv" 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"