-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-12-27 21:18:44 CET. -- Dec 27 21:17:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 4894 Dec 27 21:17:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:28 volumio volumio[1089]: info: Prefetching next song Dec 27 21:17:28 volumio volumio[1089]: info: DOING PREFETCH IN MPD Dec 27 21:17:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC/Billy Joel - The Complete Albums Collection/CD10 An Innocent Man/05 - Billy Joel - Tell Her about it.flac" Dec 27 21:17:28 volumio volumio[1089]: info: Dec 27 21:17:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:17:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:17:28 volumio volumio[1089]: info: sendMpdCommand add "USB/MUSIC/Billy Joel - The Complete Albums Collection/CD10 An Innocent Man/05 - Billy Joel - Tell Her about it.flac" took 6 milliseconds Dec 27 21:17:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 27 21:17:28 volumio volumio[1089]: info: Dec 27 21:17:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:17:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:17:28 volumio volumio[1089]: info: Dec 27 21:17:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:17:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:17:28 volumio volumio[1089]: info: ------------------------------ 7ms Dec 27 21:17:28 volumio volumio[1089]: info: sendMpdCommand consume 1 took 5 milliseconds Dec 27 21:17:28 volumio volumio[1089]: info: ------------------------------ 4ms Dec 27 21:17:28 volumio volumio[1089]: info: ------------------------------ 3ms Dec 27 21:17:32 volumio volumio[1089]: info: CoreStateMachine::startPlaybackTimer Dec 27 21:17:32 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: info: Dec 27 21:17:33 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:17:33 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:17:33 volumio volumio[1089]: info: Dec 27 21:17:33 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:17:33 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:17:33 volumio volumio[1089]: info: Dec 27 21:17:33 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:17:33 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:17:33 volumio volumio[1089]: info: Dec 27 21:17:33 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:17:33 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:17:33 volumio volumio[1089]: info: Dec 27 21:17:33 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:17:33 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:17:33 volumio volumio[1089]: info: Dec 27 21:17:33 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:17:33 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:17:33 volumio volumio[1089]: info: ------------------------------ 5ms Dec 27 21:17:33 volumio volumio[1089]: info: sendMpdCommand status took 4 milliseconds Dec 27 21:17:33 volumio volumio[1089]: info: ------------------------------ 3ms Dec 27 21:17:33 volumio volumio[1089]: info: sendMpdCommand status took 3 milliseconds Dec 27 21:17:33 volumio volumio[1089]: info: ------------------------------ 3ms Dec 27 21:17:33 volumio volumio[1089]: info: sendMpdCommand status took 2 milliseconds Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:17:33 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 21:17:33 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 21:17:33 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:17:33 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:17:33 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1014 Kbps","isStreaming":false,"title":"Tell Her about it","artist":"Billy Joel","album":"An Innocent Man","uri":"USB/MUSIC/Billy Joel - The Complete Albums Collection/CD10 An Innocent Man/05 - Billy Joel - Tell Her about it.flac","trackType":"flac"} Dec 27 21:17:33 volumio volumio[1089]: verbose: CURRENT POSITION 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:17:33 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:17:33 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1014 Kbps","isStreaming":false,"title":"Tell Her about it","artist":"Billy Joel","album":"An Innocent Man","uri":"USB/MUSIC/Billy Joel - The Complete Albums Collection/CD10 An Innocent Man/05 - Billy Joel - Tell Her about it.flac","trackType":"flac"} Dec 27 21:17:33 volumio volumio[1089]: verbose: CURRENT POSITION 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:17:33 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:17:33 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1014 Kbps","isStreaming":false,"title":"Tell Her about it","artist":"Billy Joel","album":"An Innocent Man","uri":"USB/MUSIC/Billy Joel - The Complete Albums Collection/CD10 An Innocent Man/05 - Billy Joel - Tell Her about it.flac","trackType":"flac"} Dec 27 21:17:33 volumio volumio[1089]: verbose: CURRENT POSITION 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:17:33 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:17:33 volumio volumio[1089]: info: ------------------------------ 48ms Dec 27 21:17:33 volumio volumio[1089]: info: ------------------------------ 47ms Dec 27 21:17:33 volumio volumio[1089]: info: ------------------------------ 46ms Dec 27 21:17:33 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:33 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:33 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:17:52 volumio volumio[1089]: info: VolumeController::SetAlsaVolume45 Dec 27 21:17:53 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:53 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:53 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:17:53 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:17:54 volumio volumio[1089]: info: VolumeController::SetAlsaVolume40 Dec 27 21:17:54 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:17:54 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:17:54 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:17:54 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:09 volumio volumio[1089]: Searching all installed plugins Dec 27 21:18:09 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 21:18:09 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: , search Dec 27 21:18:09 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 21:18:09 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 27 21:18:09 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 27 21:18:09 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 27 21:18:09 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 27 21:18:09 volumio volumio[1089]: info: All search sources collected, pushing search results Dec 27 21:18:10 volumio volumio[1089]: Searching all installed plugins Dec 27 21:18:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 21:18:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: , search Dec 27 21:18:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 21:18:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 27 21:18:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 27 21:18:10 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 27 21:18:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 27 21:18:11 volumio volumio[1089]: Searching all installed plugins Dec 27 21:18:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 21:18:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: , search Dec 27 21:18:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 21:18:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 27 21:18:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 27 21:18:11 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 27 21:18:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 27 21:18:11 volumio volumio[1089]: info: All search sources collected, pushing search results Dec 27 21:18:11 volumio volumio[1089]: info: All search sources collected, pushing search results Dec 27 21:18:14 volumio volumio[1089]: Searching all installed plugins Dec 27 21:18:14 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 21:18:14 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: , search Dec 27 21:18:14 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 21:18:14 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 27 21:18:14 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 27 21:18:14 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 27 21:18:14 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 27 21:18:14 volumio volumio[1089]: info: An error occurred while querying SHOUTCAST Dec 27 21:18:16 volumio volumio[1089]: info: All search sources collected, pushing search results Dec 27 21:18:16 volumio volumio[1089]: Searching all installed plugins Dec 27 21:18:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 21:18:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: , search Dec 27 21:18:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 21:18:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 27 21:18:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 27 21:18:16 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 27 21:18:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 27 21:18:16 volumio volumio[1089]: info: An error occurred while querying SHOUTCAST Dec 27 21:18:18 volumio volumio[1089]: info: All search sources collected, pushing search results Dec 27 21:18:20 volumio volumio[1089]: Searching all installed plugins Dec 27 21:18:20 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 21:18:20 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: , search Dec 27 21:18:20 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 21:18:20 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 27 21:18:20 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 27 21:18:20 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 27 21:18:20 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 27 21:18:21 volumio volumio[1089]: info: All search sources collected, pushing search results Dec 27 21:18:22 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 21:18:22 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 21:18:22 volumio volumio[1089]: info: Discovery: Getting this device information Dec 27 21:18:22 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Dec 27 21:18:22 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:18:22 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 21:18:22 volumio volumio[1089]: verbose: New Socket.io Connection to 192.168.2.2:3000 from 192.168.2.3 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 27 21:18:22 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 21:18:22 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 21:18:23 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 21:18:23 volumio volumio[1089]: info: CURURI: albums://Michael%20Hedges/Aerial%20Boundaries Dec 27 21:18:23 volumio volumio[1089]: info: Preload queue cleared Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 02 - Bensusan.flac Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 03 - Rickover's Dream.flac Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 04 - Ragamuffin.flac Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 05 - After The Gold Rush.flac Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 06 - Hot Type.flac Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 07 - Spare Change.flac Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 08 - Ménage á Trois.flac Dec 27 21:18:23 volumio volumio[1089]: info: Preloading song: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 09 - The Magic Farmer.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2001%20-%20Aerial%20Boundaries.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 02 - Bensusan.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2002%20-%20Bensusan.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 02 - Bensusan.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 03 - Rickover's Dream.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2003%20-%20Rickover's%20Dream.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 03 - Rickover's Dream.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 04 - Ragamuffin.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2004%20-%20Ragamuffin.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 04 - Ragamuffin.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 05 - After The Gold Rush.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2005%20-%20After%20The%20Gold%20Rush.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 05 - After The Gold Rush.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 06 - Hot Type.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2006%20-%20Hot%20Type.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 06 - Hot Type.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 07 - Spare Change.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2007%20-%20Spare%20Change.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 07 - Spare Change.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 08 - Ménage á Trois.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2008%20-%20M%C3%A9nage%20%C3%A1%20Trois.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 08 - Ménage á Trois.flac Dec 27 21:18:23 volumio volumio[1089]: info: Exploding uri music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 09 - The Magic Farmer.flac in service mpd Dec 27 21:18:23 volumio volumio[1089]: info: ALBUMART /albumart?cacheid=680&web=Michael%20Hedges/Aerial%20Boundaries/large&path=%2Fmnt%2FUSB%2FMUSIC%2FMichael%20Hedges%20-%20Aerial%20Boundaries%2FMichael%20Hedges%20-%20Aerial%20Boundaries%20-%2009%20-%20The%20Magic%20Farmer.flac&metadata=false Dec 27 21:18:23 volumio volumio[1089]: info: URI /mnt/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 09 - The Magic Farmer.flac Dec 27 21:18:27 volumio volumio[1089]: info: Preload queue cleared Dec 27 21:18:27 volumio volumio[1089]: info: CoreCommandRouter::volumioAddQueueItems Dec 27 21:18:27 volumio volumio[1089]: info: CoreStateMachine::addQueueItems Dec 27 21:18:27 volumio volumio[1089]: info: CorePlayQueue::addQueueItems Dec 27 21:18:27 volumio volumio[1089]: info: Preload queue cleared Dec 27 21:18:27 volumio volumio[1089]: info: Adding Item to queue: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac Dec 27 21:18:27 volumio volumio[1089]: info: Using cached record of: music-library/USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac Dec 27 21:18:27 volumio volumio[1089]: info: CoreCommandRouter::volumioPushQueue Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::saveQueue Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::updateTrackBlock Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrackBlock Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPlay Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::play index 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::stop Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::stPlaybackTimer Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::updateTrackBlock Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrackBlock Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::serviceStop Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 636 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::serviceStop Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::stop Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand stop took 157 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::play index undefined Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::startPlaybackTimer Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand status took 302 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand stop took 299 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand status took 298 milliseconds Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:18:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand clear took 36 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac" Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tell Her about it","artist":"Billy Joel","album":"An Innocent Man","uri":"USB/MUSIC/Billy Joel - The Complete Albums Collection/CD10 An Innocent Man/05 - Billy Joel - Tell Her about it.flac","trackType":"flac"} Dec 27 21:18:28 volumio volumio[1089]: verbose: CURRENT POSITION 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState stateService stop Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus stop Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: No code Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 27 21:18:28 volumio volumio[1089]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 362ms Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 356ms Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 355ms Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:18:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:18:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:18:28 volumio volumio[1089]: error: updateQueue error: null Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:18:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 27ms Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand add "USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac" took 22 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 6ms Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand play Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:18:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:18:28 volumio volumio[1089]: info: touch_display: Setting screensaver timeout to 600 seconds. Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 23ms Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 22ms Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand play took 20 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 19ms Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Dec 27 21:18:28 volumio volumio[1089]: info: Ignoring MPD Status Update Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand status took 8 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand status took 6 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 7ms Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand status took 4 milliseconds Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:18:28 volumio volumio[1089]: info: Dec 27 21:18:28 volumio volumio[1089]: ---------------------------- MPD announces state update: player Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::getState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand status took 4 milliseconds Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aerial Boundaries","artist":"Michael Hedges","album":"Aerial Boundaries","uri":"USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac","trackType":"flac"} Dec 27 21:18:28 volumio volumio[1089]: verbose: CURRENT POSITION 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus stop Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aerial Boundaries","artist":"Michael Hedges","album":"Aerial Boundaries","uri":"USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac","trackType":"flac"} Dec 27 21:18:28 volumio volumio[1089]: verbose: CURRENT POSITION 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:18:28 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"167 Kbps","isStreaming":false,"title":"Aerial Boundaries","artist":"Michael Hedges","album":"Aerial Boundaries","uri":"USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac","trackType":"flac"} Dec 27 21:18:28 volumio volumio[1089]: verbose: CURRENT POSITION 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:18:28 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 41ms Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 45ms Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 43ms Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand status took 37 milliseconds Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseState Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"384 Kbps","isStreaming":false,"title":"Aerial Boundaries","artist":"Michael Hedges","album":"Aerial Boundaries","uri":"USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac","trackType":"flac"} Dec 27 21:18:28 volumio volumio[1089]: verbose: CURRENT POSITION 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:18:28 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 56ms Dec 27 21:18:28 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 73 milliseconds Dec 27 21:18:28 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:18:28 volumio volumio[1089]: info: ControllerMpd::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"675 Kbps","isStreaming":false,"title":"Aerial Boundaries","artist":"Michael Hedges","album":"Aerial Boundaries","uri":"USB/MUSIC/Michael Hedges - Aerial Boundaries/Michael Hedges - Aerial Boundaries - 01 - Aerial Boundaries.flac","trackType":"flac"} Dec 27 21:18:28 volumio volumio[1089]: verbose: CURRENT POSITION 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:18:28 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:28 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:28 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:28 volumio volumio[1089]: info: ------------------------------ 135ms Dec 27 21:18:28 volumio volumio[1089]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 27 21:18:36 volumio volumio[1089]: info: VolumeController::SetAlsaVolume50 Dec 27 21:18:36 volumio volumio[1089]: info: CoreStateMachine::pushState Dec 27 21:18:36 volumio volumio[1089]: info: CorePlayQueue::getTrack 6659 Dec 27 21:18:36 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:18:36 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Dec 27 21:18:43 volumio volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 21:18:43 volumio volumio[1089]: TypeError: Cannot read property 'slice' of null Dec 27 21:18:43 volumio volumio[1089]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 27 21:18:43 volumio volumio[1089]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 27 21:18:43 volumio volumio[1089]: at Object.onceWrapper (events.js:422:26) Dec 27 21:18:43 volumio volumio[1089]: at ClientRequest.emit (events.js:315:20) Dec 27 21:18:43 volumio volumio[1089]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Dec 27 21:18:43 volumio volumio[1089]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Dec 27 21:18:43 volumio volumio[1089]: at TLSSocket.socketOnData (_http_client.js:509:22) Dec 27 21:18:43 volumio volumio[1089]: at TLSSocket.emit (events.js:315:20) Dec 27 21:18:43 volumio volumio[1089]: at addChunk (internal/streams/readable.js:309:12) Dec 27 21:18:43 volumio volumio[1089]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 27 21:18:43 volumio volumio[1089]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Dec 27 21:18:43 volumio volumio[1089]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 27 21:18:43 volumio volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 21:18:44 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 21:17 Dec 27 21:18:44 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"