-- Logs begin at Thu 2019-02-14 02:11:58 PST, end at Tue 2026-08-25 20:36:17 PDT. -- Aug 25 20:35:01 living volumio[5343]: info: MyVolumio token set successfully Aug 25 20:35:01 living volumio[5343]: info: MYVOLUMIO: Adding device Aug 25 20:35:01 living volumio[5343]: info: MYVOLUMIO: Evaluating Server Aug 25 20:35:03 living volumio[5343]: info: MyVolumio status changed Aug 25 20:35:03 living volumio[5343]: info: Streaming services startup Aug 25 20:35:03 living volumio[5343]: info: Starting Streaming Daemon Aug 25 20:35:03 living volumio[5343]: info: Removing browser output: myVolumio user plan is not superstar Aug 25 20:35:03 living volumio[5343]: info: Removing audio output: Aug 25 20:35:03 living volumio[5343]: info: Stoppping Tunnel 1 Aug 25 20:35:03 living sudo[5638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 25 20:35:03 living sudo[5638]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 20:35:03 living sudo[5638]: pam_unix(sudo:session): session closed for user root Aug 25 20:35:03 living sudo[5641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 25 20:35:03 living sudo[5641]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 20:35:03 living sudo[5641]: pam_unix(sudo:session): session closed for user root Aug 25 20:35:03 living volumio[5343]: error: Cannot start Volumio Streaming Daemon Aug 25 20:35:03 living volumio[5343]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 25 20:35:03 living volumio[5343]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 25 20:35:03 living volumio[5343]: info: Setting Geolocation for MyVolumio to us2 Aug 25 20:35:03 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 20:35:03 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 20:35:03 living volumio[5343]: info: Remote SSH Stopped Aug 25 20:35:04 living volumio[5343]: info: Updating MyVolumio device info Aug 25 20:35:04 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 20:35:05 living volumio[5343]: info: Successfully Added MyVolumio device Aug 25 20:35:07 living volumio[5343]: info: Successfully Updated MyVolumio device Aug 25 20:35:08 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:08 living volumio[5343]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 20:35:08 living volumio[5343]: info: CoreStateMachine::ClearQueue Aug 25 20:35:08 living volumio[5343]: info: CoreStateMachine::stop Aug 25 20:35:08 living volumio[5343]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 20:35:08 living volumio[5343]: info: CorePlayQueue::clearPlayQueue Aug 25 20:35:08 living volumio[5343]: info: CorePlayQueue::saveQueue Aug 25 20:35:08 living volumio[5343]: info: CoreCommandRouter::volumioPushQueue Aug 25 20:35:08 living volumio[5343]: info: CoreStateMachine::addQueueItems Aug 25 20:35:08 living volumio[5343]: info: CorePlayQueue::addQueueItems Aug 25 20:35:08 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:08 living volumio[5343]: info: Adding Item to queue: artists://Joe%20Walsh Aug 25 20:35:08 living volumio[5343]: info: Exploding uri artists://Joe%20Walsh in service mpd Aug 25 20:35:10 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 20:35:11 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:11 living volumio[5343]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 20:35:11 living volumio[5343]: info: CoreStateMachine::ClearQueue Aug 25 20:35:11 living volumio[5343]: info: CoreStateMachine::stop Aug 25 20:35:11 living volumio[5343]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 20:35:11 living volumio[5343]: info: CorePlayQueue::clearPlayQueue Aug 25 20:35:11 living volumio[5343]: info: CorePlayQueue::saveQueue Aug 25 20:35:11 living volumio[5343]: info: CoreCommandRouter::volumioPushQueue Aug 25 20:35:11 living volumio[5343]: info: CoreStateMachine::addQueueItems Aug 25 20:35:11 living volumio[5343]: info: CorePlayQueue::addQueueItems Aug 25 20:35:11 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:11 living volumio[5343]: info: Adding Item to queue: artists://Joe%20Walsh Aug 25 20:35:11 living volumio[5343]: info: Exploding uri artists://Joe%20Walsh in service mpd Aug 25 20:35:12 living volumio[5343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 25 20:35:13 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 20:35:13 living volumio[5343]: info: CURURI: artists://Joe%20Walsh Aug 25 20:35:13 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:13 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 20:35:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 20:35:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 20:35:15 living volumio-remote-updater[644]: No test mode Aug 25 20:35:15 living volumio-remote-updater[644]: No alpha test mode Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 20:35:15 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 20:35:16 living volumio[5343]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 25 20:35:16 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 25 20:35:16 living volumio[5343]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 25 20:35:16 living volumio[5343]: info: Completed starting MyVolumio Plugin Aug 25 20:35:16 living volumio[5343]: [Metrics] CommandRouter: 35s 960.59ms Aug 25 20:35:16 living volumio[5343]: info: CoreCommandRouter::volumiosetStartupVolume Aug 25 20:35:16 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 20:35:16 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 20:35:16 living volumio[5343]: info: CoreCommandRouter::Close All Modals sent Aug 25 20:35:16 living volumio[5343]: info: CoreCommandRouter::Close All Modals sent Aug 25 20:35:16 living kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Aug 25 20:35:18 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 25 20:35:18 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 20:35:18 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 25 20:35:20 living volumio[5343]: Cannot compose Albumart path Aug 25 20:35:20 living volumio[5343]: Cannot compose Albumart path Aug 25 20:35:20 living volumio[5343]: Cannot compose Albumart path Aug 25 20:35:20 living volumio[5343]: Cannot compose Albumart path Aug 25 20:35:20 living volumio[5343]: Cannot compose Albumart path Aug 25 20:35:20 living volumio[5343]: Cannot compose Albumart path Aug 25 20:35:20 living volumio[5343]: Cannot compose Albumart path Aug 25 20:35:20 living volumio[5343]: Cannot compose Albumart path Aug 25 20:35:20 living volumio[5343]: info: CoreCommandRouter::volumioPushQueue Aug 25 20:35:20 living volumio[5343]: info: CorePlayQueue::saveQueue Aug 25 20:35:20 living volumio[5343]: info: CoreCommandRouter::volumioPushQueue Aug 25 20:35:20 living volumio[5343]: info: CorePlayQueue::saveQueue Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::updateTrackBlock Aug 25 20:35:20 living volumio[5343]: info: CorePlayQueue::getTrackBlock Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::updateTrackBlock Aug 25 20:35:20 living volumio[5343]: info: CorePlayQueue::getTrackBlock Aug 25 20:35:20 living volumio[5343]: info: CoreCommandRouter::volumioPlay Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::play index 0 Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 20:35:20 living volumio[5343]: info: CoreCommandRouter::volumioPlay Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::play index 0 Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::stop Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::stop Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::play index undefined Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::play index undefined Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 20:35:20 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:20 living volumio[5343]: info: CoreStateMachine::startPlaybackTimer Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::clearAddPlayTracks USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::startPlaybackTimer Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::clearAddPlayTracks USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand stop took 3 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand stop took 3 milliseconds Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces system playlist update Aug 25 20:35:21 living volumio[5343]: info: Ignoring MPD Status Update Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces system playlist update Aug 25 20:35:21 living volumio[5343]: info: Ignoring MPD Status Update Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand clear took 6 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand clear took 7 milliseconds Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand add "USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand add "USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces system playlist update Aug 25 20:35:21 living volumio[5343]: info: Ignoring MPD Status Update Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces system playlist update Aug 25 20:35:21 living volumio[5343]: info: Ignoring MPD Status Update Aug 25 20:35:21 living volumio[5343]: error: updateQueue error: null Aug 25 20:35:21 living volumio[5343]: error: updateQueue error: null Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces system playlist update Aug 25 20:35:21 living volumio[5343]: info: Ignoring MPD Status Update Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces system playlist update Aug 25 20:35:21 living volumio[5343]: info: Ignoring MPD Status Update Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 8ms Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 7ms Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand add "USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" took 6 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand add "USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" took 5 milliseconds Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 5ms Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 5ms Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand play Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand play Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces system playlist update Aug 25 20:35:21 living volumio[5343]: info: Ignoring MPD Status Update Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces system playlist update Aug 25 20:35:21 living volumio[5343]: info: Ignoring MPD Status Update Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 6ms Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 6ms Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand play took 4 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand play took 4 milliseconds Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 3ms Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 2ms Aug 25 20:35:21 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:21 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/01.-Funk #49.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/02.-Tend My Garden.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/03.-The Bomber.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/04.-Walk Away.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/05.-Midnight Man.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/06.-Mothers Says.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/07.-Turn To Stone.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/08.-Meadows.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/09.-Rocky Mountain Way.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/10.-Help Me Thru The Night.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/11.-Life's Been Good.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/12.-All Night Long.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/13.-The Confessor.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/14.-A Life Of Illusion.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/15.-Ordinary Average Guy.flac Aug 25 20:35:21 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:21 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/01.-Funk #49.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/02.-Tend My Garden.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/03.-The Bomber.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/04.-Walk Away.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/05.-Midnight Man.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/06.-Mothers Says.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/07.-Turn To Stone.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/08.-Meadows.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/09.-Rocky Mountain Way.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/10.-Help Me Thru The Night.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/11.-Life's Been Good.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/12.-All Night Long.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/13.-The Confessor.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/14.-A Life Of Illusion.flac Aug 25 20:35:21 living volumio[5343]: info: Preloading song: music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/15.-Ordinary Average Guy.flac Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/01.-Funk #49.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces state update: player Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=James%20Gang/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F01.-Funk%20%2349.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/01.-Funk #49.flac Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces state update: player Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::getState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand status Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::getState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand status Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces state update: player Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::getState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand status Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces state update: player Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::getState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand status Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/02.-Tend My Garden.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/03.-The Bomber.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/04.-Walk Away.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/05.-Midnight Man.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/06.-Mothers Says.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/07.-Turn To Stone.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=James%20Gang/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F02.-Tend%20My%20Garden.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/02.-Tend My Garden.flac Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=James%20Gang/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F03.-The%20Bomber.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/03.-The Bomber.flac Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=James%20Gang/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F04.-Walk%20Away.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/04.-Walk Away.flac Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=James%20Gang/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F05.-Midnight%20Man.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/05.-Midnight Man.flac Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F06.-Mothers%20Says.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/06.-Mothers Says.flac Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F07.-Turn%20To%20Stone.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/07.-Turn To Stone.flac Aug 25 20:35:21 living volumio[5343]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand status took 35 milliseconds Aug 25 20:35:21 living volumio[5343]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand status took 35 milliseconds Aug 25 20:35:21 living volumio[5343]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand status took 34 milliseconds Aug 25 20:35:21 living volumio[5343]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand status took 34 milliseconds Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces state update: player Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::getState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand status Aug 25 20:35:21 living volumio[5343]: info: Aug 25 20:35:21 living volumio[5343]: ---------------------------- MPD announces state update: player Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::getState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand status Aug 25 20:35:21 living volumio[5343]: info: No valid Plugin REST Endpoint Aug 25 20:35:21 living volumio[5343]: info: No valid Plugin REST Endpoint Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/08.-Meadows.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/09.-Rocky Mountain Way.flac in service mpd Aug 25 20:35:21 living volumio[5343]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joe%20Walsh/5c8a3d90-8029-42a9-aa95-95633c452db0.jpg' Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F08.-Meadows.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/08.-Meadows.flac Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F09.-Rocky%20Mountain%20Way.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/09.-Rocky Mountain Way.flac Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand clearerror took 68 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand clearerror took 67 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand clearerror took 66 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand clearerror took 65 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand playlistinfo took 57 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand playlistinfo took 56 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand playlistinfo took 56 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand playlistinfo took 56 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand status took 53 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand status took 52 milliseconds Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseTrackInfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseTrackInfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseTrackInfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseTrackInfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseState Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::pushState Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::servicePushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Daughter Of Zion","artist":"Joe Walsh","album":"Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)","uri":"USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac","trackType":"flac"} Aug 25 20:35:21 living volumio[5343]: verbose: CURRENT POSITION 0 Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState stateService pause Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState currentStatus stop Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::pushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::volumioPushState Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::pushState Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::servicePushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Daughter Of Zion","artist":"Joe Walsh","album":"Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)","uri":"USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac","trackType":"flac"} Aug 25 20:35:21 living volumio[5343]: verbose: CURRENT POSITION 0 Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState stateService pause Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState currentStatus stop Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::pushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::volumioPushState Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::pushState Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::servicePushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Daughter Of Zion","artist":"Joe Walsh","album":"Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)","uri":"USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac","trackType":"flac"} Aug 25 20:35:21 living volumio[5343]: verbose: CURRENT POSITION 0 Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState stateService pause Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState currentStatus stop Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::pushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::volumioPushState Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::pushState Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::servicePushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Daughter Of Zion","artist":"Joe Walsh","album":"Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)","uri":"USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac","trackType":"flac"} Aug 25 20:35:21 living volumio[5343]: verbose: CURRENT POSITION 0 Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState stateService pause Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState currentStatus stop Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::pushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::volumioPushState Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.530-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" state=STATUS_STOPPED positionMs=417 volume=56 Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.531-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" id="music-library/USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" title="Daughter Of Zion" Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.532-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" state=STATUS_STOPPED positionMs=417 volume=56 Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.532-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" id="music-library/USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" title="Daughter Of Zion" Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.534-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" state=STATUS_STOPPED positionMs=417 volume=56 Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.534-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" id="music-library/USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" title="Daughter Of Zion" Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.535-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" state=STATUS_STOPPED positionMs=417 volume=56 Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.535-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" id="music-library/USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" title="Daughter Of Zion" Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 146ms Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 144ms Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 141ms Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 140ms Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/10.-Help Me Thru The Night.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F10.-Help%20Me%20Thru%20The%20Night.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/10.-Help Me Thru The Night.flac Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand playlistinfo took 54 milliseconds Aug 25 20:35:21 living volumio[5343]: info: sendMpdCommand playlistinfo took 54 milliseconds Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseTrackInfo Aug 25 20:35:21 living volumio[5343]: verbose: ControllerMpd::parseTrackInfo Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::pushState Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::servicePushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Daughter Of Zion","artist":"Joe Walsh","album":"Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)","uri":"USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac","trackType":"flac"} Aug 25 20:35:21 living volumio[5343]: verbose: CURRENT POSITION 0 Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState stateService pause Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState currentStatus stop Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::pushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::volumioPushState Aug 25 20:35:21 living volumio[5343]: info: ControllerMpd::pushState Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::servicePushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Daughter Of Zion","artist":"Joe Walsh","album":"Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)","uri":"USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac","trackType":"flac"} Aug 25 20:35:21 living volumio[5343]: verbose: CURRENT POSITION 0 Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState stateService pause Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::syncState currentStatus stop Aug 25 20:35:21 living volumio[5343]: info: CoreStateMachine::pushState Aug 25 20:35:21 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:21 living volumio[5343]: info: CoreCommandRouter::volumioPushState Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.575-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" state=STATUS_STOPPED positionMs=417 volume=56 Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.575-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" id="music-library/USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" title="Daughter Of Zion" Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.577-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" state=STATUS_STOPPED positionMs=417 volume=56 Aug 25 20:35:21 living volumio5-onboarding[1320]: time=2026-08-25T20:35:21.577-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.31:50494,00:00:00:00:00:00%04 @ 0x2d441e0" id="music-library/USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" title="Daughter Of Zion" Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 127ms Aug 25 20:35:21 living volumio[5343]: info: ------------------------------ 127ms Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/11.-Life's Been Good.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F11.-Life's%20Been%20Good.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/11.-Life's Been Good.flac Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/12.-All Night Long.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F12.-All%20Night%20Long.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/12.-All Night Long.flac Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/13.-The Confessor.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F13.-The%20Confessor.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/13.-The Confessor.flac Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/14.-A Life Of Illusion.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F14.-A%20Life%20Of%20Illusion.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/14.-A Life Of Illusion.flac Aug 25 20:35:21 living volumio[5343]: info: Exploding uri music-library/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/15.-Ordinary Average Guy.flac in service mpd Aug 25 20:35:21 living volumio[5343]: info: ALBUMART /albumart?cacheid=1007&web=Joe%20Walsh/Joe%20Walsh's%20Greatest%20Hits%3A%20Little%20Did%20He%20Know%E2%80%A6/extralarge&path=%2Fmnt%2FUSB%2FFLACs%2FFLACs%2FRock%2FJoe%20Walsh%2FJoe%20Walsh%20%20-%20Greatest%20Hits%20Little%20Did%20He%20Know%20(1997)%20%5BFLAC%5D%20vtwin88cube%2F15.-Ordinary%20Average%20Guy.flac&metadata=false Aug 25 20:35:21 living volumio[5343]: info: URI /mnt/USB/FLACs/FLACs/Rock/Joe Walsh/Joe Walsh - Greatest Hits Little Did He Know (1997) [FLAC] vtwin88cube/15.-Ordinary Average Guy.flac Aug 25 20:35:23 living volumio[5343]: info: BOOT COMPLETED Aug 25 20:35:31 living dbus-daemon[648]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.15" (uid=0 pid=1320 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=821 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Aug 25 20:35:31 living volumio5-onboarding[1320]: time=2026-08-25T20:35:31.497-07:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.86.31:50494 error="read tcp 192.168.86.24:7331->192.168.86.31:50494: read: connection reset by peer" Aug 25 20:35:31 living volumio5-onboarding[1320]: time=2026-08-25T20:35:31.497-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.86.31:50494 Aug 25 20:35:31 living volumio5-onboarding[1320]: time=2026-08-25T20:35:31.497-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.86.31:50494 Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.622-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.31:50711 Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.642-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.86.31:50711 Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.642-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.86.31:50711 Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.657-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.31:50713 Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.702-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.704-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.86.31:50713 @ 0x31a27e0" latency=13.277112ms timeout=20s Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.704-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" Aug 25 20:35:33 living volumio[5343]: info: Received Get System Info Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:35:33 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:33 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.713-07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" name=Living Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.714-07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" language=en Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.719-07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" timezone=America/Los_Angeles Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.723-07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.725-07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" available=true connected=true macAddress=d8:3a:dd:2d:6e:0c ip4Address=192.168.86.24/24 ip6Address= ssid="Team MG" Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.726-07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" setupComplete=true Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.737-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=34.109277ms Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 20:35:33 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 20:35:33 living volumio[5343]: amixer -c 2 info | grep "HiFiBerry Digi+ Pro HiFi wm8804-spdif-0" Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.784-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://securetoken.googleapis.com duration=81.443111ms Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.788-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=85.121731ms Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.789-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=85.927629ms Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.795-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://google.com duration=92.597786ms Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.799-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://functions.volumio.cloud duration=95.539249ms Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.799-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://functions.volumio.cloud duration=95.679618ms Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.799-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=95.928207ms Aug 25 20:35:33 living volumio[5343]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.828-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://www.googleapis.com duration=125.472226ms Aug 25 20:35:33 living volumio[5343]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 25 20:35:33 living volumio[5343]: Card hw:1 'Headphones'/'bcm2835 Headphones' Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.892-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=http://pushupdates.volumio.org duration=188.558159ms Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.926-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=http://plugins.volumio.org duration=223.617647ms Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.950-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=https://database.volumio.cloud duration=247.173111ms Aug 25 20:35:33 living volumio[5343]: amixer -c 2 info | grep "snd_rpi_hifiberry_digi" Aug 25 20:35:33 living volumio[5343]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_digi' Aug 25 20:35:33 living volumio5-onboarding[1320]: time=2026-08-25T20:35:33.993-07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" selectedOutputId=2 Aug 25 20:35:34 living volumio[5343]: info: Received Get System Info Aug 25 20:35:34 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:35:34 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:35:34 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:35:34 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:34 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:34 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.012-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50713 @ 0x31a27e0" latency=12.842223ms timeout=10s endpoint=http://cddb.volumio.org duration=309.194872ms Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.013-07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" currentVersion=3.912 latestVersion=3.912 Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.013-07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" status=UPDATE_STATUS_NONE progress=0 Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.014-07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" userId= Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.014-07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" providers=3 Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.014-07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" plugins=54 Aug 25 20:35:34 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:34 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.019-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" state=STATUS_STOPPED positionMs=12993 volume=56 Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.020-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.31:50713 @ 0x31a27e0" id="music-library/USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" title="Daughter Of Zion" Aug 25 20:35:34 living volumio5-onboarding[1320]: time=2026-08-25T20:35:34.134-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.31:50709 Aug 25 20:35:43 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 20:35:43 living volumio[5343]: info: CURURI: artists://Joe%20Walsh Aug 25 20:35:43 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:43 living volumio[5343]: info: Preload queue cleared Aug 25 20:35:43 living volumio[5343]: info: No valid Plugin REST Endpoint Aug 25 20:35:49 living volumio5-onboarding[1320]: time=2026-08-25T20:35:49.628-07:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.86.31:50709 error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 25 20:35:49 living volumio5-onboarding[1320]: time=2026-08-25T20:35:49.628-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.86.31:50709 Aug 25 20:35:49 living volumio5-onboarding[1320]: time=2026-08-25T20:35:49.628-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.86.31:50709 Aug 25 20:35:49 living volumio5-onboarding[1320]: time=2026-08-25T20:35:49.628-07:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.86.31:50713 error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 25 20:35:49 living volumio5-onboarding[1320]: time=2026-08-25T20:35:49.628-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.86.31:50713 Aug 25 20:35:49 living volumio5-onboarding[1320]: time=2026-08-25T20:35:49.628-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.86.31:50713 Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.142-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.31:50718 Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.207-07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.86.31:50718 @ 0x30820c0" latency=15.149005ms platform=PLATFORM_IOS version=6.260807.0 Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.207-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.86.31:50718 @ 0x30820c0" latency=15.441116ms timeout=20s Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.207-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" Aug 25 20:35:51 living volumio[5343]: info: Received Get System Info Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:35:51 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:51 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.211-07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" name=Living Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.212-07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" language=en Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.214-07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" timezone=America/Los_Angeles Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.215-07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.220-07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" available=true connected=true macAddress=d8:3a:dd:2d:6e:0c ip4Address=192.168.86.24/24 ip6Address= ssid="Team MG" Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.220-07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" setupComplete=true Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 20:35:51 living volumio[5343]: amixer -c 2 info | grep "HiFiBerry Digi+ Pro HiFi wm8804-spdif-0" Aug 25 20:35:51 living volumio[5343]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 25 20:35:51 living volumio[5343]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 25 20:35:51 living volumio[5343]: Card hw:1 'Headphones'/'bcm2835 Headphones' Aug 25 20:35:51 living volumio[5343]: amixer -c 2 info | grep "snd_rpi_hifiberry_digi" Aug 25 20:35:51 living volumio[5343]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_digi' Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.478-07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" selectedOutputId=2 Aug 25 20:35:51 living volumio[5343]: info: Received Get System Info Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:35:51 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:51 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.504-07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" currentVersion=3.912 latestVersion=3.912 Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.504-07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.86.31:50718 @ 0x30820c0" status=UPDATE_STATUS_NONE progress=0 Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.504-07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" userId= Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.504-07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" providers=3 Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.505-07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" plugins=54 Aug 25 20:35:51 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:51 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.509-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" state=STATUS_STOPPED positionMs=30389 volume=56 Aug 25 20:35:51 living volumio5-onboarding[1320]: time=2026-08-25T20:35:51.509-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.31:50718 @ 0x30820c0" id="music-library/USB/FLACs/FLACs/Blues/Various Artists/VA - Tell Everybody! (21st Century Juke Joint Blues From Easy Eye Sound)/10. Daughter Of Zion.flac" title="Daughter Of Zion" Aug 25 20:35:52 living volumio5-onboarding[1320]: time=2026-08-25T20:35:52.764-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.86.31:50718 @ 0x30820c0" latency=116.643483ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.120-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:35:53 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:53 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.138-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=18.323091ms Aug 25 20:35:53 living volumio[5343]: verbose: New Socket.io Connection to 192.168.86.24:3000 from 192.168.86.31 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.201-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://securetoken.googleapis.com duration=80.233995ms Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.206-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=86.080513ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.208-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=88.096753ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.209-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://functions.volumio.cloud duration=88.117179ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.214-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=93.049031ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.221-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://google.com duration=100.814216ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.224-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://database.volumio.cloud duration=103.006937ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.231-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://functions.volumio.cloud duration=110.811382ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.243-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=https://www.googleapis.com duration=122.623547ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.308-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=http://pushupdates.volumio.org duration=186.812468ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.357-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=http://plugins.volumio.org duration=236.168447ms Aug 25 20:35:53 living volumio5-onboarding[1320]: time=2026-08-25T20:35:53.423-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.31:50718 @ 0x30820c0" latency=37.371876ms timeout=10s endpoint=http://cddb.volumio.org duration=302.133128ms Aug 25 20:35:53 living sudo[5803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 20:35:53 living sudo[5803]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 20:35:53 living sudo[5803]: pam_unix(sudo:session): session closed for user root Aug 25 20:35:53 living sudo[5806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 20:35:53 living sudo[5806]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 20:35:53 living sudo[5806]: pam_unix(sudo:session): session closed for user root Aug 25 20:35:53 living volumio[5343]: verbose: New Socket.io Connection to 192.168.86.24 from 192.168.86.31 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 20:35:53 living sudo[5809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 20:35:53 living sudo[5809]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 20:35:53 living sudo[5809]: pam_unix(sudo:session): session closed for user root Aug 25 20:35:53 living sudo[5812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 20:35:53 living sudo[5812]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 20:35:53 living sudo[5812]: pam_unix(sudo:session): session closed for user root Aug 25 20:35:53 living volumio[5343]: verbose: New Socket.io Connection to 192.168.86.24 from 192.168.86.31 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:53 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 20:35:53 living volumio[5343]: info: Received Get System Info Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:35:53 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:53 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:53 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:53 living volumio[5343]: info: Listing playlists Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 20:35:53 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 20:35:54 living volumio5-onboarding[1320]: time=2026-08-25T20:35:54.143-07:00 level=INFO msg="new address was allocated" component=ble/conn old=5 new=6 Aug 25 20:35:54 living dbus-daemon[648]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.15" (uid=0 pid=1320 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=821 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Aug 25 20:35:55 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 25 20:35:55 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 20:35:55 living volumio[5343]: info: Received Get System Info Aug 25 20:35:55 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:35:55 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:35:55 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:35:55 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:55 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:55 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:35:55 living volumio[5343]: info: CoreCommandRouter::volumioGetQueue Aug 25 20:35:55 living volumio[5343]: info: CoreStateMachine::getQueue Aug 25 20:35:55 living volumio[5343]: info: CorePlayQueue::getQueue Aug 25 20:35:56 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 20:35:56 living volumio[5343]: info: Received Get System Info Aug 25 20:35:56 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:35:56 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:35:56 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:35:56 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:35:56 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:35:56 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:35:59 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:35:59 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:01 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:36:01 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:02 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:02 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:02 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:02 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:02 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:02 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:02 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:02 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:36:02 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:03 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:36:03 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:03 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 25 20:36:04 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:36:04 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:05 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:36:05 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:05 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:36:05 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:06 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:36:06 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:08 living volumio[5343]: Searching plugin music_service/mpd Aug 25 20:36:08 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: Cannot compose Albumart path Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:10 living volumio[5343]: info: All search sources collected, pushing search results Aug 25 20:36:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 20:36:14 living volumio[5343]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 25 20:36:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 25 20:36:14 living volumio[5343]: info: Received Get System Version Aug 25 20:36:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 20:36:14 living volumio[5343]: info: Received Get System Info Aug 25 20:36:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:36:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:36:14 living volumio[5343]: info: Discovery: Getting this device information Aug 25 20:36:14 living volumio[5343]: info: CoreCommandRouter::volumioGetState Aug 25 20:36:14 living volumio[5343]: info: CorePlayQueue::getTrack 0 Aug 25 20:36:14 living volumio[5343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:36:17 living volumio[5343]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 20:36:17 living volumio[5343]: Error: connect ECONNREFUSED 158.69.209.125:443 Aug 25 20:36:17 living volumio[5343]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 25 20:36:17 living volumio[5343]: errno: -111, Aug 25 20:36:17 living volumio[5343]: code: 'ECONNREFUSED', Aug 25 20:36:17 living volumio[5343]: syscall: 'connect', Aug 25 20:36:17 living volumio[5343]: address: '158.69.209.125', Aug 25 20:36:17 living volumio[5343]: port: 443 Aug 25 20:36:17 living volumio[5343]: } Aug 25 20:36:17 living volumio[5343]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 20:36:17 living sudo[5890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-25 20:35 Aug 25 20:36:17 living sudo[5890]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"