May 30 16:46:00 volumio volumio[1182]: info: ------------------------------ 70ms May 30 16:46:00 volumio volumio[1182]: info: ------------------------------ 63ms May 30 16:46:00 volumio volumio[1182]: info: ------------------------------ 60ms May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:00 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: gpio-buttons: button2 triggered: next May 30 16:46:01 volumio volumio[1182]: info: gpio-buttons: Action: next May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioNext May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::next May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::stop May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 9 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::serviceStop May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 9 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::serviceStop May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::stop May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand stop took 30 milliseconds May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::play index undefined May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::startPlaybackTimer May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::clearAddPlayTracks USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3 May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 13 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand stop took 6 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 4 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 3 milliseconds May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:01 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand clear took 4 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3" May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"For Your Love","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/07. For Your Love.mp3","trackType":"mp3"} May 30 16:46:01 volumio volumio[1182]: verbose: CURRENT POSITION 10 May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState stateService stop May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: No code May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:01 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 39ms May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 25ms May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 24ms May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:01 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:01 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:01 volumio volumio[1182]: error: updateQueue error: null May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:01 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 18ms May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3" took 15 milliseconds May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 2ms May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand play May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:01 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 17ms May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 15ms May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand play took 15 milliseconds May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 15ms May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:01 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 1ms May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 202 milliseconds May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 201 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 201 milliseconds May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: May 30 16:46:01 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 7 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 6 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand status took 6 milliseconds May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"People Of The Night","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3","trackType":"mp3"} May 30 16:46:01 volumio volumio[1182]: verbose: CURRENT POSITION 10 May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"People Of The Night","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3","trackType":"mp3"} May 30 16:46:01 volumio volumio[1182]: verbose: CURRENT POSITION 10 May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:01 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"People Of The Night","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3","trackType":"mp3"} May 30 16:46:01 volumio volumio[1182]: verbose: CURRENT POSITION 10 May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:01 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 220ms May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 226ms May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 227ms May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 20 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 20 milliseconds May 30 16:46:01 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 20 milliseconds May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"People Of The Night","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3","trackType":"mp3"} May 30 16:46:01 volumio volumio[1182]: verbose: CURRENT POSITION 10 May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:01 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"People Of The Night","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3","trackType":"mp3"} May 30 16:46:01 volumio volumio[1182]: verbose: CURRENT POSITION 10 May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:01 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"People Of The Night","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3","trackType":"mp3"} May 30 16:46:01 volumio volumio[1182]: verbose: CURRENT POSITION 10 May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:01 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:01 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:01 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 50ms May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 49ms May 30 16:46:01 volumio volumio[1182]: info: ------------------------------ 49ms May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:01 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: gpio-buttons: button2 triggered: next May 30 16:46:03 volumio volumio[1182]: info: gpio-buttons: Action: next May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioNext May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::next May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::stop May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::serviceStop May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 10 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::serviceStop May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::stop May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand stop took 27 milliseconds May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::play index undefined May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::startPlaybackTimer May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::clearAddPlayTracks USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3 May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 4 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand stop took 4 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 3 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 3 milliseconds May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:03 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand clear took 3 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3" May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"People Of The Night","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/08. People Of The Night.mp3","trackType":"mp3"} May 30 16:46:03 volumio volumio[1182]: verbose: CURRENT POSITION 11 May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState stateService stop May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: No code May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:03 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 21ms May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 19ms May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 18ms May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:03 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:03 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:03 volumio volumio[1182]: error: updateQueue error: null May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:03 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 35ms May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3" took 34 milliseconds May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 21ms May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 2ms May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand play May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:03 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:03 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 14ms May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand play took 14 milliseconds May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 14ms May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 13ms May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 209 milliseconds May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 211 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 210 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 3 milliseconds May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pretty Young Girl (Club Mix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3","trackType":"mp3"} May 30 16:46:03 volumio volumio[1182]: verbose: CURRENT POSITION 11 May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 216ms May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: info: May 30 16:46:03 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 5 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand status took 1 milliseconds May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":320,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pretty Young Girl (Club Mix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3","trackType":"mp3"} May 30 16:46:03 volumio volumio[1182]: verbose: CURRENT POSITION 11 May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:03 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":618,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pretty Young Girl (Club Mix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3","trackType":"mp3"} May 30 16:46:03 volumio volumio[1182]: verbose: CURRENT POSITION 11 May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:03 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":618,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pretty Young Girl (Club Mix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3","trackType":"mp3"} May 30 16:46:03 volumio volumio[1182]: verbose: CURRENT POSITION 11 May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:03 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 234ms May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 234ms May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 26ms May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 19 milliseconds May 30 16:46:03 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 19 milliseconds May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pretty Young Girl (Club Mix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3","trackType":"mp3"} May 30 16:46:03 volumio volumio[1182]: verbose: CURRENT POSITION 11 May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:03 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pretty Young Girl (Club Mix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3","trackType":"mp3"} May 30 16:46:03 volumio volumio[1182]: verbose: CURRENT POSITION 11 May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:03 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:03 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:03 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 40ms May 30 16:46:03 volumio volumio[1182]: info: ------------------------------ 36ms May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:03 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:07 volumio volumio[1182]: info: gpio-buttons: button2 triggered: next May 30 16:46:07 volumio volumio[1182]: info: gpio-buttons: Action: next May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::volumioNext May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::next May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::stop May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::serviceStop May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrack 11 May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::serviceStop May 30 16:46:07 volumio volumio[1182]: info: ControllerMpd::stop May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:07 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:07 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand stop took 27 milliseconds May 30 16:46:07 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::play index undefined May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::startPlaybackTimer May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::clearAddPlayTracks USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3 May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:07 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:07 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand status took 10 milliseconds May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand stop took 5 milliseconds May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand status took 3 milliseconds May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:07 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand status took 5 milliseconds May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand clear took 3 milliseconds May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3" May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:07 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:07 volumio volumio[1182]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pretty Young Girl (Club Mix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/09. Pretty Young Girl (Club Mix '88).mp3","trackType":"mp3"} May 30 16:46:07 volumio volumio[1182]: verbose: CURRENT POSITION 12 May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::syncState stateService stop May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:07 volumio volumio[1182]: info: No code May 30 16:46:07 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:07 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:07 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:07 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 42ms May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 35ms May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:07 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:07 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:07 volumio volumio[1182]: error: updateQueue error: null May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:07 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 33ms May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 32 milliseconds May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3" took 31 milliseconds May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 4ms May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 3ms May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand play May 30 16:46:07 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 43ms May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:07 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:07 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:07 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:07 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:07 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:07 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:07 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:07 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:07 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 26ms May 30 16:46:07 volumio volumio[1182]: info: sendMpdCommand play took 24 milliseconds May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 22ms May 30 16:46:07 volumio volumio[1182]: info: ------------------------------ 21ms May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:07 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:07 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:07 volumio volumio[1182]: info: May 30 16:46:07 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:07 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:07 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:08 volumio volumio[1182]: info: May 30 16:46:08 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand status took 211 milliseconds May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:08 volumio volumio[1182]: info: May 30 16:46:08 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand status took 213 milliseconds May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand status took 213 milliseconds May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:08 volumio volumio[1182]: info: May 30 16:46:08 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand status took 5 milliseconds May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand status took 4 milliseconds May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":386,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"L.O.V.E. In My Car (Special Remix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3","trackType":"mp3"} May 30 16:46:08 volumio volumio[1182]: verbose: CURRENT POSITION 12 May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":386,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"L.O.V.E. In My Car (Special Remix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3","trackType":"mp3"} May 30 16:46:08 volumio volumio[1182]: verbose: CURRENT POSITION 12 May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:08 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":386,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"L.O.V.E. In My Car (Special Remix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3","trackType":"mp3"} May 30 16:46:08 volumio volumio[1182]: verbose: CURRENT POSITION 12 May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:08 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: ------------------------------ 231ms May 30 16:46:08 volumio volumio[1182]: info: ------------------------------ 242ms May 30 16:46:08 volumio volumio[1182]: info: ------------------------------ 243ms May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand status took 29 milliseconds May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 25 milliseconds May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 22 milliseconds May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":386,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"L.O.V.E. In My Car (Special Remix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3","trackType":"mp3"} May 30 16:46:08 volumio volumio[1182]: verbose: CURRENT POSITION 12 May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:08 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":386,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"L.O.V.E. In My Car (Special Remix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3","trackType":"mp3"} May 30 16:46:08 volumio volumio[1182]: verbose: CURRENT POSITION 12 May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:08 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: ------------------------------ 63ms May 30 16:46:08 volumio volumio[1182]: info: ------------------------------ 62ms May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 75 milliseconds May 30 16:46:08 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:08 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":386,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"L.O.V.E. In My Car (Special Remix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3","trackType":"mp3"} May 30 16:46:08 volumio volumio[1182]: verbose: CURRENT POSITION 12 May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:08 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:08 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:08 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:08 volumio volumio[1182]: info: ------------------------------ 116ms May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:08 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: gpio-buttons: button2 triggered: next May 30 16:46:09 volumio volumio[1182]: info: gpio-buttons: Action: next May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioNext May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::next May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::stop May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::serviceStop May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 12 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::serviceStop May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::stop May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand stop took 32 milliseconds May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::play index undefined May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::startPlaybackTimer May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::clearAddPlayTracks USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3 May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 6 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand stop took 5 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 5 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:09 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand clear took 2 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3" May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L.O.V.E. In My Car (Special Remix '88)","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/10. L.O.V.E. In My Car (Special Remix '88).mp3","trackType":"mp3"} May 30 16:46:09 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState stateService stop May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: No code May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:09 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 40ms May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 39ms May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 36ms May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:09 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:09 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:09 volumio volumio[1182]: error: updateQueue error: null May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:09 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 35ms May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3" took 34 milliseconds May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 3ms May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 2ms May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand play May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:09 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:09 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:09 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 22ms May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand play took 22 milliseconds May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 22ms May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 21ms May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 217 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 217 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 216 milliseconds May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:09 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:09 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:09 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:09 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:09 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 227ms May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 233ms May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 232ms May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: May 30 16:46:09 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 19 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 4 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 33 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 32 milliseconds May 30 16:46:09 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 32 milliseconds May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:09 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:09 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:09 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:09 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:09 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:09 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:09 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:09 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 75ms May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 61ms May 30 16:46:09 volumio volumio[1182]: info: ------------------------------ 60ms May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:09 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:20 volumio volumio[1182]: info: peppy_screensaver: Start PeppyMeter May 30 16:46:25 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 30 16:46:25 volumio volumio[1182]: info: CURURI: music-library/USB/FAF4D4A7F4D46801/1978 La Bionda May 30 16:46:25 volumio volumio[1182]: info: Preload queue cleared May 30 16:46:30 volumio volumio[1182]: info: gpio-buttons: button1 triggered: playPause May 30 16:46:30 volumio volumio[1182]: info: gpio-buttons: Action: playPause May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::volumioGetState May 30 16:46:30 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::volumioPause May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::pause May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::servicePause May 30 16:46:30 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::servicePause May 30 16:46:30 volumio volumio[1182]: info: ControllerMpd::pause May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand pause May 30 16:46:30 volumio volumio[1182]: info: May 30 16:46:30 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:30 volumio volumio[1182]: info: sendMpdCommand pause took 2 milliseconds May 30 16:46:30 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:30 volumio volumio[1182]: info: May 30 16:46:30 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:30 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:30 volumio volumio[1182]: info: sendMpdCommand status took 1 milliseconds May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:30 volumio volumio[1182]: info: May 30 16:46:30 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:30 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:30 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:30 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:30 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:30 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:30 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:30 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21893,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:30 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:30 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:30 volumio volumio[1182]: info: ------------------------------ 11ms May 30 16:46:30 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 16:46:30 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 8 milliseconds May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:30 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:30 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:30 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:30 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21893,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:30 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:30 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:30 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:30 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:30 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21893,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:30 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:30 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:30 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:30 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:30 volumio volumio[1182]: info: ------------------------------ 23ms May 30 16:46:30 volumio volumio[1182]: info: ------------------------------ 22ms May 30 16:46:30 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:46:30 volumio volumio[1182]: info: peppy_screensaver: Genuine stop — paused May 30 16:46:30 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:46:30 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:46:30 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:46:34 volumio volumio[1182]: info: gpio-buttons: button4 triggered: playPause May 30 16:46:34 volumio volumio[1182]: info: gpio-buttons: Action: playPause May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::volumioGetState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::volumioPlay May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::play index undefined May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::startPlaybackTimer May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: info: ControllerMpd::resume May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand play May 30 16:46:34 volumio volumio[1182]: info: May 30 16:46:34 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:34 volumio volumio[1182]: info: sendMpdCommand play took 221 milliseconds May 30 16:46:34 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:34 volumio volumio[1182]: info: May 30 16:46:34 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:34 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:34 volumio volumio[1182]: info: May 30 16:46:34 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:34 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:34 volumio volumio[1182]: info: sendMpdCommand status took 4 milliseconds May 30 16:46:34 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:34 volumio volumio[1182]: info: sendMpdCommand status took 1 milliseconds May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:34 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:34 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:34 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:34 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:34 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22627,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:34 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:34 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22627,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:34 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:34 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:34 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22627,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:34 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:34 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:34 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:34 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:34 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:34 volumio volumio[1182]: info: ------------------------------ 31ms May 30 16:46:34 volumio volumio[1182]: info: ------------------------------ 30ms May 30 16:46:34 volumio volumio[1182]: info: ------------------------------ 30ms May 30 16:46:34 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:34 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:34 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:34 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:34 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:36 volumio volumio[1182]: info: gpio-buttons: button4 triggered: playPause May 30 16:46:36 volumio volumio[1182]: info: gpio-buttons: Action: playPause May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::volumioGetState May 30 16:46:36 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::volumioPause May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::pause May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::servicePause May 30 16:46:36 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::servicePause May 30 16:46:36 volumio volumio[1182]: info: ControllerMpd::pause May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand pause May 30 16:46:36 volumio volumio[1182]: info: May 30 16:46:36 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:36 volumio volumio[1182]: info: sendMpdCommand pause took 3 milliseconds May 30 16:46:36 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:36 volumio volumio[1182]: info: May 30 16:46:36 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:36 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:36 volumio volumio[1182]: info: May 30 16:46:36 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:36 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:36 volumio volumio[1182]: info: sendMpdCommand status took 6 milliseconds May 30 16:46:36 volumio volumio[1182]: info: sendMpdCommand status took 5 milliseconds May 30 16:46:36 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:36 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 16:46:36 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:46:36 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:36 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:36 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:36 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:36 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":23750,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:36 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:36 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:36 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:36 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:36 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":23750,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:36 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:36 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:36 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:36 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:36 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":23750,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:36 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:36 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:36 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:36 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:36 volumio volumio[1182]: info: ------------------------------ 29ms May 30 16:46:36 volumio volumio[1182]: info: ------------------------------ 28ms May 30 16:46:36 volumio volumio[1182]: info: ------------------------------ 27ms May 30 16:46:36 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:46:36 volumio volumio[1182]: info: peppy_screensaver: Genuine stop — paused May 30 16:46:36 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:46:36 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:46:36 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:46:37 volumio volumio[1182]: info: gpio-buttons: button1 triggered: playPause May 30 16:46:37 volumio volumio[1182]: info: gpio-buttons: Action: playPause May 30 16:46:37 volumio volumio[1182]: info: CoreCommandRouter::volumioGetState May 30 16:46:37 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:37 volumio volumio[1182]: info: CoreCommandRouter::volumioPlay May 30 16:46:37 volumio volumio[1182]: info: CoreStateMachine::play index undefined May 30 16:46:37 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:37 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:37 volumio volumio[1182]: info: CoreStateMachine::startPlaybackTimer May 30 16:46:37 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:37 volumio volumio[1182]: info: ControllerMpd::resume May 30 16:46:37 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand play May 30 16:46:38 volumio volumio[1182]: info: May 30 16:46:38 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:38 volumio volumio[1182]: info: sendMpdCommand play took 223 milliseconds May 30 16:46:38 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:38 volumio volumio[1182]: info: May 30 16:46:38 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:38 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:38 volumio volumio[1182]: info: sendMpdCommand status took 3 milliseconds May 30 16:46:38 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:38 volumio volumio[1182]: info: May 30 16:46:38 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:38 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:38 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:38 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:38 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:38 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:38 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:38 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24300,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:38 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:38 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:38 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:38 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:38 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24392,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:38 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:38 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:38 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:38 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:38 volumio volumio[1182]: info: ------------------------------ 21ms May 30 16:46:38 volumio volumio[1182]: info: ------------------------------ 22ms May 30 16:46:38 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 17 milliseconds May 30 16:46:38 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:38 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:38 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:38 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24484,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:38 volumio volumio[1182]: verbose: CURRENT POSITION 13 May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:38 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:38 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:38 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:38 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:38 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:38 volumio volumio[1182]: info: ------------------------------ 31ms May 30 16:46:38 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:38 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:38 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:38 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:38 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:42 volumio volumio[1182]: info: Preload queue cleared May 30 16:46:42 volumio volumio[1182]: info: May 30 16:46:42 volumio volumio[1182]: [1780148802945] ---------------------------- Client requests add and Play Volumio CUE entry May 30 16:46:42 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue May 30 16:46:42 volumio volumio[1182]: info: Adding CUE individual entry: 0 USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue May 30 16:46:42 volumio volumio[1182]: info: CoreCommandRouter::volumioAddQueueItems May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::addQueueItems May 30 16:46:42 volumio volumio[1182]: info: CorePlayQueue::addQueueItems May 30 16:46:42 volumio volumio[1182]: info: Preload queue cleared May 30 16:46:42 volumio volumio[1182]: info: Adding Item to queue: cue://USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue@0 May 30 16:46:42 volumio volumio[1182]: info: Exploding uri cue://USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue@0 in service mpd May 30 16:46:42 volumio volumio[1182]: info: CoreCommandRouter::volumioPlay May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::play index 15 May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::stop May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:42 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:42 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:42 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:42 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::serviceStop May 30 16:46:42 volumio volumio[1182]: info: CorePlayQueue::getTrack 13 May 30 16:46:42 volumio volumio[1182]: info: CoreCommandRouter::serviceStop May 30 16:46:42 volumio volumio[1182]: info: ControllerMpd::stop May 30 16:46:42 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:42 volumio volumio[1182]: info: CoreCommandRouter::volumioPushQueue May 30 16:46:42 volumio volumio[1182]: info: CorePlayQueue::saveQueue May 30 16:46:42 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:46:42 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:46:42 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand stop took 55 milliseconds May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::play index undefined May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::startPlaybackTimer May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 6 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand stop took 4 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 3 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 2 milliseconds May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:43 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand clear took 1 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand load "USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue" May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"You're A Woman","artist":"Bad Boys Blue","album":"Hot Girls, Bad Boys","uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/11. You're A Woman (Instrumental).mp3","trackType":"mp3"} May 30 16:46:43 volumio volumio[1182]: verbose: CURRENT POSITION 15 May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState stateService stop May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: No code May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:43 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 21ms May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 17ms May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 17ms May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:43 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:43 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:43 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:46:43 volumio volumio[1182]: error: updateQueue error: null May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:43 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 21ms May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand load "USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue" took 20 milliseconds May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 7ms May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 6ms May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand play May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:43 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:46:43 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 36ms May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand play took 33 milliseconds May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 33ms May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 27ms May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 219 milliseconds May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 220 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 217 milliseconds May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: May 30 16:46:43 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 7 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 7 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand status took 6 milliseconds May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":384,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"18734 Kbps","isStreaming":false,"title":"There For Me","artist":"La Bionda","album":"La Bionda (Polydor PD-1-6157, US)","uri":"USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side A).wv","trackType":"wv"} May 30 16:46:43 volumio volumio[1182]: verbose: CURRENT POSITION 15 May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":210,"samplerate":384,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"18734 Kbps","isStreaming":false,"title":"There For Me","artist":"La Bionda","album":"La Bionda (Polydor PD-1-6157, US)","uri":"USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side A).wv","trackType":"wv"} May 30 16:46:43 volumio volumio[1182]: verbose: CURRENT POSITION 15 May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:43 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3,"duration":210,"samplerate":384,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"18734 Kbps","isStreaming":false,"title":"There For Me","artist":"La Bionda","album":"La Bionda (Polydor PD-1-6157, US)","uri":"USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side A).wv","trackType":"wv"} May 30 16:46:43 volumio volumio[1182]: verbose: CURRENT POSITION 15 May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:43 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 242ms May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 245ms May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 244ms May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 17 milliseconds May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82,"duration":210,"samplerate":384,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"18734 Kbps","isStreaming":false,"title":"There For Me","artist":"La Bionda","album":"La Bionda (Polydor PD-1-6157, US)","uri":"USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side A).wv","trackType":"wv"} May 30 16:46:43 volumio volumio[1182]: verbose: CURRENT POSITION 15 May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:43 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 32ms May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 53 milliseconds May 30 16:46:43 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 53 milliseconds May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90,"duration":210,"samplerate":384,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"18543 Kbps","isStreaming":false,"title":"There For Me","artist":"La Bionda","album":"La Bionda (Polydor PD-1-6157, US)","uri":"USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side A).wv","trackType":"wv"} May 30 16:46:43 volumio volumio[1182]: verbose: CURRENT POSITION 15 May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:43 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94,"duration":210,"samplerate":384,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"18543 Kbps","isStreaming":false,"title":"There For Me","artist":"La Bionda","album":"La Bionda (Polydor PD-1-6157, US)","uri":"USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side A).wv","trackType":"wv"} May 30 16:46:43 volumio volumio[1182]: verbose: CURRENT POSITION 15 May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:46:43 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:46:43 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:46:43 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 87ms May 30 16:46:43 volumio volumio[1182]: info: ------------------------------ 86ms May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:46:43 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:00 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 30 16:47:00 volumio volumio[1182]: info: CURURI: music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue May 30 16:47:00 volumio volumio[1182]: info: Preload queue cleared May 30 16:47:00 volumio volumio[1182]: info: Preloading song: music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0001 May 30 16:47:00 volumio volumio[1182]: info: Preloading song: music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0002 May 30 16:47:00 volumio volumio[1182]: info: Preloading song: music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0003 May 30 16:47:00 volumio volumio[1182]: info: Preloading song: music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0004 May 30 16:47:00 volumio volumio[1182]: info: Preloading song: music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0005 May 30 16:47:00 volumio systemd[1]: Starting apt-daily.service - Daily apt download activities... May 30 16:47:00 volumio volumio[1182]: info: Exploding uri music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0001 in service mpd May 30 16:47:00 volumio volumio[1182]: error: scanFolder - failure to stat '/mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0001' May 30 16:47:00 volumio volumio[1182]: info: Exploding uri music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0002 in service mpd May 30 16:47:00 volumio volumio[1182]: error: scanFolder - failure to stat '/mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0002' May 30 16:47:00 volumio volumio[1182]: info: Exploding uri music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0003 in service mpd May 30 16:47:00 volumio volumio[1182]: error: scanFolder - failure to stat '/mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0003' May 30 16:47:00 volumio volumio[1182]: info: Exploding uri music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0004 in service mpd May 30 16:47:00 volumio volumio[1182]: error: scanFolder - failure to stat '/mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0004' May 30 16:47:01 volumio volumio[1182]: info: Exploding uri music-library/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0005 in service mpd May 30 16:47:01 volumio volumio[1182]: error: scanFolder - failure to stat '/mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue/track0005' May 30 16:47:03 volumio systemd[1]: apt-daily.service: Deactivated successfully. May 30 16:47:03 volumio systemd[1]: Finished apt-daily.service - Daily apt download activities. May 30 16:47:03 volumio systemd[1]: apt-daily.service: Consumed 1.614s CPU time. May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioNext May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::next May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::stop May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::serviceStop May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 15 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::serviceStop May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::stop May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand stop took 18 milliseconds May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::play index undefined May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::startPlaybackTimer May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::clearAddPlayTracks USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3 May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 15 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand stop took 14 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 13 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 12 milliseconds May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:47:11 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 10 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand clear took 13 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 15 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 15 milliseconds May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3" May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"There For Me","artist":"La Bionda","album":"La Bionda (Polydor PD-1-6157, US)","uri":"USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side A).wv","trackType":"wv"} May 30 16:47:11 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState stateService stop May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: No code May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:47:11 volumio volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 63ms May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 62ms May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 61ms May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:47:11 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:47:11 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:47:11 volumio volumio[1182]: error: updateQueue error: null May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:47:11 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 40ms May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand add "USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3" took 29 milliseconds May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 3ms May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 2ms May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand play May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:47:11 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces system playlist update May 30 16:47:11 volumio volumio[1182]: info: Ignoring MPD Status Update May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 30 16:47:11 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 46ms May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand play took 44 milliseconds May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 44ms May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 39ms May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 199 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 200 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 199 milliseconds May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: info: May 30 16:47:11 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 5 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 5 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand status took 4 milliseconds May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:11 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus stop May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:11 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:47:11 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":252,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:11 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:47:11 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 216ms May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 220ms May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 220ms May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 13 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 14 milliseconds May 30 16:47:11 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 14 milliseconds May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:11 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:47:11 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:11 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:47:11 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:11 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState stateService play May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus play May 30 16:47:11 volumio volumio[1182]: info: Received an update from plugin. extracting info from payload May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:11 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:11 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 43ms May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 39ms May 30 16:47:11 volumio volumio[1182]: info: ------------------------------ 40ms May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:11 volumio volumio[1182]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 30 16:47:15 volumio volumio[1182]: info: gpio-buttons: button4 triggered: playPause May 30 16:47:15 volumio volumio[1182]: info: gpio-buttons: Action: playPause May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::volumioGetState May 30 16:47:15 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::volumioPause May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::pause May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::servicePause May 30 16:47:15 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::servicePause May 30 16:47:15 volumio volumio[1182]: info: ControllerMpd::pause May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand pause May 30 16:47:15 volumio volumio[1182]: info: May 30 16:47:15 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:15 volumio volumio[1182]: info: sendMpdCommand pause took 4 milliseconds May 30 16:47:15 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:15 volumio volumio[1182]: info: May 30 16:47:15 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:15 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:15 volumio volumio[1182]: info: May 30 16:47:15 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:15 volumio volumio[1182]: info: ControllerMpd::getState May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand status May 30 16:47:15 volumio volumio[1182]: info: sendMpdCommand status took 7 milliseconds May 30 16:47:15 volumio volumio[1182]: info: sendMpdCommand status took 5 milliseconds May 30 16:47:15 volumio volumio[1182]: info: sendMpdCommand status took 3 milliseconds May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::parseState May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:47:15 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:47:15 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:47:15 volumio volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:15 volumio volumio[1182]: verbose: ControllerMpd::parseTrackInfo May 30 16:47:15 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:15 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:15 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4424,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:15 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:15 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:47:15 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:15 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:15 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4424,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:15 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:15 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:47:15 volumio volumio[1182]: info: ControllerMpd::pushState May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::servicePushState May 30 16:47:15 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:15 volumio volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4424,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track06","artist":null,"album":null,"uri":"USB/FAF4D4A7F4D46801/Bad Boys Blue/Bad Boys Blue - Hot Girls, Bad Boys - 1985 (2025)/13. L.O.V.E. In My Car (7'' Version).mp3","trackType":"mp3"} May 30 16:47:15 volumio volumio[1182]: verbose: CURRENT POSITION 0 May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::syncState stateService pause May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::syncState currentStatus pause May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:15 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:15 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:15 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:47:15 volumio volumio[1182]: info: ------------------------------ 34ms May 30 16:47:15 volumio volumio[1182]: info: ------------------------------ 31ms May 30 16:47:15 volumio volumio[1182]: info: ------------------------------ 30ms May 30 16:47:15 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:47:15 volumio volumio[1182]: info: peppy_screensaver: Genuine stop — paused May 30 16:47:15 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:47:15 volumio volumio[1182]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false May 30 16:47:15 volumio volumio[1182]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 30 16:47:24 volumio volumio[1182]: info: Preload queue cleared May 30 16:47:24 volumio volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 16:47:24 volumio volumio[1182]: info: CoreStateMachine::ClearQueue May 30 16:47:24 volumio volumio[1182]: info: CoreStateMachine::stop May 30 16:47:24 volumio volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:47:24 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 30 16:47:24 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 30 16:47:24 volumio volumio[1182]: info: CoreStateMachine::stPlaybackTimer May 30 16:47:24 volumio volumio[1182]: info: CoreStateMachine::pushState May 30 16:47:24 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:24 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:47:24 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 30 16:47:24 volumio volumio[1182]: info: CoreStateMachine::serviceStop May 30 16:47:24 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 30 16:47:24 volumio volumio[1182]: info: CoreCommandRouter::serviceStop May 30 16:47:24 volumio volumio[1182]: info: ControllerMpd::stop May 30 16:47:24 volumio volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:47:24 volumio volumio[1182]: info: CorePlayQueue::clearPlayQueue May 30 16:47:24 volumio volumio[1182]: info: CorePlayQueue::saveQueue May 30 16:47:24 volumio volumio[1182]: info: CoreCommandRouter::volumioPushQueue May 30 16:47:24 volumio volumio[1182]: info: CoreStateMachine::addQueueItems May 30 16:47:24 volumio volumio[1182]: info: CorePlayQueue::addQueueItems May 30 16:47:24 volumio volumio[1182]: info: Preload queue cleared May 30 16:47:24 volumio volumio[1182]: info: Adding Item to queue: music-library/USB/FAF4D4A7F4D46801/1978 La Bionda May 30 16:47:24 volumio volumio[1182]: info: Exploding uri music-library/USB/FAF4D4A7F4D46801/1978 La Bionda in service mpd May 30 16:47:24 volumio volumio[1182]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 30 16:47:24 volumio volumio[1182]: info: May 30 16:47:24 volumio volumio[1182]: ---------------------------- MPD announces state update: player May 30 16:47:24 volumio volumio[1182]: info: ALBUMART /albumart?cacheid=651&web=//medium&path=%2Fmnt%2FUSB%2FFAF4D4A7F4D46801%2F1978%20La%20Bionda%2FLa%20Bionda%20-%20La%20Bionda%20(Side%20A).wv&metadata=false May 30 16:47:24 volumio volumio[1182]: info: URI /mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side A).wv May 30 16:47:24 volumio volumio[1182]: info: ALBUMART /albumart?cacheid=651&web=//medium&path=%2Fmnt%2FUSB%2FFAF4D4A7F4D46801%2F1978%20La%20Bionda%2FLa%20Bionda%20-%20La%20Bionda%20(Side%20B).wv&metadata=false May 30 16:47:24 volumio volumio[1182]: info: URI /mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda (Side B).wv May 30 16:47:24 volumio volumio[1182]: info: ALBUMART /albumart?cacheid=651&web=La%20Bionda/La%20Bionda%20(Polydor%20PD-1-6157%2C%20US)/medium&path=%2Fmnt%2FUSB%2FFAF4D4A7F4D46801%2F1978%20La%20Bionda%2FLa%20Bionda%20-%20La%20Bionda.cue&metadata=false May 30 16:47:24 volumio volumio[1182]: info: URI /mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue May 30 16:47:24 volumio volumio[1182]: info: ALBUMART /albumart?cacheid=651&web=La%20Bionda/La%20Bionda%20(Polydor%20PD-1-6157%2C%20US)/medium&path=%2Fmnt%2FUSB%2FFAF4D4A7F4D46801%2F1978%20La%20Bionda%2FLa%20Bionda%20-%20La%20Bionda.cue&metadata=false May 30 16:47:24 volumio volumio[1182]: info: URI /mnt/USB/FAF4D4A7F4D46801/1978 La Bionda/La Bionda - La Bionda.cue May 30 16:47:24 volumio volumio[1182]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 16:47:24 volumio volumio[1182]: Error: Unable to resolve or reject the same promise twice May 30 16:47:24 volumio volumio[1182]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 30 16:47:24 volumio volumio[1182]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 May 30 16:47:24 volumio volumio[1182]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 30 16:47:24 volumio volumio[1182]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 30 16:47:24 volumio volumio[1182]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 30 16:47:24 volumio volumio[1182]: at Socket.emit (node:events:514:28) May 30 16:47:24 volumio volumio[1182]: at addChunk (node:internal/streams/readable:343:12) May 30 16:47:24 volumio volumio[1182]: at readableAddChunk (node:internal/streams/readable:312:11) May 30 16:47:24 volumio volumio[1182]: at Readable.push (node:internal/streams/readable:253:10) May 30 16:47:24 volumio volumio[1182]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 30 16:47:24 volumio volumio[1182]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 16:47:25 volumio sudo[5156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 16:46' May 30 16:47:25 volumio sudo[5156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"