Jan 25 17:30:05 volumio volumio[1246]: info: Preload queue cleared
Jan 25 17:30:05 volumio volumio[1246]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jan 25 17:30:05 volumio volumio[1246]: info: CoreStateMachine::ClearQueue
Jan 25 17:30:05 volumio volumio[1246]: info: CoreStateMachine::stop
Jan 25 17:30:05 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 25 17:30:05 volumio volumio[1246]: info: CorePlayQueue::clearPlayQueue
Jan 25 17:30:05 volumio volumio[1246]: info: CorePlayQueue::saveQueue
Jan 25 17:30:05 volumio volumio[1246]: info: CoreCommandRouter::volumioPushQueue
Jan 25 17:30:05 volumio volumio[1246]: info: CoreStateMachine::addQueueItems
Jan 25 17:30:05 volumio volumio[1246]: info: CorePlayQueue::addQueueItems
Jan 25 17:30:05 volumio volumio[1246]: info: Preload queue cleared
Jan 25 17:30:05 volumio volumio[1246]: info: Adding Item to queue: music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal
Jan 25 17:30:05 volumio volumio[1246]: info: Exploding uri music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal in service mpd
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(1%20-%20Beloved).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(10%20-%20Querido%20Amigo).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (10 - Querido Amigo).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(11%20-%20Dance%20Of%20Spring).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (11 - Dance Of Spring).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(12%20-%20Mario%20Takes%20A%20Walk).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (12 - Mario Takes A Walk).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(13%20-%20Baghdad).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (13 - Baghdad).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(14%20-%20Fall%20At%20Your%20Feet).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (14 - Fall At Your Feet).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(2%20-%20Rattle%20and%20Burn).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (2 - Rattle and Burn).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(3%20-%20Cascada).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (3 - Cascada).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(4%20-%20Breeze%20from%20Saintes%20Maries).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (4 - Breeze from Saintes Maries).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(5%20-%20Jumpstart).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (5 - Jumpstart).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(6%20-%20Air).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (6 - Air).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(7%20-%20Luna%20Llena).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (7 - Luna Llena).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(8%20-%20Closer%20To%20Madness).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (8 - Closer To Madness).flac
Jan 25 17:30:05 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/Montreal/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2005%20-%20Montreal%2FJesse%20Cook%20-%20Montreal%20(9%20-%20Tempest).flac&metadata=false
Jan 25 17:30:05 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (9 - Tempest).flac
Jan 25 17:30:05 volumio volumio[1246]: info: CoreCommandRouter::volumioPushQueue
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::saveQueue
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::volumioPlay
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::play index 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::stop
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::play index undefined
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::startPlaybackTimer
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand stop
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand stop took 7 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand clear
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Jan 25 17:30:06 volumio volumio[1246]: info: Ignoring MPD Status Update
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand clear took 4 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac"
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Jan 25 17:30:06 volumio volumio[1246]: info: Ignoring MPD Status Update
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Jan 25 17:30:06 volumio volumio[1246]: info: Ignoring MPD Status Update
Jan 25 17:30:06 volumio volumio[1246]: error: updateQueue error: null
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Jan 25 17:30:06 volumio volumio[1246]: info: Ignoring MPD Status Update
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 15ms
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand add "USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac" took 15 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 13ms
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 11ms
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand play
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Jan 25 17:30:06 volumio volumio[1246]: info: Ignoring MPD Status Update
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Jan 25 17:30:06 volumio volumio[1246]: info: Ignoring MPD Status Update
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 16ms
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand play took 10 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 9ms
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 9ms
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces state update: player
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::getState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces state update: player
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::getState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces state update: player
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::getState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces state update: player
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand clearerror
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand status took 91 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::getState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces state update: player
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::getState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand clearerror
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand status took 99 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand clearerror
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand status took 99 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand clearerror took 12 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand status took 13 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 11 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand status took 10 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beloved","artist":"Jesse Cook","album":"Montreal","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac","trackType":"flac"}
Jan 25 17:30:06 volumio volumio[1246]: verbose: CURRENT POSITION 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 131ms
Jan 25 17:30:06 volumio volumio[1246]: info:
Jan 25 17:30:06 volumio volumio[1246]: ---------------------------- MPD announces state update: player
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::getState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand clearerror took 43 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand clearerror took 41 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 35 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 34 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 35 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 32 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand status took 16 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseState
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beloved","artist":"Jesse Cook","album":"Montreal","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac","trackType":"flac"}
Jan 25 17:30:06 volumio volumio[1246]: verbose: CURRENT POSITION 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beloved","artist":"Jesse Cook","album":"Montreal","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac","trackType":"flac"}
Jan 25 17:30:06 volumio volumio[1246]: verbose: CURRENT POSITION 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beloved","artist":"Jesse Cook","album":"Montreal","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac","trackType":"flac"}
Jan 25 17:30:06 volumio volumio[1246]: verbose: CURRENT POSITION 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beloved","artist":"Jesse Cook","album":"Montreal","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac","trackType":"flac"}
Jan 25 17:30:06 volumio volumio[1246]: verbose: CURRENT POSITION 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 194ms
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 193ms
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 116ms
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 104ms
Jan 25 17:30:06 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 50 milliseconds
Jan 25 17:30:06 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Jan 25 17:30:06 volumio volumio[1246]: info: ControllerMpd::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beloved","artist":"Jesse Cook","album":"Montreal","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2005 - Montreal/Jesse Cook - Montreal (1 - Beloved).flac","trackType":"flac"}
Jan 25 17:30:06 volumio volumio[1246]: verbose: CURRENT POSITION 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop
Jan 25 17:30:06 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:06 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 25 17:30:06 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:06 volumio volumio[1246]: info: ------------------------------ 84ms
Jan 25 17:30:06 volumio volumio[1246]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found
Jan 25 17:30:06 volumio volumio[1246]: at createHttpError (/volumio/node_modules/send/index.js:979:12)
Jan 25 17:30:06 volumio volumio[1246]: at SendStream.error (/volumio/node_modules/send/index.js:270:31)
Jan 25 17:30:06 volumio volumio[1246]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14)
Jan 25 17:30:06 volumio volumio[1246]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8)
Jan 25 17:30:06 volumio volumio[1246]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3)
Jan 25 17:30:06 volumio volumio[1246]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9)
Jan 25 17:30:06 volumio volumio[1246]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Jan 25 17:30:06 volumio volumio[1246]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11)
Jan 25 17:30:34 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState
Jan 25 17:30:34 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 25 17:30:38 volumio volumio[1246]: info: Getting Alsa Cards List without I2S DAC
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: Invalid card number '2'.
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Jan 25 17:30:38 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jan 25 17:30:45 volumio volumio[1246]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Jan 25 17:30:45 volumio volumio[1246]: info: Preparing to save Alsa Options, stopping services first
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState
Jan 25 17:30:45 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::volumioPause
Jan 25 17:30:45 volumio volumio[1246]: info: CoreStateMachine::pause
Jan 25 17:30:45 volumio volumio[1246]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":false,"i2sid":{"value":"allo-boss-dac-pcm512x-audio","label":"Allo BOSS"}}
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 25 17:30:45 volumio volumio[1246]: info: Enabling PI HDMI Output
Jan 25 17:30:45 volumio volumio[1246]: info: Disabling I2S DAC:
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 25 17:30:45 volumio volumio[1246]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jan 25 17:30:45 volumio volumio[1246]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Jan 25 17:30:45 volumio volumio[1246]: info: Disabling external Volume Control
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 25 17:30:45 volumio volumio[1246]: info: Preparing to generate the ALSA configuration file
Jan 25 17:30:45 volumio volumio[1246]: info: Raspberry PI HDMI Output Enabled
Jan 25 17:30:45 volumio sudo[4039]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store
Jan 25 17:30:45 volumio sudo[4039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 25 17:30:45 volumio sudo[4039]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jan 25 17:30:45 volumio sudo[4053]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l
Jan 25 17:30:45 volumio sudo[4053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:45 volumio sudo[4053]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:45 volumio volumio[1246]: info: No Overlays Loaded
Jan 25 17:30:45 volumio volumio[1246]: info: Alsa Settings successfully stored
Jan 25 17:30:45 volumio volumio[1246]: info: VolumeController:: Volume=86 Mute =false
Jan 25 17:30:45 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:45 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:45 volumio volumio[1246]: info: Asound.conf file written
Jan 25 17:30:45 volumio sudo[4057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Jan 25 17:30:45 volumio sudo[4057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:45 volumio sudo[4057]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:45 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Jan 25 17:30:45 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Jan 25 17:30:45 volumio volumio[1246]: info: Output device has changed, restarting MPD
Jan 25 17:30:45 volumio volumio[1246]: info: Output device has changed, restarting Shairport Sync
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:45 volumio sudo[4064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 25 17:30:45 volumio sudo[4064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:45 volumio sudo[4066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 25 17:30:45 volumio sudo[4066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:45 volumio sudo[4064]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:45 volumio volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 25 17:30:45 volumio volumio[1246]: info: Output device has changed, restarting MPD
Jan 25 17:30:45 volumio volumio[1246]: info: Output device has changed, restarting Shairport Sync
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:45 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Jan 25 17:30:45 volumio sudo[4075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 25 17:30:45 volumio sudo[4075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:45 volumio sudo[4075]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:45 volumio volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 25 17:30:45 volumio volumio[1246]: info: MPD Permissions set
Jan 25 17:30:45 volumio volumio[1246]: info: MPD Permissions set
Jan 25 17:30:45 volumio sudo[4078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 25 17:30:45 volumio sudo[4078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:45 volumio volumio[1246]: info: Starting Shairport Sync
Jan 25 17:30:46 volumio volumio[1246]: info: Starting Shairport Sync
Jan 25 17:30:46 volumio sudo[4097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 25 17:30:46 volumio sudo[4097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:46 volumio sudo[4099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 25 17:30:46 volumio sudo[4099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:46 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Jan 25 17:30:46 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Jan 25 17:30:46 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jan 25 17:30:46 volumio systemd[1]: shairport-sync.service: Consumed 10.474s CPU time.
Jan 25 17:30:46 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jan 25 17:30:46 volumio sudo[4099]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:46 volumio sudo[4097]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:46 volumio volumio[1246]: info: Shairport-Sync Started
Jan 25 17:30:46 volumio volumio[1246]: info: Shairport-Sync Started
Jan 25 17:30:48 volumio systemd[1]: mpd.service: Deactivated successfully.
Jan 25 17:30:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Jan 25 17:30:48 volumio systemd[1]: mpd.service: Consumed 10min 7.521s CPU time.
Jan 25 17:30:48 volumio systemd[1]: mpd.socket: Deactivated successfully.
Jan 25 17:30:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Jan 25 17:30:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Jan 25 17:30:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Jan 25 17:30:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Jan 25 17:30:48 volumio sudo[4133]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jan 25 17:30:48 volumio sudo[4133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Jan 25 17:30:48 volumio sudo[4133]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:49 volumio volumio[1246]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Jan 25 17:30:49 volumio volumio[1246]: info: CoreStateMachine::getcurrentVolume
Jan 25 17:30:49 volumio volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume
Jan 25 17:30:49 volumio volumio[1246]: info: VolumeController:: Volume=86 Mute =false
Jan 25 17:30:49 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:49 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:49 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 25 17:30:49 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:49 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock
Jan 25 17:30:49 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock
Jan 25 17:30:49 volumio volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume
Jan 25 17:30:49 volumio volumio[1246]: info: VolumeController:: Volume=86 Mute =false
Jan 25 17:30:49 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:49 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:49 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 25 17:30:49 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:52 volumio mpd[4135]: 2026-01-25T17:30:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jan 25 17:30:52 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Jan 25 17:30:52 volumio sudo[4066]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:52 volumio sudo[4078]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:52 volumio volumio[1246]: error: MPD error: The expression evaluated to a falsy value:
Jan 25 17:30:52 volumio volumio[1246]: assert.ok(self.idling)
Jan 25 17:30:52 volumio volumio[1246]: error: The expression evaluated to a falsy value:
Jan 25 17:30:52 volumio volumio[1246]: assert.ok(self.idling)
Jan 25 17:30:52 volumio volumio[1246]: error: updateQueue error: null
Jan 25 17:30:58 volumio volumio[1246]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Jan 25 17:30:58 volumio volumio[1246]: info: Preparing to save Alsa Options, stopping services first
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState
Jan 25 17:30:58 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::volumioPause
Jan 25 17:30:58 volumio volumio[1246]: info: CoreStateMachine::pause
Jan 25 17:30:58 volumio volumio[1246]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}}
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 25 17:30:58 volumio volumio[1246]: info: Found match in Cards Database: setting mixer PCM for card Headphones
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jan 25 17:30:58 volumio volumio[1246]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Jan 25 17:30:58 volumio volumio[1246]: info: Disabling external Volume Control
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 25 17:30:58 volumio volumio[1246]: info: Preparing to generate the ALSA configuration file
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jan 25 17:30:58 volumio volumio[1246]: info: VolumeController:: Volume=86 Mute =false
Jan 25 17:30:58 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:30:58 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:30:58 volumio volumio[1246]: info: Asound.conf file written
Jan 25 17:30:58 volumio sudo[4176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Jan 25 17:30:58 volumio sudo[4176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:58 volumio sudo[4176]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:58 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Jan 25 17:30:58 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Jan 25 17:30:58 volumio volumio[1246]: info: Output device has changed, restarting MPD
Jan 25 17:30:58 volumio sudo[4182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 25 17:30:58 volumio sudo[4182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:58 volumio sudo[4182]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:58 volumio volumio[1246]: info: Output device has changed, restarting Shairport Sync
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 25 17:30:58 volumio volumio[1246]: info: Output device has changed, restarting MPD
Jan 25 17:30:58 volumio sudo[4186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 25 17:30:58 volumio sudo[4186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:58 volumio volumio[1246]: info: Output device has changed, restarting Shairport Sync
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:58 volumio sudo[4191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 25 17:30:58 volumio sudo[4191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:58 volumio sudo[4191]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:58 volumio sudo[4195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 25 17:30:58 volumio sudo[4195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:58 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Jan 25 17:30:58 volumio volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 25 17:30:58 volumio volumio[1246]: info: MPD Permissions set
Jan 25 17:30:58 volumio volumio[1246]: info: MPD Permissions set
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 25 17:30:58 volumio volumio[1246]: info: Starting Shairport Sync
Jan 25 17:30:58 volumio volumio[1246]: info: Starting Shairport Sync
Jan 25 17:30:58 volumio sudo[4212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 25 17:30:58 volumio sudo[4212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:58 volumio systemd[1]: mpd.service: Deactivated successfully.
Jan 25 17:30:58 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Jan 25 17:30:58 volumio systemd[1]: mpd.service: Consumed 4.027s CPU time.
Jan 25 17:30:59 volumio systemd[1]: mpd.socket: Deactivated successfully.
Jan 25 17:30:59 volumio sudo[4214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 25 17:30:59 volumio sudo[4214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 25 17:30:59 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Jan 25 17:30:59 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Jan 25 17:30:59 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Jan 25 17:30:59 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Jan 25 17:30:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Jan 25 17:30:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Jan 25 17:30:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jan 25 17:30:59 volumio systemd[1]: shairport-sync.service: Consumed 1.897s CPU time.
Jan 25 17:30:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jan 25 17:30:59 volumio sudo[4212]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:59 volumio sudo[4214]: pam_unix(sudo:session): session closed for user root
Jan 25 17:30:59 volumio volumio[1246]: info: Shairport-Sync Started
Jan 25 17:30:59 volumio volumio[1246]: info: Shairport-Sync Started
Jan 25 17:30:59 volumio sudo[4218]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jan 25 17:30:59 volumio sudo[4218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Jan 25 17:30:59 volumio sudo[4218]: pam_unix(sudo:session): session closed for user root
Jan 25 17:31:02 volumio mpd[4236]: 2026-01-25T17:31:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jan 25 17:31:02 volumio volumio[1246]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Jan 25 17:31:02 volumio volumio[1246]: info: CoreStateMachine::getcurrentVolume
Jan 25 17:31:02 volumio volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume
Jan 25 17:31:02 volumio volumio[1246]: info: VolumeController:: Volume=86 Mute =false
Jan 25 17:31:02 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:31:02 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:31:02 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 25 17:31:02 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:31:02 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock
Jan 25 17:31:02 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock
Jan 25 17:31:02 volumio volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume
Jan 25 17:31:02 volumio volumio[1246]: info: VolumeController:: Volume=86 Mute =false
Jan 25 17:31:02 volumio volumio[1246]: info: CoreStateMachine::pushState
Jan 25 17:31:02 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:31:02 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Jan 25 17:31:02 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Jan 25 17:31:02 volumio sudo[4186]: pam_unix(sudo:session): session closed for user root
Jan 25 17:31:02 volumio sudo[4195]: pam_unix(sudo:session): session closed for user root
Jan 25 17:31:02 volumio volumio[1246]: error: MPD error: The expression evaluated to a falsy value:
Jan 25 17:31:02 volumio volumio[1246]: assert.ok(self.idling)
Jan 25 17:31:02 volumio volumio[1246]: error: The expression evaluated to a falsy value:
Jan 25 17:31:02 volumio volumio[1246]: assert.ok(self.idling)
Jan 25 17:31:02 volumio volumio[1246]: error: updateQueue error: null
Jan 25 17:31:07 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState
Jan 25 17:31:07 volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Jan 25 17:31:18 volumio volumio[1246]: info: Preload queue cleared
Jan 25 17:31:18 volumio volumio[1246]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jan 25 17:31:18 volumio volumio[1246]: info: CoreStateMachine::ClearQueue
Jan 25 17:31:18 volumio volumio[1246]: info: CoreStateMachine::stop
Jan 25 17:31:18 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 25 17:31:18 volumio volumio[1246]: info: CorePlayQueue::clearPlayQueue
Jan 25 17:31:18 volumio volumio[1246]: info: CorePlayQueue::saveQueue
Jan 25 17:31:18 volumio volumio[1246]: info: CoreCommandRouter::volumioPushQueue
Jan 25 17:31:18 volumio volumio[1246]: info: CoreStateMachine::addQueueItems
Jan 25 17:31:18 volumio volumio[1246]: info: CorePlayQueue::addQueueItems
Jan 25 17:31:18 volumio volumio[1246]: info: Preload queue cleared
Jan 25 17:31:18 volumio volumio[1246]: info: Adding Item to queue: music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2009 - Rumba
Jan 25 17:31:18 volumio volumio[1246]: info: Exploding uri music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2009 - Rumba in service mpd
Jan 25 17:31:18 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/The%20Rumba%20Foundation/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2009%20-%20Rumba%2FJesse%20Cook%20-%20The%20Rumba%20Foundation.cue&metadata=false
Jan 25 17:31:18 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2009 - Rumba/Jesse Cook - The Rumba Foundation.cue
Jan 25 17:31:18 volumio volumio[1246]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/The%20Rumba%20Foundation/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2009%20-%20Rumba%2FJesse%20Cook%20-%20The%20Rumba%20Foundation.cue&metadata=false
Jan 25 17:31:18 volumio volumio[1246]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2009 - Rumba/Jesse Cook - The Rumba Foundation.cue
Jan 25 17:31:18 volumio volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 25 17:31:18 volumio volumio[1246]: Error: Unable to resolve or reject the same promise twice
Jan 25 17:31:18 volumio volumio[1246]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Jan 25 17:31:18 volumio volumio[1246]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21
Jan 25 17:31:18 volumio volumio[1246]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Jan 25 17:31:18 volumio volumio[1246]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Jan 25 17:31:18 volumio volumio[1246]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Jan 25 17:31:18 volumio volumio[1246]: at Socket.emit (node:events:514:28)
Jan 25 17:31:18 volumio volumio[1246]: at addChunk (node:internal/streams/readable:343:12)
Jan 25 17:31:18 volumio volumio[1246]: at readableAddChunk (node:internal/streams/readable:312:11)
Jan 25 17:31:18 volumio volumio[1246]: at Readable.push (node:internal/streams/readable:253:10)
Jan 25 17:31:18 volumio volumio[1246]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23)
Jan 25 17:31:18 volumio volumio[1246]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 25 17:31:19 volumio sudo[4286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 17:30'
Jan 25 17:31:19 volumio sudo[4286]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025"
VOLUMIO_VERSION="4.084"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"