-- Logs begin at Sat 2024-08-24 03:53:05 CEST, end at Mon 2024-08-26 10:24:45 CEST. -- Aug 26 10:23:06 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:23:06 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:23:06 volumio volumio[840]: info: Listing playlists Aug 26 10:23:06 volumio volumio[840]: info: Listing playlists Aug 26 10:23:16 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:23:16 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:23:26 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:23:26 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:23:26 volumio volumio[840]: info: Listing playlists Aug 26 10:23:26 volumio volumio[840]: info: Listing playlists Aug 26 10:23:36 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:23:36 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:23:46 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:23:46 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:23:46 volumio volumio[840]: info: Listing playlists Aug 26 10:23:46 volumio volumio[840]: info: Listing playlists Aug 26 10:23:56 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:23:56 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:06 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:24:06 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:06 volumio volumio[840]: info: Listing playlists Aug 26 10:24:06 volumio volumio[840]: info: Listing playlists Aug 26 10:24:16 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:24:16 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:21 volumio volumio[840]: error: Upnp client error: Error: This socket has been ended by the other party Aug 26 10:24:23 volumio volumio[840]: info: Clearing queue after UPNP request Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::ClearQueue Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::updateTrackBlock Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrackBlock Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::stPlaybackTimer Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::serviceStop Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::serviceStop Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::stop Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::clearPlayQueue Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::saveQueue Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:24:23 volumio volumio[840]: info: sendMpdCommand stop took 36 milliseconds Aug 26 10:24:23 volumio volumio[840]: info: Aug 26 10:24:23 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:23 volumio volumio[840]: info: Aug 26 10:24:23 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:23 volumio volumio[840]: info: Aug 26 10:24:23 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:23 volumio volumio[840]: info: Aug 26 10:24:23 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:23 volumio volumio[840]: info: Aug 26 10:24:23 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:23 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:23 volumio volumio[840]: info: sendMpdCommand status took 10 milliseconds Aug 26 10:24:23 volumio volumio[840]: info: sendMpdCommand status took 9 milliseconds Aug 26 10:24:23 volumio volumio[840]: info: sendMpdCommand status took 8 milliseconds Aug 26 10:24:23 volumio volumio[840]: info: sendMpdCommand status took 6 milliseconds Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:23 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:24:23 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::syncState stateService stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: No code Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:24:23 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::syncState stateService stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: No code Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:24:23 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::syncState stateService stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: No code Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:24:23 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::syncState stateService stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: No code Aug 26 10:24:23 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:23 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:23 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:23 volumio volumio[840]: info: ------------------------------ 70ms Aug 26 10:24:23 volumio volumio[840]: info: ------------------------------ 68ms Aug 26 10:24:23 volumio volumio[840]: info: ------------------------------ 67ms Aug 26 10:24:23 volumio volumio[840]: info: ------------------------------ 67ms Aug 26 10:24:23 volumio volumio[840]: info: Aug 26 10:24:23 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:23 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:23 volumio volumio[840]: info: Aug 26 10:24:23 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:23 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:23 volumio volumio[840]: info: Aug 26 10:24:23 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:23 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:23 volumio volumio[840]: error: updateQueue error: null Aug 26 10:24:23 volumio volumio[840]: error: updateQueue error: null Aug 26 10:24:23 volumio volumio[840]: error: updateQueue error: null Aug 26 10:24:23 volumio volumio[840]: error: updateQueue error: null Aug 26 10:24:23 volumio volumio[840]: info: ------------------------------ 73ms Aug 26 10:24:23 volumio volumio[840]: info: ------------------------------ 10ms Aug 26 10:24:23 volumio volumio[840]: info: ------------------------------ 9ms Aug 26 10:24:23 volumio volumio[840]: info: ------------------------------ 8ms Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces state update: options Aug 26 10:24:24 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:24 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces state update: options Aug 26 10:24:24 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:24 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces state update: options Aug 26 10:24:24 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:24 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces state update: options Aug 26 10:24:24 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:24 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:24 volumio volumio[840]: info: Starting UPNP Playback Aug 26 10:24:24 volumio volumio[840]: info: Preparing playback through UPNP Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:24:24 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 10:24:24 volumio volumio[840]: info: sendMpdCommand status took 8 milliseconds Aug 26 10:24:24 volumio volumio[840]: info: sendMpdCommand status took 7 milliseconds Aug 26 10:24:24 volumio volumio[840]: info: sendMpdCommand status took 6 milliseconds Aug 26 10:24:24 volumio volumio[840]: info: sendMpdCommand status took 5 milliseconds Aug 26 10:24:24 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:24 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:24 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:24 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:24 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:24 volumio volumio[840]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:24:24 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::syncState stateService stop Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: No code Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:24 volumio volumio[840]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:24:24 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::syncState stateService stop Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: No code Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:24 volumio volumio[840]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:24:24 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::syncState stateService stop Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: No code Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:24 volumio volumio[840]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:24:24 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::syncState stateService stop Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: No code Aug 26 10:24:24 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:24 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 51ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 51ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 50ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 50ms Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 7ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 6ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 5ms Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 6ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 5ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 4ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 3ms Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 6ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 5ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 4ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 3ms Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 5ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 4ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 3ms Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 6ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 4ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 3ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 2ms Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: Aug 26 10:24:24 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:24 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 5ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 4ms Aug 26 10:24:24 volumio volumio[840]: info: ------------------------------ 3ms Aug 26 10:24:26 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:24:26 volumio volumio[840]: info: Listing playlists Aug 26 10:24:26 volumio volumio[840]: info: Listing playlists Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - Loudness is ON true Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - Nulleq applied Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - Loudness is ON true Aug 26 10:24:32 volumio volumio[840]: info: FusionDsp - Nulleq applied Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:32 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand status took 52 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand status took 50 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand status took 48 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand status took 47 milliseconds Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:32 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:32 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 26 10:24:32 volumio volumio[840]: info: Ignoring MPD Status Update Aug 26 10:24:32 volumio volumio[840]: info: Aug 26 10:24:32 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 18ms Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand status took 18 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 15ms Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand status took 14 milliseconds Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:32 volumio volumio[840]: verbose: In UPNP mode Aug 26 10:24:32 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"f7230b7310f34912bab501459449dda1.flac?ts=1724660661","artist":"Music Assistant","album":null,"uri":"http://192.168.0.3:8097/single/uuid:548a2cb5-2925-37ea-1818-000ec6c4c159/f7230b7310f34912bab501459449dda1.flac?ts=1724660661","trackType":""} Aug 26 10:24:32 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:32 volumio volumio[840]: verbose: In UPNP mode Aug 26 10:24:32 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"611 Kbps","isStreaming":false,"title":"f7230b7310f34912bab501459449dda1.flac?ts=1724660661","artist":"Music Assistant","album":null,"uri":"http://192.168.0.3:8097/single/uuid:548a2cb5-2925-37ea-1818-000ec6c4c159/f7230b7310f34912bab501459449dda1.flac?ts=1724660661","trackType":""} Aug 26 10:24:32 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:24:32 volumio volumio[840]: info: Received an update from plugin. extracting info from payload Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:32 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:32 volumio volumio[840]: verbose: In UPNP mode Aug 26 10:24:32 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"611 Kbps","isStreaming":false,"title":"f7230b7310f34912bab501459449dda1.flac?ts=1724660661","artist":"Music Assistant","album":null,"uri":"http://192.168.0.3:8097/single/uuid:548a2cb5-2925-37ea-1818-000ec6c4c159/f7230b7310f34912bab501459449dda1.flac?ts=1724660661","trackType":""} Aug 26 10:24:32 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:24:32 volumio volumio[840]: info: Received an update from plugin. extracting info from payload Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:32 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:32 volumio volumio[840]: verbose: In UPNP mode Aug 26 10:24:32 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"611 Kbps","isStreaming":false,"title":"f7230b7310f34912bab501459449dda1.flac?ts=1724660661","artist":"Music Assistant","album":null,"uri":"http://192.168.0.3:8097/single/uuid:548a2cb5-2925-37ea-1818-000ec6c4c159/f7230b7310f34912bab501459449dda1.flac?ts=1724660661","trackType":""} Aug 26 10:24:32 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:24:32 volumio volumio[840]: info: Received an update from plugin. extracting info from payload Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:32 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 93ms Aug 26 10:24:32 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:32 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 92ms Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 89ms Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 89ms Aug 26 10:24:32 volumio volumio[840]: error: FusionDsp - WebSocket error: [object Object] Aug 26 10:24:32 volumio volumio[840]: error: FusionDsp - WebSocket error: [object Object] Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 62ms Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand status took 61 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 60ms Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand status took 59 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand playlistinfo took 52 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:32 volumio volumio[840]: verbose: In UPNP mode Aug 26 10:24:32 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"662 Kbps","isStreaming":false,"title":"f7230b7310f34912bab501459449dda1.flac?ts=1724660661","artist":"Music Assistant","album":null,"uri":"http://192.168.0.3:8097/single/uuid:548a2cb5-2925-37ea-1818-000ec6c4c159/f7230b7310f34912bab501459449dda1.flac?ts=1724660661","trackType":""} Aug 26 10:24:32 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:24:32 volumio volumio[840]: info: Received an update from plugin. extracting info from payload Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:32 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:32 volumio volumio[840]: verbose: In UPNP mode Aug 26 10:24:32 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"662 Kbps","isStreaming":false,"title":"f7230b7310f34912bab501459449dda1.flac?ts=1724660661","artist":"Music Assistant","album":null,"uri":"http://192.168.0.3:8097/single/uuid:548a2cb5-2925-37ea-1818-000ec6c4c159/f7230b7310f34912bab501459449dda1.flac?ts=1724660661","trackType":""} Aug 26 10:24:32 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:24:32 volumio volumio[840]: info: Received an update from plugin. extracting info from payload Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:32 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:32 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:32 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 82ms Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 75ms Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 10:24:32 volumio volumio[840]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:24:32 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:32 volumio volumio[840]: verbose: In UPNP mode Aug 26 10:24:32 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"708 Kbps","isStreaming":false,"title":"f7230b7310f34912bab501459449dda1.flac?ts=1724660661","artist":"Music Assistant","album":null,"uri":"http://192.168.0.3:8097/single/uuid:548a2cb5-2925-37ea-1818-000ec6c4c159/f7230b7310f34912bab501459449dda1.flac?ts=1724660661","trackType":""} Aug 26 10:24:32 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:24:32 volumio volumio[840]: info: Received an update from plugin. extracting info from payload Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:32 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:32 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:32 volumio volumio[840]: verbose: In UPNP mode Aug 26 10:24:32 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"708 Kbps","isStreaming":false,"title":"f7230b7310f34912bab501459449dda1.flac?ts=1724660661","artist":"Music Assistant","album":null,"uri":"http://192.168.0.3:8097/single/uuid:548a2cb5-2925-37ea-1818-000ec6c4c159/f7230b7310f34912bab501459449dda1.flac?ts=1724660661","trackType":""} Aug 26 10:24:32 volumio volumio[840]: verbose: CURRENT POSITION 18 Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:24:32 volumio volumio[840]: info: Received an update from plugin. extracting info from payload Aug 26 10:24:32 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:24:32 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:32 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:32 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:32 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 84ms Aug 26 10:24:32 volumio volumio[840]: info: ------------------------------ 82ms Aug 26 10:24:35 volumio volumio[840]: info: Aug 26 10:24:35 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:35 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:35 volumio volumio[840]: info: Aug 26 10:24:35 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:35 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:35 volumio volumio[840]: info: Aug 26 10:24:35 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:35 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:35 volumio volumio[840]: info: Aug 26 10:24:35 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 26 10:24:35 volumio volumio[840]: info: ControllerMpd::getState Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:24:35 volumio volumio[840]: info: sendMpdCommand status took 6 milliseconds Aug 26 10:24:35 volumio volumio[840]: info: sendMpdCommand status took 4 milliseconds Aug 26 10:24:35 volumio volumio[840]: info: sendMpdCommand status took 4 milliseconds Aug 26 10:24:35 volumio volumio[840]: info: sendMpdCommand status took 3 milliseconds Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:24:35 volumio volumio[840]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 10:24:35 volumio volumio[840]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:24:35 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:24:35 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:35 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:35 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:35 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:35 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:35 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:35 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:35 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:35 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:35 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:35 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:35 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:35 volumio volumio[840]: info: ControllerMpd::pushState Aug 26 10:24:35 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 26 10:24:35 volumio volumio[840]: info: CoreStateMachine::pushState Aug 26 10:24:35 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:35 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:35 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:35 volumio volumio[840]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:35 volumio volumio[840]: info: ------------------------------ 20ms Aug 26 10:24:35 volumio volumio[840]: info: ------------------------------ 19ms Aug 26 10:24:35 volumio volumio[840]: info: ------------------------------ 17ms Aug 26 10:24:35 volumio volumio[840]: info: ------------------------------ 16ms Aug 26 10:24:36 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:24:36 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:36 volumio volumio[840]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:36 volumio volumio[840]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 26 10:24:36 volumio volumio[840]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Aug 26 10:24:36 volumio volumio[840]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Aug 26 10:24:36 volumio volumio[840]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 26 10:24:36 volumio volumio[840]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Aug 26 10:24:36 volumio volumio[840]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Aug 26 10:24:36 volumio volumio[840]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 26 10:24:36 volumio volumio[840]: at /volumio/node_modules/express/lib/router/index.js:281:22 Aug 26 10:24:36 volumio volumio[840]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 26 10:24:36 volumio volumio[840]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 26 10:24:36 volumio volumio[840]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Aug 26 10:24:36 volumio volumio[840]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Aug 26 10:24:36 volumio volumio[840]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Aug 26 10:24:36 volumio volumio[840]: at /volumio/node_modules/express/lib/router/index.js:284:7 Aug 26 10:24:36 volumio volumio[840]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Aug 26 10:24:36 volumio volumio[840]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Aug 26 10:24:43 volumio sudo[16502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 10:24:43 volumio sudo[16502]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 10:24:43 volumio sudo[16502]: pam_unix(sudo:session): session closed for user root Aug 26 10:24:43 volumio sudo[16505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 10:24:43 volumio sudo[16505]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 10:24:43 volumio sudo[16505]: pam_unix(sudo:session): session closed for user root Aug 26 10:24:43 volumio volumio[840]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.140 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Aug 26 10:24:43 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 10:24:43 volumio volumio[840]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 10:24:43 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 10:24:43 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 26 10:24:43 volumio volumio[840]: info: CorePlayQueue::getTrack 18 Aug 26 10:24:43 volumio volumio[840]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 10:24:43 volumio volumio[840]: TypeError: Cannot read property 'name' of undefined Aug 26 10:24:43 volumio volumio[840]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Aug 26 10:24:43 volumio volumio[840]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Aug 26 10:24:43 volumio volumio[840]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Aug 26 10:24:43 volumio volumio[840]: at Socket.emit (events.js:315:20) Aug 26 10:24:43 volumio volumio[840]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 26 10:24:43 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 26 10:24:43 volumio volumio[840]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 10:24:45 volumio sudo[16527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 10:23 Aug 26 10:24:45 volumio sudo[16527]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"