-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Sat 2024-11-23 15:27:57 KST. -- Nov 23 15:26:00 volumio-black volumio[1077]: info: Nov 23 15:26:00 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:00 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:00 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:00 volumio-black volumio[1077]: info: sendMpdCommand status took 2 milliseconds Nov 23 15:26:00 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:00 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:00 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:00 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:00 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:00 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:00 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:00 volumio-black volumio[1077]: info: ------------------------------ 9ms Nov 23 15:26:00 volumio-black volumio[1077]: info: Clearing queue after UPNP request Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::ClearQueue Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::stop Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::stPlaybackTimer Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::updateTrackBlock Nov 23 15:26:00 volumio-black volumio[1077]: info: CorePlayQueue::getTrackBlock Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:00 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::serviceStop Nov 23 15:26:00 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:00 volumio-black volumio[1077]: info: ControllerMpd::stop Nov 23 15:26:00 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 15:26:00 volumio-black volumio[1077]: info: CorePlayQueue::clearPlayQueue Nov 23 15:26:00 volumio-black volumio[1077]: info: CorePlayQueue::saveQueue Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 23 15:26:00 volumio-black volumio[1077]: info: Nov 23 15:26:00 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:00 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:00 volumio-black volumio[1077]: info: sendMpdCommand stop took 10 milliseconds Nov 23 15:26:00 volumio-black volumio[1077]: error: updateQueue error: null Nov 23 15:26:00 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:00 volumio-black volumio[1077]: info: Starting UPNP Playback Nov 23 15:26:00 volumio-black volumio[1077]: info: Preparing playback through UPNP Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:00 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:00 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 15:26:00 volumio-black volumio[1077]: info: Nov 23 15:26:00 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:00 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:00 volumio-black volumio[1077]: info: Nov 23 15:26:00 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:00 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:00 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:00 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:00 volumio-black volumio[1077]: info: Nov 23 15:26:00 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:00 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:00 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:00 volumio-black volumio[1077]: info: Nov 23 15:26:00 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:00 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:00 volumio-black volumio[1077]: info: Nov 23 15:26:00 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:00 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:00 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:00 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:00 volumio-black volumio[1077]: info: Nov 23 15:26:00 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:00 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:00 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:01 volumio-black volumio[1077]: info: Nov 23 15:26:01 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:01 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:01 volumio-black volumio[1077]: info: Nov 23 15:26:01 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:01 volumio-black volumio[1077]: info: sendMpdCommand status took 5 milliseconds Nov 23 15:26:01 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:01 volumio-black volumio[1077]: info: sendMpdCommand status took 2 milliseconds Nov 23 15:26:01 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:01 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:01 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343160","trackType":""} Nov 23 15:26:01 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Nov 23 15:26:01 volumio-black volumio[1077]: info: ------------------------------ 12ms Nov 23 15:26:01 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:01 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:01 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343160","trackType":""} Nov 23 15:26:01 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:01 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:01 volumio-black volumio[1077]: info: ------------------------------ 23ms Nov 23 15:26:01 volumio-black volumio[1077]: info: Nov 23 15:26:01 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:01 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:01 volumio-black volumio[1077]: info: Nov 23 15:26:01 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:01 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:01 volumio-black volumio[1077]: info: ------------------------------ 5ms Nov 23 15:26:01 volumio-black volumio[1077]: info: sendMpdCommand status took 5 milliseconds Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:01 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:01 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:01 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:01 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:01 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":782,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2247 Kbps","isStreaming":false,"title":"37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343160","artist":"Music Assistant","album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343160","trackType":""} Nov 23 15:26:01 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:01 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 15:26:01 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:01 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:01 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:01 volumio-black volumio[1077]: info: ------------------------------ 12ms Nov 23 15:26:07 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:07 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:07 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:07 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:26:07 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:26:07 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:26:07 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:07 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:26:07 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:26:07 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:07 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:26:07 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:07 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:07 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:26:07 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:07 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:26:07 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:26:07 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:07 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:07 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:26:07 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:26:17 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:17 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:17 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:17 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:26:17 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:26:17 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:26:17 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:17 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:26:17 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:26:17 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:17 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:26:17 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:17 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:17 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:26:17 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:17 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:26:17 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:26:17 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:17 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:21 volumio-black volumio[1077]: info: Nov 23 15:26:21 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:21 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:21 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:21 volumio-black volumio[1077]: info: sendMpdCommand status took 1 milliseconds Nov 23 15:26:21 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:21 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:21 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:21 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:21 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:21 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:21 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:21 volumio-black volumio[1077]: info: ------------------------------ 5ms Nov 23 15:26:21 volumio-black volumio[1077]: info: Clearing queue after UPNP request Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::ClearQueue Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::stop Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::stPlaybackTimer Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::updateTrackBlock Nov 23 15:26:21 volumio-black volumio[1077]: info: CorePlayQueue::getTrackBlock Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:21 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::serviceStop Nov 23 15:26:21 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:21 volumio-black volumio[1077]: info: ControllerMpd::stop Nov 23 15:26:21 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 15:26:21 volumio-black volumio[1077]: info: CorePlayQueue::clearPlayQueue Nov 23 15:26:21 volumio-black volumio[1077]: info: CorePlayQueue::saveQueue Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 23 15:26:21 volumio-black volumio[1077]: info: Nov 23 15:26:21 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:21 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:21 volumio-black volumio[1077]: info: sendMpdCommand stop took 14 milliseconds Nov 23 15:26:21 volumio-black volumio[1077]: error: updateQueue error: null Nov 23 15:26:21 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:21 volumio-black volumio[1077]: info: Starting UPNP Playback Nov 23 15:26:21 volumio-black volumio[1077]: info: Preparing playback through UPNP Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:21 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:21 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 15:26:22 volumio-black volumio[1077]: info: Nov 23 15:26:22 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:22 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:22 volumio-black volumio[1077]: info: Nov 23 15:26:22 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:22 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:22 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:22 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:22 volumio-black volumio[1077]: info: Nov 23 15:26:22 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:22 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:22 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:22 volumio-black volumio[1077]: info: Nov 23 15:26:22 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:22 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:22 volumio-black volumio[1077]: info: Nov 23 15:26:22 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:22 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:22 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:22 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:22 volumio-black volumio[1077]: info: Nov 23 15:26:22 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:22 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:22 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:22 volumio-black volumio[1077]: info: Nov 23 15:26:22 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:22 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:22 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:22 volumio-black volumio[1077]: info: sendMpdCommand status took 1 milliseconds Nov 23 15:26:22 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:22 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:22 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:22 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:22 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:22 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:22 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:22 volumio-black volumio[1077]: info: ------------------------------ 5ms Nov 23 15:26:22 volumio-black volumio[1077]: info: Clearing queue after UPNP request Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreStateMachine::ClearQueue Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreStateMachine::stop Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:26:22 volumio-black volumio[1077]: info: CorePlayQueue::clearPlayQueue Nov 23 15:26:22 volumio-black volumio[1077]: info: CorePlayQueue::saveQueue Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 23 15:26:22 volumio-black volumio[1077]: info: Nov 23 15:26:22 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:22 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:22 volumio-black volumio[1077]: error: updateQueue error: null Nov 23 15:26:22 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:22 volumio-black volumio[1077]: info: Starting UPNP Playback Nov 23 15:26:22 volumio-black volumio[1077]: info: Preparing playback through UPNP Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:22 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:22 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 15:26:23 volumio-black volumio[1077]: info: Nov 23 15:26:23 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:23 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:23 volumio-black volumio[1077]: info: Nov 23 15:26:23 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:23 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:23 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:23 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:23 volumio-black volumio[1077]: info: Nov 23 15:26:23 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:23 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:23 volumio-black volumio[1077]: info: Nov 23 15:26:23 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:23 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:23 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:23 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:23 volumio-black volumio[1077]: info: Nov 23 15:26:23 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:23 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:23 volumio-black volumio[1077]: info: Nov 23 15:26:23 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:23 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:23 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:23 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:23 volumio-black volumio[1077]: info: Nov 23 15:26:23 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:23 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:23 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:23 volumio-black volumio[1077]: info: sendMpdCommand status took 1 milliseconds Nov 23 15:26:23 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:23 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:23 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 15:26:23 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:23 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:23 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:23 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:23 volumio-black volumio[1077]: info: ------------------------------ 8ms Nov 23 15:26:23 volumio-black volumio[1077]: info: Clearing queue after UPNP request Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreStateMachine::ClearQueue Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreStateMachine::stop Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:26:23 volumio-black volumio[1077]: info: CorePlayQueue::clearPlayQueue Nov 23 15:26:23 volumio-black volumio[1077]: info: CorePlayQueue::saveQueue Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 23 15:26:23 volumio-black volumio[1077]: info: Nov 23 15:26:23 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:23 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:23 volumio-black volumio[1077]: error: updateQueue error: null Nov 23 15:26:23 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:23 volumio-black volumio[1077]: info: Starting UPNP Playback Nov 23 15:26:23 volumio-black volumio[1077]: info: Preparing playback through UPNP Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:23 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:23 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:24 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:24 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 6ms Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 5ms Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:24 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:24 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:24 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:24 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:24 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:24 volumio-black volumio[1077]: info: sendMpdCommand status took 6 milliseconds Nov 23 15:26:24 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:24 volumio-black volumio[1077]: info: sendMpdCommand status took 5 milliseconds Nov 23 15:26:24 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:24 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:24 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343182","trackType":""} Nov 23 15:26:24 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 14ms Nov 23 15:26:24 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:24 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:24 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343182","trackType":""} Nov 23 15:26:24 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:24 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 18ms Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:24 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:24 volumio-black volumio[1077]: info: Nov 23 15:26:24 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:24 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 7ms Nov 23 15:26:24 volumio-black volumio[1077]: info: sendMpdCommand status took 4 milliseconds Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:24 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 15:26:24 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:24 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:24 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:24 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2245 Kbps","isStreaming":false,"title":"37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343182","artist":"Music Assistant","album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343182","trackType":""} Nov 23 15:26:24 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:24 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 15:26:24 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:24 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:24 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:24 volumio-black volumio[1077]: info: ------------------------------ 19ms Nov 23 15:26:27 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:27 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:27 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:27 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:26:27 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:26:27 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:26:27 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:27 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:26:27 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:26:27 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:27 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:26:27 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:27 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:27 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:26:27 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:27 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:26:27 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:26:27 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:27 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:27 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:26:27 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:26:28 volumio-black volumio[1077]: info: Nov 23 15:26:28 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:28 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:28 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:28 volumio-black volumio[1077]: info: sendMpdCommand status took 1 milliseconds Nov 23 15:26:28 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:28 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:28 volumio-black volumio[1077]: info: Clearing queue after UPNP request Nov 23 15:26:28 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:28 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:28 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:28 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:28 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:28 volumio-black volumio[1077]: info: ------------------------------ 6ms Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::ClearQueue Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::stop Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::stPlaybackTimer Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::updateTrackBlock Nov 23 15:26:28 volumio-black volumio[1077]: info: CorePlayQueue::getTrackBlock Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:28 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::serviceStop Nov 23 15:26:28 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:28 volumio-black volumio[1077]: info: ControllerMpd::stop Nov 23 15:26:28 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 15:26:28 volumio-black volumio[1077]: info: CorePlayQueue::clearPlayQueue Nov 23 15:26:28 volumio-black volumio[1077]: info: CorePlayQueue::saveQueue Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 23 15:26:28 volumio-black volumio[1077]: info: Nov 23 15:26:28 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:28 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:28 volumio-black volumio[1077]: info: sendMpdCommand stop took 10 milliseconds Nov 23 15:26:28 volumio-black volumio[1077]: error: updateQueue error: null Nov 23 15:26:28 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:28 volumio-black volumio[1077]: info: Starting UPNP Playback Nov 23 15:26:28 volumio-black volumio[1077]: info: Preparing playback through UPNP Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:28 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:28 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:29 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:29 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:29 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:29 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:29 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:29 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:29 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:29 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:29 volumio-black volumio[1077]: info: sendMpdCommand status took 3 milliseconds Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:29 volumio-black volumio[1077]: info: sendMpdCommand status took 4 milliseconds Nov 23 15:26:29 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:29 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:29 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343188","trackType":""} Nov 23 15:26:29 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 11ms Nov 23 15:26:29 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:29 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:29 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343188","trackType":""} Nov 23 15:26:29 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:29 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 17ms Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:29 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:29 volumio-black volumio[1077]: info: Nov 23 15:26:29 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:29 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 5ms Nov 23 15:26:29 volumio-black volumio[1077]: info: sendMpdCommand status took 3 milliseconds Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:29 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:29 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:29 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:29 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:29 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2189 Kbps","isStreaming":false,"title":"37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343188","artist":"Music Assistant","album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343188","trackType":""} Nov 23 15:26:29 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:29 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 15:26:29 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:29 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:29 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:29 volumio-black volumio[1077]: info: ------------------------------ 19ms Nov 23 15:26:34 volumio-black volumio[1077]: info: Nov 23 15:26:34 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:34 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:34 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:34 volumio-black volumio[1077]: info: sendMpdCommand status took 1 milliseconds Nov 23 15:26:34 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:34 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:34 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:34 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:34 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:34 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:34 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:34 volumio-black volumio[1077]: info: ------------------------------ 6ms Nov 23 15:26:34 volumio-black volumio[1077]: info: Clearing queue after UPNP request Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::ClearQueue Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::stop Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::stPlaybackTimer Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::updateTrackBlock Nov 23 15:26:34 volumio-black volumio[1077]: info: CorePlayQueue::getTrackBlock Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:34 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::serviceStop Nov 23 15:26:34 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:34 volumio-black volumio[1077]: info: ControllerMpd::stop Nov 23 15:26:34 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 15:26:34 volumio-black volumio[1077]: info: CorePlayQueue::clearPlayQueue Nov 23 15:26:34 volumio-black volumio[1077]: info: CorePlayQueue::saveQueue Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 23 15:26:34 volumio-black volumio[1077]: info: Nov 23 15:26:34 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:34 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:34 volumio-black volumio[1077]: info: sendMpdCommand stop took 12 milliseconds Nov 23 15:26:34 volumio-black volumio[1077]: error: updateQueue error: null Nov 23 15:26:34 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:34 volumio-black volumio[1077]: info: Starting UPNP Playback Nov 23 15:26:34 volumio-black volumio[1077]: info: Preparing playback through UPNP Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:34 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:34 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 15:26:34 volumio-black volumio[1077]: info: Nov 23 15:26:34 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:34 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:34 volumio-black volumio[1077]: info: Nov 23 15:26:34 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:34 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:34 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:34 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:34 volumio-black volumio[1077]: info: Nov 23 15:26:34 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:34 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:34 volumio-black volumio[1077]: info: Nov 23 15:26:34 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:34 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:34 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:34 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:34 volumio-black volumio[1077]: info: Nov 23 15:26:34 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:34 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:34 volumio-black volumio[1077]: info: Nov 23 15:26:34 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:34 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:34 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:34 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:26:35 volumio-black volumio[1077]: info: Nov 23 15:26:35 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:35 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:35 volumio-black volumio[1077]: info: Nov 23 15:26:35 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:35 volumio-black volumio[1077]: info: sendMpdCommand status took 6 milliseconds Nov 23 15:26:35 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:35 volumio-black volumio[1077]: info: sendMpdCommand status took 4 milliseconds Nov 23 15:26:35 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:35 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:35 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343193","trackType":""} Nov 23 15:26:35 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Nov 23 15:26:35 volumio-black volumio[1077]: info: ------------------------------ 15ms Nov 23 15:26:35 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:35 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:35 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343193","trackType":""} Nov 23 15:26:35 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:35 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:35 volumio-black volumio[1077]: info: ------------------------------ 25ms Nov 23 15:26:35 volumio-black volumio[1077]: info: Nov 23 15:26:35 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:35 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:35 volumio-black volumio[1077]: info: Nov 23 15:26:35 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:35 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:35 volumio-black volumio[1077]: info: ------------------------------ 6ms Nov 23 15:26:35 volumio-black volumio[1077]: info: sendMpdCommand status took 2 milliseconds Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:35 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:35 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:35 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:35 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:35 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2213 Kbps","isStreaming":false,"title":"37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343193","artist":"Music Assistant","album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343193","trackType":""} Nov 23 15:26:35 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:35 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 15:26:35 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:35 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:35 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:35 volumio-black volumio[1077]: info: ------------------------------ 11ms Nov 23 15:26:37 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:37 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:37 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:37 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:26:37 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:26:37 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:26:37 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:37 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:26:37 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:26:37 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:37 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:26:37 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:37 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:37 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:26:37 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:37 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:26:37 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:26:37 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:37 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:47 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:47 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:47 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:47 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:26:47 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:26:47 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:26:47 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:47 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:26:47 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:26:47 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:47 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:26:47 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:47 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:47 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:26:47 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:47 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:26:47 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:26:47 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:47 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:47 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:26:47 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:26:54 volumio-black volumio[1077]: info: Nov 23 15:26:54 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:54 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:54 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:54 volumio-black volumio[1077]: info: sendMpdCommand status took 1 milliseconds Nov 23 15:26:54 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:54 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:54 volumio-black volumio[1077]: info: Clearing queue after UPNP request Nov 23 15:26:54 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 15:26:54 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:54 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:54 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:54 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:54 volumio-black volumio[1077]: info: ------------------------------ 6ms Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::ClearQueue Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::stop Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::stPlaybackTimer Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::updateTrackBlock Nov 23 15:26:54 volumio-black volumio[1077]: info: CorePlayQueue::getTrackBlock Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:54 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::serviceStop Nov 23 15:26:54 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:54 volumio-black volumio[1077]: info: ControllerMpd::stop Nov 23 15:26:54 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 15:26:54 volumio-black volumio[1077]: info: CorePlayQueue::clearPlayQueue Nov 23 15:26:54 volumio-black volumio[1077]: info: CorePlayQueue::saveQueue Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 23 15:26:54 volumio-black volumio[1077]: info: Nov 23 15:26:54 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:54 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:54 volumio-black volumio[1077]: info: sendMpdCommand stop took 10 milliseconds Nov 23 15:26:54 volumio-black volumio[1077]: error: updateQueue error: null Nov 23 15:26:54 volumio-black volumio[1077]: info: ------------------------------ 6ms Nov 23 15:26:54 volumio-black volumio[1077]: info: Starting UPNP Playback Nov 23 15:26:54 volumio-black volumio[1077]: info: Preparing playback through UPNP Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:54 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:54 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:55 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:55 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:55 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:55 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 8ms Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:55 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:55 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 3ms Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:55 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:55 volumio-black volumio[1077]: info: sendMpdCommand status took 4 milliseconds Nov 23 15:26:55 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:55 volumio-black volumio[1077]: info: sendMpdCommand status took 2 milliseconds Nov 23 15:26:55 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:55 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:55 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343214","trackType":""} Nov 23 15:26:55 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 12ms Nov 23 15:26:55 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:55 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:55 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343214","trackType":""} Nov 23 15:26:55 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:55 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 18ms Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:26:55 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:26:55 volumio-black volumio[1077]: info: Nov 23 15:26:55 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:26:55 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 5ms Nov 23 15:26:55 volumio-black volumio[1077]: info: sendMpdCommand status took 1 milliseconds Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:26:55 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:26:55 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:26:55 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:26:55 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:26:55 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2245 Kbps","isStreaming":false,"title":"37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343214","artist":"Music Assistant","album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343214","trackType":""} Nov 23 15:26:55 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:26:55 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 15:26:55 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:26:55 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:55 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:55 volumio-black volumio[1077]: info: ------------------------------ 9ms Nov 23 15:26:57 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:26:57 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:26:57 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:26:57 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:26:57 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:26:57 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:26:57 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:57 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:26:57 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:26:57 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:57 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:26:57 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:57 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:26:57 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:26:57 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:26:57 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:26:57 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:26:57 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:26:57 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:07 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:27:07 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:07 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:27:07 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:27:07 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:27:07 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:27:07 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:07 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:27:07 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:27:07 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:07 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:27:07 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:07 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:07 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:27:07 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:07 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:27:07 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:27:07 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:07 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:07 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:27:07 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:27:09 volumio-black volumio[1077]: info: Nov 23 15:27:09 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:27:09 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:27:09 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:27:09 volumio-black volumio[1077]: info: sendMpdCommand status took 1 milliseconds Nov 23 15:27:09 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:27:09 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:27:09 volumio-black volumio[1077]: info: Clearing queue after UPNP request Nov 23 15:27:09 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 15:27:09 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:27:09 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:27:09 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:09 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:27:09 volumio-black volumio[1077]: info: ------------------------------ 7ms Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::ClearQueue Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::stop Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::stPlaybackTimer Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::updateTrackBlock Nov 23 15:27:09 volumio-black volumio[1077]: info: CorePlayQueue::getTrackBlock Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:27:09 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::serviceStop Nov 23 15:27:09 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:09 volumio-black volumio[1077]: info: ControllerMpd::stop Nov 23 15:27:09 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 15:27:09 volumio-black volumio[1077]: info: CorePlayQueue::clearPlayQueue Nov 23 15:27:09 volumio-black volumio[1077]: info: CorePlayQueue::saveQueue Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 23 15:27:09 volumio-black volumio[1077]: info: Nov 23 15:27:09 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:27:09 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:27:09 volumio-black volumio[1077]: info: sendMpdCommand stop took 10 milliseconds Nov 23 15:27:09 volumio-black volumio[1077]: error: updateQueue error: null Nov 23 15:27:09 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:27:09 volumio-black volumio[1077]: info: Starting UPNP Playback Nov 23 15:27:09 volumio-black volumio[1077]: info: Preparing playback through UPNP Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:27:09 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:09 volumio-black volumio[1077]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:27:10 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:27:10 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:27:10 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:27:10 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:27:10 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:27:10 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 2ms Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 1ms Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:27:10 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:27:10 volumio-black volumio[1077]: info: sendMpdCommand status took 5 milliseconds Nov 23 15:27:10 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:27:10 volumio-black volumio[1077]: info: sendMpdCommand status took 3 milliseconds Nov 23 15:27:10 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:27:10 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:27:10 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343229","trackType":""} Nov 23 15:27:10 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 12ms Nov 23 15:27:10 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:27:10 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:27:10 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343229","trackType":""} Nov 23 15:27:10 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:27:10 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 20ms Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces system playlist update Nov 23 15:27:10 volumio-black volumio[1077]: info: Ignoring MPD Status Update Nov 23 15:27:10 volumio-black volumio[1077]: info: Nov 23 15:27:10 volumio-black volumio[1077]: ---------------------------- MPD announces state update: player Nov 23 15:27:10 volumio-black volumio[1077]: info: ControllerMpd::getState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 3ms Nov 23 15:27:10 volumio-black volumio[1077]: info: sendMpdCommand status took 2 milliseconds Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::parseState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 15:27:10 volumio-black volumio[1077]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 15:27:10 volumio-black volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 23 15:27:10 volumio-black volumio[1077]: info: ControllerMpd::pushState Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreCommandRouter::servicePushState Nov 23 15:27:10 volumio-black volumio[1077]: verbose: In UPNP mode Nov 23 15:27:10 volumio-black volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2236 Kbps","isStreaming":false,"title":"37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343229","artist":"Music Assistant","album":null,"uri":"http://192.168.1.62:8097/flow/uuid:39fa6f7b-8fdd-6b9b-4a0e-b827eb7ef1f2/37ac6db3cd7149d083c612de30fcdbd2.flac?ts=1732343229","trackType":""} Nov 23 15:27:10 volumio-black volumio[1077]: verbose: CURRENT POSITION 0 Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 23 15:27:10 volumio-black volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 15:27:10 volumio-black volumio[1077]: info: CoreStateMachine::pushState Nov 23 15:27:10 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:10 volumio-black volumio[1077]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 23 15:27:10 volumio-black volumio[1077]: info: ------------------------------ 9ms Nov 23 15:27:17 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:27:17 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:17 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:27:17 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:27:17 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:27:17 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:27:17 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:17 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:27:17 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:27:17 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:17 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:27:17 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:17 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:17 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:27:17 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:17 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:27:17 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:27:17 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:17 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:27 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:27:27 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:27 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:27:27 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:27:27 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:27:27 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:27:27 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:27 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:27:27 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:27:27 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:27 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:27:27 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:27 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:27 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:27:27 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:27 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:27:27 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:27:27 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:27 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:27 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:27:27 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:27:37 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:27:37 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:37 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:27:37 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:27:37 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:27:37 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:27:37 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:37 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:27:37 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:27:37 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:37 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:27:37 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:37 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:37 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:27:37 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:37 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:27:37 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:27:37 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:37 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:47 volumio-black volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 23 15:27:47 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:47 volumio-black volumio[1077]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 23 15:27:47 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:27:47 volumio-black volumio[1077]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 23 15:27:47 volumio-black volumio[1077]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 23 15:27:47 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:47 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 23 15:27:47 volumio-black volumio[1077]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 23 15:27:47 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:47 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 23 15:27:47 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:47 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:47 volumio-black volumio[1077]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 23 15:27:47 volumio-black volumio[1077]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 23 15:27:47 volumio-black volumio[1077]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 23 15:27:47 volumio-black volumio[1077]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 23 15:27:47 volumio-black volumio[1077]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 23 15:27:47 volumio-black volumio[1077]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 23 15:27:47 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:27:47 volumio-black volumio[1077]: info: Listing playlists Nov 23 15:27:57 volumio-black volumio[1077]: info: Airplay started streaming, receiving metadatas Nov 23 15:27:57 volumio-black volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 23 15:27:57 volumio-black volumio[1077]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 15:27:57 volumio-black volumio[1077]: TypeError: Cannot read property 'name' of undefined Nov 23 15:27:57 volumio-black volumio[1077]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 23 15:27:57 volumio-black volumio[1077]: at AirPlayInterface.prepareAirplayPlayback (/volumio/app/plugins/music_service/airplay_emulation/index.js:538:47) Nov 23 15:27:57 volumio-black volumio[1077]: at ShairportSyncReaderUDP. (/volumio/app/plugins/music_service/airplay_emulation/index.js:257:12) Nov 23 15:27:57 volumio-black volumio[1077]: at ShairportSyncReaderUDP.emit (events.js:327:22) Nov 23 15:27:57 volumio-black volumio[1077]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:86:16) Nov 23 15:27:57 volumio-black volumio[1077]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) Nov 23 15:27:57 volumio-black volumio[1077]: at Socket.emit (events.js:315:20) Nov 23 15:27:57 volumio-black volumio[1077]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Nov 23 15:27:57 volumio-black volumio[1077]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 15:27:57 volumio-black shairport-sync[1418]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 23 15:27:57 volumio-black shairport-sync[1418]: Dload Upload Total Spent Left Speed Nov 23 15:27:57 volumio-black sudo[4151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 15:26 Nov 23 15:27:57 volumio-black sudo[4151]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 21 Nov 2024 11:00:22 AM CET" VOLUMIO_VERSION="3.781" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="274050037be981e489ea334352baf229"