-- Logs begin at Wed 2025-06-18 10:17:41 -05, end at Thu 2025-06-19 08:13:47 -05. -- Jun 19 08:12:05 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume77 Jun 19 08:12:05 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:12:05 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:12:05 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:12:05 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:05 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:05 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:05 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:05 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Jun 19 08:12:05 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 53 Jun 19 08:12:05 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 77 Jun 19 08:12:05 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:12:05 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 77 Jun 19 08:12:07 volumio306z volumio[825]: SPOTIFY: SETTING SPOTIFY VOLUME 77 Jun 19 08:12:07 volumio306z volumio[825]: info: Sending Spotify command with payload to local API: /player/volume Jun 19 08:12:07 volumio306z go-librespot[1055]: time="2025-06-19T08:12:07-05:00" level=debug msg="update volume to 50461/65535" Jun 19 08:12:07 volumio306z go-librespot[1055]: time="2025-06-19T08:12:07-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 19 08:12:07 volumio306z go-librespot[1055]: time="2025-06-19T08:12:07-05:00" level=trace msg="emitting websocket event: volume" Jun 19 08:12:07 volumio306z volumio[825]: SPOTIFY: received: {"type":"volume","data":{"value":77,"max":100}} Jun 19 08:12:07 volumio306z volumio[825]: SPOTIFY: RECEIVED SPOTIFY VOLUME 77 Jun 19 08:12:09 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume46 Jun 19 08:12:09 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:12:09 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:12:09 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:12:10 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:10 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:10 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:10 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 19 08:12:10 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 77 Jun 19 08:12:10 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 46 Jun 19 08:12:10 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:12:10 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 46 Jun 19 08:12:11 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume60 Jun 19 08:12:11 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:12:11 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:12:11 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:12:11 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:11 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:11 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:11 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:11 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jun 19 08:12:11 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 46 Jun 19 08:12:11 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 60 Jun 19 08:12:11 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:12:11 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 60 Jun 19 08:12:12 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume51 Jun 19 08:12:12 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:12:12 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:12:12 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:12:12 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:12 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:12 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:12 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:12 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:12:12 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 60 Jun 19 08:12:12 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 51 Jun 19 08:12:12 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:12:12 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 51 Jun 19 08:12:14 volumio306z volumio[825]: SPOTIFY: SETTING SPOTIFY VOLUME 51 Jun 19 08:12:14 volumio306z volumio[825]: info: Sending Spotify command with payload to local API: /player/volume Jun 19 08:12:14 volumio306z go-librespot[1055]: time="2025-06-19T08:12:14-05:00" level=debug msg="update volume to 33422/65535" Jun 19 08:12:14 volumio306z go-librespot[1055]: time="2025-06-19T08:12:14-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 19 08:12:14 volumio306z go-librespot[1055]: time="2025-06-19T08:12:14-05:00" level=trace msg="emitting websocket event: volume" Jun 19 08:12:14 volumio306z volumio[825]: SPOTIFY: received: {"type":"volume","data":{"value":51,"max":100}} Jun 19 08:12:14 volumio306z volumio[825]: SPOTIFY: RECEIVED SPOTIFY VOLUME 51 Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:20 volumio306z volumio[825]: Bus error Jun 19 08:12:20 volumio306z volumio[825]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Jun 19 08:12:20 volumio306z volumio[825]: Bus error Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: zlib decompression failed, data probably corrupt Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Failed to read block 0x146804a2: -5 Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:12:20 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:12:20 volumio306z volumio[825]: Bus error Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 19 08:12:20 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::volumioStop Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::stop Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::stPlaybackTimer Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::updateTrackBlock Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrackBlock Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::serviceStop Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::serviceStop Jun 19 08:12:33 volumio306z volumio[825]: info: [1750338753476] ControllerWebradio::stop Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 08:12:33 volumio306z volumio[825]: info: Jun 19 08:12:33 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:12:33 volumio306z volumio[825]: info: sendMpdCommand stop took 134 milliseconds Jun 19 08:12:33 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:12:33 volumio306z volumio[825]: info: Jun 19 08:12:33 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:12:33 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:12:33 volumio306z volumio[825]: info: Jun 19 08:12:33 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:12:33 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:12:33 volumio306z volumio[825]: info: sendMpdCommand status took 60 milliseconds Jun 19 08:12:33 volumio306z volumio[825]: info: sendMpdCommand status took 42 milliseconds Jun 19 08:12:33 volumio306z volumio[825]: info: sendMpdCommand status took 27 milliseconds Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:12:33 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 19 08:12:33 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 42 milliseconds Jun 19 08:12:33 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 40 milliseconds Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:12:33 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:12:33 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:33 volumio306z volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 19 08:12:33 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:33 volumio306z volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 19 08:12:33 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:12:33 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:33 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:12:33 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:33 volumio306z volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 19 08:12:33 volumio306z volumio[825]: info: ------------------------------ 285ms Jun 19 08:12:33 volumio306z volumio[825]: info: ------------------------------ 260ms Jun 19 08:12:33 volumio306z volumio[825]: info: ------------------------------ 253ms Jun 19 08:12:33 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:12:34 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:12:34 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:12:34 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:12:34 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:35 volumio306z volumio[825]: error: error Jun 19 08:12:36 volumio306z volumio[825]: error: error Jun 19 08:12:36 volumio306z volumio[825]: error: error Jun 19 08:12:36 volumio306z volumio[825]: error: error Jun 19 08:12:36 volumio306z volumio[825]: error: error Jun 19 08:12:36 volumio306z volumio[825]: error: error Jun 19 08:12:36 volumio306z volumio[825]: error: error Jun 19 08:12:36 volumio306z volumio[825]: error: error Jun 19 08:12:36 volumio306z volumio[825]: error: error Jun 19 08:12:37 volumio306z volumio[825]: info: CoreCommandRouter::volumioGetState Jun 19 08:12:37 volumio306z volumio[825]: info: CorePlayQueue::getTrack 1 Jun 19 08:12:43 volumio306z volumio[825]: info: CoreCommandRouter::volumioGetQueue Jun 19 08:12:43 volumio306z volumio[825]: info: CoreStateMachine::getQueue Jun 19 08:12:43 volumio306z volumio[825]: info: CorePlayQueue::getQueue Jun 19 08:12:44 volumio306z volumio[825]: info: CoreCommandRouter::volumioPlay Jun 19 08:12:44 volumio306z volumio[825]: info: CoreStateMachine::play index 0 Jun 19 08:12:44 volumio306z volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 08:12:44 volumio306z volumio[825]: info: CoreStateMachine::stop Jun 19 08:12:44 volumio306z volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 08:12:44 volumio306z volumio[825]: info: CoreStateMachine::play index undefined Jun 19 08:12:44 volumio306z volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 08:12:44 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:12:44 volumio306z volumio[825]: info: CoreStateMachine::startPlaybackTimer Jun 19 08:12:44 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:12:44 volumio306z volumio[825]: info: [1750338764930] ControllerWebradio::clearAddPlayTrack Jun 19 08:12:44 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 08:12:44 volumio306z volumio[825]: info: sendMpdCommand stop took 10 milliseconds Jun 19 08:12:44 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 08:12:44 volumio306z volumio[825]: info: Jun 19 08:12:44 volumio306z volumio[825]: ---------------------------- MPD announces system playlist update Jun 19 08:12:44 volumio306z volumio[825]: info: Ignoring MPD Status Update Jun 19 08:12:44 volumio306z volumio[825]: info: sendMpdCommand clear took 11 milliseconds Jun 19 08:12:44 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand load "https://kissfm.kissfmradio.cires21.com/kissfm.mp3" Jun 19 08:12:44 volumio306z volumio[825]: info: Jun 19 08:12:44 volumio306z volumio[825]: ---------------------------- MPD announces system playlist update Jun 19 08:12:44 volumio306z volumio[825]: info: Ignoring MPD Status Update Jun 19 08:12:44 volumio306z volumio[825]: info: Jun 19 08:12:44 volumio306z volumio[825]: ---------------------------- MPD announces system playlist update Jun 19 08:12:44 volumio306z volumio[825]: info: Ignoring MPD Status Update Jun 19 08:12:44 volumio306z volumio[825]: error: updateQueue error: null Jun 19 08:12:44 volumio306z volumio[825]: info: ------------------------------ 38ms Jun 19 08:12:53 volumio306z volumio[825]: info: CoreCommandRouter::volumioGetState Jun 19 08:12:53 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:12:58 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand add "https://kissfm.kissfmradio.cires21.com/kissfm.mp3" Jun 19 08:12:58 volumio306z volumio[825]: Upnp client error: Error: This socket has been ended by the other party Jun 19 08:12:58 volumio306z volumio[825]: Upnp client error: Error: This socket has been ended by the other party Jun 19 08:12:58 volumio306z volumio[825]: error: updateQueue error: null Jun 19 08:12:58 volumio306z volumio[825]: error: updateQueue error: null Jun 19 08:12:58 volumio306z volumio[825]: info: Jun 19 08:12:58 volumio306z volumio[825]: ---------------------------- MPD announces system playlist update Jun 19 08:12:58 volumio306z volumio[825]: info: Ignoring MPD Status Update Jun 19 08:12:58 volumio306z volumio[825]: info: ------------------------------ 13786ms Jun 19 08:12:58 volumio306z volumio[825]: info: ------------------------------ 13781ms Jun 19 08:12:58 volumio306z volumio[825]: info: sendMpdCommand add "https://kissfm.kissfmradio.cires21.com/kissfm.mp3" took 36 milliseconds Jun 19 08:12:58 volumio306z volumio[825]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 19 08:12:58 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand play Jun 19 08:12:58 volumio306z volumio[825]: info: ------------------------------ 34ms Jun 19 08:12:58 volumio306z volumio[825]: info: Jun 19 08:12:58 volumio306z volumio[825]: ---------------------------- MPD announces system playlist update Jun 19 08:12:58 volumio306z volumio[825]: info: Ignoring MPD Status Update Jun 19 08:12:58 volumio306z volumio[825]: info: Jun 19 08:12:58 volumio306z volumio[825]: ---------------------------- MPD announces system playlist update Jun 19 08:12:58 volumio306z volumio[825]: info: Ignoring MPD Status Update Jun 19 08:12:58 volumio306z volumio[825]: info: sendMpdCommand play took 62 milliseconds Jun 19 08:12:58 volumio306z volumio[825]: info: ------------------------------ 38ms Jun 19 08:12:58 volumio306z volumio[825]: info: ------------------------------ 31ms Jun 19 08:13:09 volumio306z volumio[825]: info: Jun 19 08:13:09 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:09 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:09 volumio306z volumio[825]: info: Jun 19 08:13:09 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:09 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:09 volumio306z volumio[825]: info: Jun 19 08:13:09 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:09 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:09 volumio306z volumio[825]: info: Jun 19 08:13:09 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:09 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:09 volumio306z volumio[825]: info: sendMpdCommand status took 70 milliseconds Jun 19 08:13:09 volumio306z volumio[825]: info: sendMpdCommand status took 59 milliseconds Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:09 volumio306z volumio[825]: info: Jun 19 08:13:09 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:09 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:09 volumio306z volumio[825]: info: Jun 19 08:13:09 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:09 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:09 volumio306z volumio[825]: info: sendMpdCommand status took 131 milliseconds Jun 19 08:13:09 volumio306z volumio[825]: info: sendMpdCommand status took 119 milliseconds Jun 19 08:13:09 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 95 milliseconds Jun 19 08:13:09 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 86 milliseconds Jun 19 08:13:09 volumio306z volumio[825]: info: sendMpdCommand status took 68 milliseconds Jun 19 08:13:09 volumio306z volumio[825]: info: sendMpdCommand status took 51 milliseconds Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:09 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:09 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:09 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:09 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:09 volumio306z volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kissfm.mp3","artist":null,"album":null,"uri":"https://kissfm.kissfmradio.cires21.com/kissfm.mp3","trackType":"mp3"} Jun 19 08:13:09 volumio306z volumio[825]: verbose: CURRENT POSITION 0 Jun 19 08:13:09 volumio306z volumio[825]: info: CoreStateMachine::syncState stateService play Jun 19 08:13:09 volumio306z volumio[825]: info: CoreStateMachine::syncState currentStatus stop Jun 19 08:13:09 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:09 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:09 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:09 volumio306z volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kissfm.mp3","artist":null,"album":null,"uri":"https://kissfm.kissfmradio.cires21.com/kissfm.mp3","trackType":"mp3"} Jun 19 08:13:09 volumio306z volumio[825]: verbose: CURRENT POSITION 0 Jun 19 08:13:09 volumio306z volumio[825]: info: CoreStateMachine::syncState stateService play Jun 19 08:13:09 volumio306z volumio[825]: info: CoreStateMachine::syncState currentStatus play Jun 19 08:13:09 volumio306z volumio[825]: info: Received an update from plugin. extracting info from payload Jun 19 08:13:09 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:09 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:09 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:09 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:09 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:09 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:09 volumio306z volumio[825]: info: ------------------------------ 423ms Jun 19 08:13:10 volumio306z volumio[825]: info: ------------------------------ 458ms Jun 19 08:13:10 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 259 milliseconds Jun 19 08:13:10 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 258 milliseconds Jun 19 08:13:10 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 247 milliseconds Jun 19 08:13:10 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 246 milliseconds Jun 19 08:13:10 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:13:10 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:13:10 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:13:10 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:13:10 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:10 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:10 volumio306z volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kissfm.mp3","artist":null,"album":null,"uri":"https://kissfm.kissfmradio.cires21.com/kissfm.mp3","trackType":"mp3"} Jun 19 08:13:10 volumio306z volumio[825]: verbose: CURRENT POSITION 0 Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::syncState stateService play Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::syncState currentStatus play Jun 19 08:13:10 volumio306z volumio[825]: info: Received an update from plugin. extracting info from payload Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:10 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:10 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:10 volumio306z volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kissfm.mp3","artist":null,"album":null,"uri":"https://kissfm.kissfmradio.cires21.com/kissfm.mp3","trackType":"mp3"} Jun 19 08:13:10 volumio306z volumio[825]: verbose: CURRENT POSITION 0 Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::syncState stateService play Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::syncState currentStatus play Jun 19 08:13:10 volumio306z volumio[825]: info: Received an update from plugin. extracting info from payload Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:10 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:10 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:10 volumio306z volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kissfm.mp3","artist":null,"album":null,"uri":"https://kissfm.kissfmradio.cires21.com/kissfm.mp3","trackType":"mp3"} Jun 19 08:13:10 volumio306z volumio[825]: verbose: CURRENT POSITION 0 Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::syncState stateService play Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::syncState currentStatus play Jun 19 08:13:10 volumio306z volumio[825]: info: Received an update from plugin. extracting info from payload Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:10 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:10 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:10 volumio306z volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kissfm.mp3","artist":null,"album":null,"uri":"https://kissfm.kissfmradio.cires21.com/kissfm.mp3","trackType":"mp3"} Jun 19 08:13:10 volumio306z volumio[825]: verbose: CURRENT POSITION 0 Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::syncState stateService play Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::syncState currentStatus play Jun 19 08:13:10 volumio306z volumio[825]: info: Received an update from plugin. extracting info from payload Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:10 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:10 volumio306z volumio[825]: info: ------------------------------ 1009ms Jun 19 08:13:10 volumio306z volumio[825]: info: ------------------------------ 992ms Jun 19 08:13:10 volumio306z volumio[825]: info: ------------------------------ 931ms Jun 19 08:13:10 volumio306z volumio[825]: info: ------------------------------ 918ms Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:10 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jun 19 08:13:13 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume67 Jun 19 08:13:13 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:13 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:13 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:13 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Jun 19 08:13:13 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 51 Jun 19 08:13:13 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 67 Jun 19 08:13:13 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:13:13 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 67 Jun 19 08:13:13 volumio306z kernel: SQUASHFS error: zlib decompression failed, data probably corrupt Jun 19 08:13:13 volumio306z kernel: SQUASHFS error: Failed to read block 0x146804a2: -5 Jun 19 08:13:13 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:13 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:13 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:13 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:15 volumio306z volumio[825]: SPOTIFY: SETTING SPOTIFY VOLUME 67 Jun 19 08:13:15 volumio306z volumio[825]: info: Sending Spotify command with payload to local API: /player/volume Jun 19 08:13:15 volumio306z go-librespot[1055]: time="2025-06-19T08:13:15-05:00" level=debug msg="update volume to 43908/65535" Jun 19 08:13:15 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume7 Jun 19 08:13:15 volumio306z go-librespot[1055]: time="2025-06-19T08:13:15-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 19 08:13:15 volumio306z go-librespot[1055]: time="2025-06-19T08:13:15-05:00" level=trace msg="emitting websocket event: volume" Jun 19 08:13:15 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:15 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:15 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:15 volumio306z volumio[825]: SPOTIFY: received: {"type":"volume","data":{"value":67,"max":100}} Jun 19 08:13:15 volumio306z volumio[825]: SPOTIFY: RECEIVED SPOTIFY VOLUME 67 Jun 19 08:13:15 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:15 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:15 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:15 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:15 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Jun 19 08:13:15 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 67 Jun 19 08:13:15 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 7 Jun 19 08:13:15 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:13:15 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 7 Jun 19 08:13:16 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume0 Jun 19 08:13:16 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:16 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:16 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:16 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:16 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:16 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:16 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:17 volumio306z volumio[825]: SPOTIFY: SETTING SPOTIFY VOLUME 7 Jun 19 08:13:17 volumio306z volumio[825]: info: Sending Spotify command with payload to local API: /player/volume Jun 19 08:13:17 volumio306z go-librespot[1055]: time="2025-06-19T08:13:17-05:00" level=debug msg="update volume to 4587/65535" Jun 19 08:13:17 volumio306z go-librespot[1055]: time="2025-06-19T08:13:17-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 19 08:13:17 volumio306z go-librespot[1055]: time="2025-06-19T08:13:17-05:00" level=trace msg="emitting websocket event: volume" Jun 19 08:13:17 volumio306z volumio[825]: SPOTIFY: received: {"type":"volume","data":{"value":7,"max":100}} Jun 19 08:13:17 volumio306z volumio[825]: SPOTIFY: RECEIVED SPOTIFY VOLUME 7 Jun 19 08:13:18 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume49 Jun 19 08:13:18 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:18 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:18 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:18 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Jun 19 08:13:18 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:18 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:18 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:18 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:18 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 7 Jun 19 08:13:18 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 49 Jun 19 08:13:18 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:13:18 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 49 Jun 19 08:13:18 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume59 Jun 19 08:13:18 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:18 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:18 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:18 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jun 19 08:13:18 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 49 Jun 19 08:13:18 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 59 Jun 19 08:13:18 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:13:18 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 59 Jun 19 08:13:18 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:18 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:18 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:18 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:18 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume58 Jun 19 08:13:19 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:19 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:19 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:19 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jun 19 08:13:19 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 59 Jun 19 08:13:19 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 58 Jun 19 08:13:19 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 19 08:13:19 volumio306z volumio[825]: info: VolumeController::SetAlsaVolume54 Jun 19 08:13:19 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:19 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:19 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:19 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:19 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:19 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:19 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:19 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jun 19 08:13:19 volumio306z volumio[825]: SPOTIFY: SPOTIFY VOLUME 59 Jun 19 08:13:19 volumio306z volumio[825]: SPOTIFY: VOLUMIO VOLUME 54 Jun 19 08:13:19 volumio306z volumio[825]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 19 08:13:19 volumio306z volumio[825]: info: Setting Spotify Volume from Volumio: 54 Jun 19 08:13:19 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:19 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:19 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:19 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:21 volumio306z volumio[825]: SPOTIFY: SETTING SPOTIFY VOLUME 54 Jun 19 08:13:21 volumio306z volumio[825]: info: Sending Spotify command with payload to local API: /player/volume Jun 19 08:13:21 volumio306z go-librespot[1055]: time="2025-06-19T08:13:21-05:00" level=debug msg="update volume to 35388/65535" Jun 19 08:13:21 volumio306z go-librespot[1055]: time="2025-06-19T08:13:21-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 19 08:13:21 volumio306z go-librespot[1055]: time="2025-06-19T08:13:21-05:00" level=trace msg="emitting websocket event: volume" Jun 19 08:13:21 volumio306z volumio[825]: SPOTIFY: received: {"type":"volume","data":{"value":54,"max":100}} Jun 19 08:13:21 volumio306z volumio[825]: SPOTIFY: RECEIVED SPOTIFY VOLUME 54 Jun 19 08:13:24 volumio306z volumio[825]: error: error Jun 19 08:13:24 volumio306z volumio[825]: error: error Jun 19 08:13:25 volumio306z volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 08:13:25 volumio306z volumio[825]: error: error Jun 19 08:13:25 volumio306z volumio[825]: error: error Jun 19 08:13:25 volumio306z volumio[825]: error: error Jun 19 08:13:25 volumio306z volumio[825]: error: error Jun 19 08:13:25 volumio306z volumio[825]: error: error Jun 19 08:13:25 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:26 volumio306z volumio[825]: error: error Jun 19 08:13:27 volumio306z volumio[825]: Bus error Jun 19 08:13:27 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:27 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:27 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:27 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:27 volumio306z volumio[825]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Jun 19 08:13:27 volumio306z volumio[825]: Bus error Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 08:13:27 volumio306z volumio[825]: Bus error Jun 19 08:13:27 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:27 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:27 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:27 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 19 08:13:27 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 08:13:39 volumio306z volumio[825]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 19 08:13:39 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 19 08:13:39 volumio306z volumio[825]: info: Preparing to save Alsa Options, stopping services first Jun 19 08:13:39 volumio306z volumio[825]: info: CoreCommandRouter::volumioGetState Jun 19 08:13:39 volumio306z volumio[825]: info: CoreCommandRouter::volumioStop Jun 19 08:13:39 volumio306z volumio[825]: info: CoreStateMachine::stop Jun 19 08:13:39 volumio306z volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 08:13:39 volumio306z volumio[825]: info: CoreStateMachine::stPlaybackTimer Jun 19 08:13:39 volumio306z volumio[825]: info: CoreStateMachine::updateTrackBlock Jun 19 08:13:39 volumio306z volumio[825]: info: CorePlayQueue::getTrackBlock Jun 19 08:13:39 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:39 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:39 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:39 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:39 volumio306z volumio[825]: info: CoreStateMachine::serviceStop Jun 19 08:13:39 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:39 volumio306z volumio[825]: info: CoreCommandRouter::serviceStop Jun 19 08:13:39 volumio306z volumio[825]: info: [1750338819655] ControllerWebradio::stop Jun 19 08:13:39 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 08:13:39 volumio306z volumio[825]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB PnP Sound Device"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 19 08:13:39 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 08:13:39 volumio306z volumio[825]: Bus error Jun 19 08:13:39 volumio306z volumio[825]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Jun 19 08:13:39 volumio306z volumio[825]: Bus error Jun 19 08:13:39 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:39 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:39 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:39 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:39 volumio306z volumio[825]: warn: Unable to locate the device USB PnP Sound Device a reboot may be required. Jun 19 08:13:39 volumio306z volumio[825]: Bus error Jun 19 08:13:39 volumio306z volumio[825]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Jun 19 08:13:39 volumio306z volumio[825]: Bus error Jun 19 08:13:39 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:39 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:39 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:39 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:40 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:40 volumio306z volumio[825]: Bus error Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:40 volumio306z volumio[825]: Bus error Jun 19 08:13:40 volumio306z volumio[825]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Jun 19 08:13:40 volumio306z volumio[825]: Bus error Jun 19 08:13:40 volumio306z volumio[825]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 19 08:13:40 volumio306z volumio[825]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 19 08:13:40 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 19 08:13:40 volumio306z volumio[825]: info: Disabling external Volume Control Jun 19 08:13:40 volumio306z volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 08:13:40 volumio306z volumio[825]: info: Enable softmixer device for audio device undefined Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:40 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jun 19 08:13:40 volumio306z volumio[825]: Unable to parse: Jun 19 08:13:40 volumio306z volumio[825]: Jun 19 08:13:40 volumio306z volumio[825]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 19 08:13:40 volumio306z volumio[825]: info: Software Volume ALSA configuration written Jun 19 08:13:40 volumio306z volumio[825]: info: Preparing to generate the ALSA configuration file Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:40 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:41 volumio306z volumio[825]: info: Jun 19 08:13:41 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:41 volumio306z volumio[825]: info: sendMpdCommand stop took 1454 milliseconds Jun 19 08:13:41 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:41 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:41 volumio306z volumio[825]: info: Jun 19 08:13:41 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:41 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:41 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:41 volumio306z volumio[825]: info: Jun 19 08:13:41 volumio306z volumio[825]: ---------------------------- MPD announces state update: player Jun 19 08:13:41 volumio306z volumio[825]: info: ControllerMpd::getState Jun 19 08:13:41 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand status Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: zlib decompression failed, data probably corrupt Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Failed to read block 0x146804a2: -5 Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:42 volumio306z volumio[825]: Bus error Jun 19 08:13:42 volumio306z volumio[825]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Jun 19 08:13:42 volumio306z volumio[825]: Bus error Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 08:13:42 volumio306z volumio[825]: Bus error Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:42 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 19 08:13:42 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 08:13:42 volumio306z volumio[825]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 19 08:13:42 volumio306z volumio[825]: info: Reading ALSA contributions from plugins. Jun 19 08:13:42 volumio306z volumio[825]: info: sendMpdCommand status took 1726 milliseconds Jun 19 08:13:42 volumio306z volumio[825]: info: sendMpdCommand status took 1708 milliseconds Jun 19 08:13:42 volumio306z volumio[825]: info: sendMpdCommand status took 1677 milliseconds Jun 19 08:13:42 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:42 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:42 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:42 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:42 volumio306z volumio[825]: verbose: ControllerMpd::parseState Jun 19 08:13:42 volumio306z volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 08:13:42 volumio306z volumio[825]: Unable to parse: Jun 19 08:13:42 volumio306z volumio[825]: Jun 19 08:13:43 volumio306z volumio[825]: info: VolumeController:: Volume=undefined Mute =false Jun 19 08:13:43 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:43 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:43 volumio306z volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 08:13:43 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:43 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 324 milliseconds Jun 19 08:13:43 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 314 milliseconds Jun 19 08:13:43 volumio306z volumio[825]: info: sendMpdCommand playlistinfo took 307 milliseconds Jun 19 08:13:43 volumio306z volumio[825]: verbose: ControllerMpd::parseTrackInfo Jun 19 08:13:43 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:43 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:43 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:43 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:43 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:43 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:43 volumio306z volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 19 08:13:43 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:43 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:43 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:43 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:43 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:43 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:43 volumio306z volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 19 08:13:43 volumio306z volumio[825]: info: ControllerMpd::pushState Jun 19 08:13:43 volumio306z volumio[825]: info: CoreCommandRouter::servicePushState Jun 19 08:13:43 volumio306z volumio[825]: info: CoreStateMachine::pushState Jun 19 08:13:43 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:43 volumio306z volumio[825]: info: CoreCommandRouter::volumioPushState Jun 19 08:13:43 volumio306z volumio[825]: info: CorePlayQueue::getTrack 0 Jun 19 08:13:43 volumio306z volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 19 08:13:43 volumio306z volumio[825]: info: ------------------------------ 2230ms Jun 19 08:13:43 volumio306z volumio[825]: info: ------------------------------ 2180ms Jun 19 08:13:43 volumio306z volumio[825]: info: ------------------------------ 2145ms Jun 19 08:13:43 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jun 19 08:13:43 volumio306z volumio[825]: Upnp client error: Error: This socket has been ended by the other party Jun 19 08:13:43 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jun 19 08:13:43 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jun 19 08:13:43 volumio306z volumio[825]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jun 19 08:13:43 volumio306z volumio[825]: info: Asound.conf file written Jun 19 08:13:43 volumio306z sudo[6243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 19 08:13:43 volumio306z sudo[6243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 08:13:43 volumio306z sudo[6243]: pam_unix(sudo:session): session closed for user root Jun 19 08:13:43 volumio306z volumio[825]: Bus error Jun 19 08:13:43 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:43 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:43 volumio306z kernel: SQUASHFS error: Unable to read fragment cache entry [146804a2] Jun 19 08:13:43 volumio306z kernel: SQUASHFS error: Unable to read page, block 146804a2, size d385 Jun 19 08:13:43 volumio306z volumio[825]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 08:13:43 volumio306z volumio[825]: Error: Command failed: /usr/sbin/alsactl -L -R nrestore Jun 19 08:13:43 volumio306z volumio[825]: Bus error Jun 19 08:13:43 volumio306z volumio[825]: at checkExecSyncError (child_process.js:616:11) Jun 19 08:13:43 volumio306z volumio[825]: at execSync (child_process.js:652:15) Jun 19 08:13:43 volumio306z volumio[825]: at /volumio/app/plugins/audio_interface/alsa_controller/index.js:2209:13 Jun 19 08:13:43 volumio306z volumio[825]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Jun 19 08:13:43 volumio306z volumio[825]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Jun 19 08:13:43 volumio306z volumio[825]: at FSReqCallback.oncomplete (fs.js:171:23) { Jun 19 08:13:43 volumio306z volumio[825]: status: 135, Jun 19 08:13:43 volumio306z volumio[825]: signal: null, Jun 19 08:13:43 volumio306z volumio[825]: output: [ null, '', 'Bus error\n' ], Jun 19 08:13:43 volumio306z volumio[825]: pid: 6245, Jun 19 08:13:43 volumio306z volumio[825]: stdout: '', Jun 19 08:13:43 volumio306z volumio[825]: stderr: 'Bus error\n' Jun 19 08:13:43 volumio306z volumio[825]: } Jun 19 08:13:44 volumio306z volumio[825]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 08:13:47 volumio306z sudo[6256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-19 08:12 Jun 19 08:13:47 volumio306z sudo[6256]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"