-- Logs begin at Thu 2019-02-14 13:11:58 +03, end at Tue 2025-06-17 18:34:45 +03. -- Jun 17 18:33:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:33:01 volumio volumio[995]: info: CURURI: music-library Jun 17 18:33:01 volumio volumio[995]: info: Preload queue cleared Jun 17 18:33:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:33:03 volumio volumio[995]: info: CURURI: music-library/INTERNAL Jun 17 18:33:03 volumio volumio[995]: info: Preload queue cleared Jun 17 18:33:06 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:33:06 volumio volumio[995]: info: CURURI: music-library/INTERNAL/Metallica Jun 17 18:33:06 volumio volumio[995]: info: Preload queue cleared Jun 17 18:33:09 volumio volumio[995]: info: Preload queue cleared Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::ClearQueue Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::stop Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::saveQueue Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::addQueueItems Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::addQueueItems Jun 17 18:33:09 volumio volumio[995]: info: Preload queue cleared Jun 17 18:33:09 volumio volumio[995]: info: Adding Item to queue: music-library/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered) Jun 17 18:33:09 volumio volumio[995]: info: Exploding uri music-library/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered) in service mpd Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(01)%20Enter%20Sandman.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(01) Enter Sandman.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(02)%20Sad%20But%20True.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(02) Sad But True.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(03)%20Holier%20Than%20Thou.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(03) Holier Than Thou.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(04)%20The%20Unforgiven.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(04) The Unforgiven.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(05)%20Wherever%20I%20May%20Roam.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(05) Wherever I May Roam.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(06)%20Don't%20Tread%20On%20Me.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(06) Don't Tread On Me.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(07)%20Through%20The%20Never.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(07) Through The Never.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(08)%20Nothing%20Else%20Matters.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(08) Nothing Else Matters.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(09)%20Of%20Wolf%20And%20Man.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(09) Of Wolf And Man.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(10)%20The%20God%20That%20Failed.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(10) The God That Failed.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(11)%20My%20Friend%20Of%20Misery.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(11) My Friend Of Misery.flac Jun 17 18:33:09 volumio volumio[995]: info: ALBUMART /albumart?cacheid=566&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FINTERNAL%2FMetallica%2F1991%20~Metallica~%20%5BTR%5D%5BOF%5D%20(Remastered)%2F(12)%20The%20Struggle%20Within.flac&metadata=false Jun 17 18:33:09 volumio volumio[995]: info: URI /mnt/INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(12) The Struggle Within.flac Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::saveQueue Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::volumioPlay Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::play index 0 Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::stop Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::play index undefined Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::startPlaybackTimer Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(01) Enter Sandman.flac Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:33:09 volumio volumio[995]: info: sendMpdCommand stop took 2 milliseconds Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 18:33:09 volumio volumio[995]: info: Jun 17 18:33:09 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jun 17 18:33:09 volumio volumio[995]: info: Ignoring MPD Status Update Jun 17 18:33:09 volumio volumio[995]: info: sendMpdCommand clear took 5 milliseconds Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(01) Enter Sandman.flac" Jun 17 18:33:09 volumio volumio[995]: error: updateQueue error: null Jun 17 18:33:09 volumio volumio[995]: info: Jun 17 18:33:09 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jun 17 18:33:09 volumio volumio[995]: info: Ignoring MPD Status Update Jun 17 18:33:09 volumio volumio[995]: info: ------------------------------ 5ms Jun 17 18:33:09 volumio volumio[995]: info: sendMpdCommand add "INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(01) Enter Sandman.flac" took 3 milliseconds Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand play Jun 17 18:33:09 volumio volumio[995]: error: Upnp client error: Error: This socket has been ended by the other party Jun 17 18:33:09 volumio volumio[995]: info: ------------------------------ 15ms Jun 17 18:33:09 volumio volumio[995]: info: sendMpdCommand play took 13 milliseconds Jun 17 18:33:09 volumio volumio[995]: info: Jun 17 18:33:09 volumio volumio[995]: ---------------------------- MPD announces state update: player Jun 17 18:33:09 volumio volumio[995]: info: ControllerMpd::getState Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:33:09 volumio volumio[995]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 17 18:33:09 volumio volumio[995]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 17 18:33:09 volumio volumio[995]: info: camilladsp stopping service pid 2003... Jun 17 18:33:09 volumio volumio[995]: info: camilladsp service terminated, instance 1 Jun 17 18:33:09 volumio volumio[995]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 17 18:33:09 volumio volumio[995]: info: camilladsp service started and running in background, instance 1 Jun 17 18:33:09 volumio volumio[995]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 17 18:33:09 volumio volumio[995]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 17 18:33:09 volumio volumio[995]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 17 18:33:09 volumio volumio[995]: info: Jun 17 18:33:09 volumio volumio[995]: ---------------------------- MPD announces state update: player Jun 17 18:33:09 volumio volumio[995]: info: sendMpdCommand status took 56 milliseconds Jun 17 18:33:09 volumio volumio[995]: info: ControllerMpd::getState Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::parseState Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:33:09 volumio volumio[995]: info: sendMpdCommand status took 5 milliseconds Jun 17 18:33:09 volumio volumio[995]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::parseState Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:33:09 volumio volumio[995]: info: ControllerMpd::pushState Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:09 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Enter Sandman","artist":"Metallica","album":"Metallica","uri":"INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(01) Enter Sandman.flac","trackType":"flac"} Jun 17 18:33:09 volumio volumio[995]: verbose: CURRENT POSITION 0 Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:33:09 volumio volumio[995]: info: ------------------------------ 69ms Jun 17 18:33:09 volumio volumio[995]: error: FusionDsp - WebSocket error: [object Object] Jun 17 18:33:09 volumio volumio[995]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 17 18:33:09 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:33:09 volumio volumio[995]: info: ControllerMpd::pushState Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:09 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":332,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2862 Kbps","isStreaming":false,"title":"Enter Sandman","artist":"Metallica","album":"Metallica","uri":"INTERNAL/Metallica/1991 ~Metallica~ [TR][OF] (Remastered)/(01) Enter Sandman.flac","trackType":"flac"} Jun 17 18:33:09 volumio volumio[995]: verbose: CURRENT POSITION 0 Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:33:09 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:09 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:09 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:09 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:09 volumio volumio[995]: info: ------------------------------ 65ms Jun 17 18:33:09 volumio volumio[995]: info: [LastFM] Current track has sufficient metadata: title (1 - Enter Sandman) and artist (Metallica) passed on explicitly Jun 17 18:33:09 volumio volumio[995]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 17 18:33:17 volumio ntpd[803]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:19 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:19 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:19 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:20 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:20 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:20 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:21 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:21 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:21 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:21 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:21 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:21 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:21 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:21 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:21 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:21 volumio volumio[995]: info: VolumeController::SetAlsaVolume+ Jun 17 18:33:21 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:21 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:21 volumio volumio[995]: info: VolumeController::SetAlsaVolume- Jun 17 18:33:21 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:21 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:21 volumio volumio[995]: info: VolumeController::SetAlsaVolume- Jun 17 18:33:21 volumio volumio[995]: info: CoreStateMachine::pushState Jun 17 18:33:21 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:33:21 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jun 17 18:33:27 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Jun 17 18:33:27 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jun 17 18:33:37 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 18:33:37 volumio volumio[995]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Jun 17 18:33:37 volumio volumio[995]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jun 17 18:33:37 volumio volumio[995]: errno: -3007, Jun 17 18:33:37 volumio volumio[995]: code: 'ENOTFOUND', Jun 17 18:33:37 volumio volumio[995]: syscall: 'getaddrinfo', Jun 17 18:33:37 volumio volumio[995]: hostname: 'ws.audioscrobbler.com' Jun 17 18:33:37 volumio volumio[995]: } Jun 17 18:33:37 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 18:33:38 volumio sudo[3973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 18:32 Jun 17 18:33:38 volumio sudo[3973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:33:38 volumio sudo[3973]: pam_unix(sudo:session): session closed for user root Jun 17 18:33:45 volumio ntpd[803]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 18:33:50 volumio key.dns_resolver[3773]: Win10.local: No address associated with name Jun 17 18:33:53 volumio volumio-remote-updater[625]: [2025-06-17 18:33:53] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jun 17 18:33:58 volumio volumio-remote-updater[625]: [2025-06-17 18:33:58] [connect] Successful connection Jun 17 18:34:06 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 17 18:34:06 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 17 18:34:06 volumio systemd[1]: Started dynamicswap service. Jun 17 18:34:06 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 17 18:34:06 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 17 18:34:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 17 18:34:06 volumio systemd[1]: Stopped Volumio Backend Module. Jun 17 18:34:06 volumio systemd[1]: Started Volumio Backend Module. Jun 17 18:34:06 volumio systemd[1]: Started dynamicswap service. Jun 17 18:34:06 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 17 18:34:07 volumio volumio[4083]: info: ------------------------------------------- Jun 17 18:34:07 volumio volumio[4083]: info: ----- Volumio3 ---- Jun 17 18:34:07 volumio volumio[4083]: info: ------------------------------------------- Jun 17 18:34:07 volumio volumio[4083]: info: ----- System startup ---- Jun 17 18:34:07 volumio volumio[4083]: info: ------------------------------------------- Jun 17 18:34:08 volumio volumio[4083]: info: MYVOLUMIO Environment detected Jun 17 18:34:08 volumio volumio[4083]: info: Plugin folders cleanup Jun 17 18:34:08 volumio volumio[4083]: info: Scanning into folder /volumio/app/plugins/ Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category audio_interface Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category miscellanea Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category music_service Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category plugins.json Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category system_controller Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category user_interface Jun 17 18:34:08 volumio volumio[4083]: info: Scanning into folder /data/plugins/ Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category audio_interface Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category system_hardware Jun 17 18:34:08 volumio volumio[4083]: info: Scanning category user_interface Jun 17 18:34:08 volumio volumio[4083]: info: Plugin folders cleanup completed Jun 17 18:34:08 volumio volumio[4083]: info: ------------------------------------------- Jun 17 18:34:08 volumio volumio[4083]: info: ----- Core plugins startup ---- Jun 17 18:34:08 volumio volumio[4083]: info: ------------------------------------------- Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugins from folder /volumio/app/plugins/ Jun 17 18:34:08 volumio volumio[4083]: info: Adding plugin upnp to MyMusic Plugins Jun 17 18:34:08 volumio volumio[4083]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 17 18:34:08 volumio volumio[4083]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugins from folder /data/plugins/ Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "system"... Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "appearance"... Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "network"... Jun 17 18:34:08 volumio volumio[4083]: info: Refreshing Cached IP Addresses Jun 17 18:34:08 volumio sudo[4110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 18:34:08 volumio sudo[4110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:08 volumio sudo[4110]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:08 volumio sudo[4113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 18:34:08 volumio sudo[4113]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:08 volumio sudo[4113]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "services"... Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "alsa_controller"... Jun 17 18:34:08 volumio sudo[4125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 17 18:34:08 volumio sudo[4125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:08 volumio sudo[4125]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:08 volumio kernel: ieee80211 phy0: brcmf_vif_set_mgmt_ie: vndr ie set error : -52 Jun 17 18:34:08 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: scan error (-52) Jun 17 18:34:08 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "wizard"... Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "networkfs"... Jun 17 18:34:08 volumio volumio[4083]: info: Cannot mount NAS Win10_Music at system boot, trial number 1 ,retrying in 5 seconds Jun 17 18:34:08 volumio volumio[4083]: info: Starting Udev Watcher for removable devices Jun 17 18:34:08 volumio volumio[4083]: info: Ignoring mount for partition: boot Jun 17 18:34:08 volumio volumio[4083]: info: Ignoring mount for partition: volumio Jun 17 18:34:08 volumio volumio[4083]: info: Ignoring mount for partition: volumio_data Jun 17 18:34:08 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "volumio_command_line_client"... Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "upnp"... Jun 17 18:34:08 volumio volumio[4083]: info: [1750174448947] Starting Upmpd Daemon Jun 17 18:34:08 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "my_music"... Jun 17 18:34:08 volumio volumio[4083]: info: Loading plugin "mpd"... Jun 17 18:34:09 volumio volumio[4083]: info: Loading plugin "upnp_browser"... Jun 17 18:34:09 volumio volumio[4083]: info: Loading plugin "alarm-clock"... Jun 17 18:34:09 volumio volumio[4083]: info: Loading plugin "airplay_emulation"... Jun 17 18:34:09 volumio volumio[4083]: info: Starting Shairport Sync Jun 17 18:34:09 volumio volumio[4083]: info: Loading plugin "last_100"... Jun 17 18:34:09 volumio volumio[4083]: info: Loading plugin "webradio"... Jun 17 18:34:09 volumio volumio[4083]: info: Loading plugin "i2s_dacs"... Jun 17 18:34:09 volumio volumio[4083]: info: Loading plugin "volumiodiscovery"... Jun 17 18:34:09 volumio volumio[4083]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 18:34:09 volumio volumio[4083]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 18:34:09 volumio volumio[4083]: *** WARNING *** For more information see Jun 17 18:34:09 volumio node[4083]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 18:34:09 volumio volumio[4083]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 18:34:09 volumio volumio[4083]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 18:34:09 volumio volumio[4083]: *** WARNING *** For more information see Jun 17 18:34:09 volumio node[4083]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 18:34:09 volumio node[4083]: *** WARNING *** For more information see Jun 17 18:34:09 volumio node[4083]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 18:34:09 volumio node[4083]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 18:34:09 volumio node[4083]: *** WARNING *** For more information see Jun 17 18:34:09 volumio volumio[4083]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 17 18:34:09 volumio volumio[4083]: info: Discovery: Started advertising with name: Volumio Jun 17 18:34:09 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 18:34:09 volumio volumio[4083]: info: Loading plugin "now_playing"... Jun 17 18:34:10 volumio volumio[4083]: info: Loading plugin "outputs"... Jun 17 18:34:10 volumio volumio[4083]: info: Loading plugin "albumart"... Jun 17 18:34:10 volumio volumio[4083]: info: Plugin example_plugin is not enabled Jun 17 18:34:10 volumio volumio[4083]: info: Loading plugin "inputs"... Jun 17 18:34:10 volumio volumio[4083]: info: Loading plugin "updater_comm"... Jun 17 18:34:10 volumio volumio[4083]: info: Plugin mpdemulation is not enabled Jun 17 18:34:10 volumio volumio[4083]: info: Loading plugin "rest_api"... Jun 17 18:34:10 volumio volumio[4083]: info: Loading plugin "websocket"... Jun 17 18:34:10 volumio volumio[4083]: info: Starting Socket.io Server version 2.3.0 Jun 17 18:34:10 volumio volumio[4083]: info: Loading plugin "fusiondsp"... Jun 17 18:34:10 volumio volumio[4083]: Forking 3 albumart workers Jun 17 18:34:10 volumio volumio[4083]: info: Applying required configuration parameters for plugin fusiondsp Jun 17 18:34:10 volumio volumio[4083]: info: Loading plugin "rotaryencoder2"... Jun 17 18:34:10 volumio volumio-remote-updater[625]: [2025-06-17 18:34:10] [connect] Successful connection Jun 17 18:34:11 volumio volumio[4083]: info: Loading plugin "lastfm"... Jun 17 18:34:11 volumio volumio[4083]: Starting albumart workers Jun 17 18:34:11 volumio volumio[4083]: Starting albumart workers Jun 17 18:34:11 volumio volumio[4083]: Starting albumart workers Jun 17 18:34:11 volumio volumio[4083]: info: Loading plugin "touch_display"... Jun 17 18:34:11 volumio volumio[4083]: info: Applying required configuration parameters for plugin touch_display Jun 17 18:34:11 volumio volumio[4083]: info: Loading i18n strings for locale ru Jun 17 18:34:11 volumio volumio[4083]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 17 18:34:11 volumio volumio[4083]: Updating browse sources language Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::initPlayerControls Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 18:34:11 volumio volumio[4083]: Express server listening on port 3000 Jun 17 18:34:11 volumio volumio[4083]: [Metrics] WebUI: 4s 493.95ms Jun 17 18:34:11 volumio volumio[4083]: info: CoreStateMachine::resetVolumioState Jun 17 18:34:11 volumio volumio[4083]: info: CoreStateMachine::getcurrentVolume Jun 17 18:34:11 volumio volumio[4083]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 18:34:11 volumio volumio[4083]: info: Volumio Network Manager: Network status updated: 0 Jun 17 18:34:11 volumio volumio[4083]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 17 18:34:11 volumio volumio[4083]: wlan0 Interface doesn't support scanning : Invalid exchange Jun 17 18:34:11 volumio volumio[4083]: info: Cannot use regular scanning, forcing with ap-force Jun 17 18:34:11 volumio sudo[4198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 17 18:34:11 volumio sudo[4198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:13 volumio ntpd[803]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 18:34:16 volumio sudo[4198]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:16 volumio volumio[4083]: info: Cannot mount NAS Win10_Music at system boot, trial number 2 ,retrying in 5 seconds Jun 17 18:34:16 volumio volumio[4083]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.166 UA: Mozilla/5.0 (Linux; Android 14; T60 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 17 18:34:16 volumio volumio[4083]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 17 18:34:16 volumio volumio[4083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 17 18:34:16 volumio volumio[4083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 17 18:34:16 volumio volumio[4083]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.166 UA: Mozilla/5.0 (Linux; Android 14; T60 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:16 volumio volumio[4083]: info: Reloading queue from file Jun 17 18:34:16 volumio volumio[4083]: info: CoreStateMachine::setRepeat null single undefined Jun 17 18:34:16 volumio volumio[4083]: info: CoreStateMachine::pushState Jun 17 18:34:16 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioPushState Jun 17 18:34:16 volumio volumio[4083]: info: CoreStateMachine::setRandom null Jun 17 18:34:16 volumio volumio[4083]: info: CoreStateMachine::pushState Jun 17 18:34:16 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioPushState Jun 17 18:34:16 volumio volumio[4083]: info: Setting Device type: Raspberry PI Jun 17 18:34:16 volumio volumio[4083]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 17 18:34:16 volumio volumio[4083]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jun 17 18:34:16 volumio volumio[4083]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 17 18:34:16 volumio volumio[4083]: info: Completed loading Core Plugins Jun 17 18:34:16 volumio volumio[4083]: info: Preparing to generate the ALSA configuration file Jun 17 18:34:16 volumio volumio[4083]: info: VolumeController:: Volume=78 Mute =false Jun 17 18:34:16 volumio volumio[4083]: info: CoreStateMachine::pushState Jun 17 18:34:16 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioPushState Jun 17 18:34:16 volumio volumio[4083]: info: CoreStateMachine::updateTrackBlock Jun 17 18:34:16 volumio volumio[4083]: info: CorePlayQueue::getTrackBlock Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 18:34:16 volumio volumio[4083]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 17 18:34:16 volumio volumio[4083]: info: Reading ALSA contributions from plugins. Jun 17 18:34:16 volumio volumio[4083]: info: VolumeController:: Volume=78 Mute =false Jun 17 18:34:16 volumio volumio[4083]: info: CoreStateMachine::pushState Jun 17 18:34:16 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioPushState Jun 17 18:34:16 volumio volumio[4083]: info: Asound.conf file unchanged, so no further update is needed Jun 17 18:34:16 volumio volumio[4083]: info: Output device has changed, restarting MPD Jun 17 18:34:16 volumio sudo[4209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 18:34:16 volumio sudo[4209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio volumio[4083]: info: Output device has changed, restarting Shairport Sync Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:16 volumio sudo[4209]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:16 volumio sudo[4211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 18:34:16 volumio sudo[4211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio systemd[1]: Stopping Music Player Daemon... Jun 17 18:34:16 volumio volumio[4083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 18:34:16 volumio volumio[4083]: info: ___________ START PLUGINS ___________ Jun 17 18:34:16 volumio systemd[1]: mpd.service: Succeeded. Jun 17 18:34:16 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 18:34:16 volumio volumio[4083]: info: ControllerMpd::onStart: Initializing MPD Jun 17 18:34:16 volumio volumio[4083]: info: Creating MPD Configuration file Jun 17 18:34:16 volumio sudo[4219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 18:34:16 volumio sudo[4219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 18:34:16 volumio volumio[4083]: info: [1750174456498] CoreMusicLibrary::Adding element Медиа-серверы Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:16 volumio sudo[4219]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:16 volumio sudo[4222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 18:34:16 volumio systemd[1]: Starting Music Player Daemon... Jun 17 18:34:16 volumio sudo[4222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 17 18:34:16 volumio systemd[1]: mpd.service: Succeeded. Jun 17 18:34:16 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 18:34:16 volumio systemd[1]: Starting Music Player Daemon... Jun 17 18:34:16 volumio volumio[4083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:16 volumio volumio[4083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 18:34:16 volumio volumio[4083]: info: [1750174456602] CoreMusicLibrary::Adding element Last_100 Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 18:34:16 volumio volumio[4083]: info: [1750174456605] CoreMusicLibrary::Adding element Webradio Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 18:34:16 volumio sudo[4225]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 18:34:16 volumio sudo[4225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio volumio[4083]: info: Initializing BBC Radios Jun 17 18:34:16 volumio sudo[4225]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:16 volumio volumio[4083]: info: [now-playing] ConfigUpdater: config is up to date. Jun 17 18:34:16 volumio volumio[4083]: info: Loading i18n strings for locale ru Jun 17 18:34:16 volumio volumio[4083]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:16 volumio volumio[4083]: info: FusionDsp - mixtype--------------------- Hardware Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 18:34:16 volumio volumio[4083]: info: [1750174456727] CoreMusicLibrary::Adding element LastFM Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 18:34:16 volumio volumio[4083]: Cannot find translation for source LastFM Jun 17 18:34:16 volumio volumio[4083]: info: [LastFM] scrobbler initiated! Jun 17 18:34:16 volumio volumio[4083]: info: [LastFM] extended logging: false Jun 17 18:34:16 volumio volumio[4083]: info: [LastFM] try scrobble stream/radio plays: true Jun 17 18:34:16 volumio volumio[4083]: info: [LastFM] Left init routine Jun 17 18:34:16 volumio volumio[4083]: info: [LastFM] Socket already connected: true Jun 17 18:34:16 volumio volumio[4083]: info: Loading i18n strings for locale ru Jun 17 18:34:16 volumio volumio[4083]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 17 18:34:16 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 18:34:16 volumio volumio[4083]: info: Volumio Calling Home Jun 17 18:34:16 volumio volumio[4083]: info: Preparing to generate the ALSA configuration file Jun 17 18:34:16 volumio sudo[4247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=27 relative_axis=true steps-per-period=1 Jun 17 18:34:16 volumio sudo[4247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio sudo[4252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 17 18:34:16 volumio sudo[4252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio sudo[4256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 17 18:34:16 volumio sudo[4256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio sudo[4247]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:16 volumio sudo[4262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 17 18:34:16 volumio sudo[4262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:16 volumio sudo[4252]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:16 volumio systemd[1]: Reloading. Jun 17 18:34:17 volumio volumio[4083]: info: [now-playing] App is listening on port 4004. Jun 17 18:34:17 volumio volumio[4083]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 17 18:34:17 volumio volumio[4083]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 17 18:34:17 volumio volumio[4083]: info: Reading ALSA contributions from plugins. Jun 17 18:34:17 volumio volumio[4083]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Jun 17 18:34:17 volumio volumio[4083]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 17 18:34:17 volumio volumio[4083]: error: [ROTARYENCODER2] addOverlay: * Failed to apply overlay '2_rotary-encoder' (kernel) Jun 17 18:34:17 volumio volumio[4083]: info: MPD Permissions set Jun 17 18:34:17 volumio volumio[4083]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 17 18:34:17 volumio volumio[4083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio sudo[4277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 17 18:34:17 volumio sudo[4277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:17 volumio sudo[4277]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 17 18:34:17 volumio sudo[4290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 17 18:34:17 volumio sudo[4290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:17 volumio volumio[4083]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 17 18:34:17 volumio sudo[4290]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::volumioGetVisibleSources Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:17 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 17 18:34:17 volumio sudo[4293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 17 18:34:17 volumio volumio[4083]: info: Received Get System Info Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 18:34:17 volumio sudo[4293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:17 volumio volumio[4083]: info: Discovery: Getting this device information Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:17 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:17 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:17 volumio volumio[4083]: info: Listing playlists Jun 17 18:34:17 volumio sudo[4293]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:17 volumio volumio[4083]: info: Starting Shairport Sync Jun 17 18:34:17 volumio volumio[4083]: info: Starting Shairport Sync Jun 17 18:34:17 volumio sudo[4296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 18:34:17 volumio sudo[4296]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:17 volumio volumio[4083]: info: Starting Shairport Sync Jun 17 18:34:17 volumio sudo[4299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 18:34:17 volumio sudo[4299]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:17 volumio sudo[4302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 18:34:17 volumio sudo[4302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:17 volumio volumio[4083]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 17 18:34:17 volumio mpd[4232]: Jun 17 18:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 18:34:17 volumio volumio[4083]: info: Asound.conf file unchanged, so no further update is needed Jun 17 18:34:17 volumio volumio[4083]: info: Output device has changed, restarting MPD Jun 17 18:34:17 volumio sudo[4308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 18:34:17 volumio sudo[4308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:17 volumio volumio[4083]: info: Output device has changed, restarting Shairport Sync Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:17 volumio sudo[4308]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:17 volumio sudo[4310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 18:34:17 volumio sudo[4310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:17 volumio volumio[4083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 18:34:17 volumio systemd[1]: Started Music Player Daemon. Jun 17 18:34:17 volumio sudo[4256]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:17 volumio sudo[4211]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:17 volumio sudo[4222]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:17 volumio volumio[4083]: info: MPD Permissions set Jun 17 18:34:17 volumio systemd[1]: Reloading. Jun 17 18:34:17 volumio volumio[4083]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:17 volumio volumio[4083]: error: MPD error: The expression evaluated to a falsy value: Jun 17 18:34:17 volumio volumio[4083]: assert.ok(self.idling) Jun 17 18:34:17 volumio volumio[4083]: error: The expression evaluated to a falsy value: Jun 17 18:34:17 volumio volumio[4083]: assert.ok(self.idling) Jun 17 18:34:17 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:18 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:18 volumio sudo[4262]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:18 volumio systemd[1]: Stopping Music Player Daemon... Jun 17 18:34:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 18:34:18 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 18:34:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:18 volumio sudo[4299]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 18:34:18 volumio systemd[1]: mpd.service: Succeeded. Jun 17 18:34:18 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 18:34:18 volumio sudo[4302]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:18 volumio sudo[4296]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:18 volumio systemd[1]: Starting Music Player Daemon... Jun 17 18:34:18 volumio volumio[4083]: error: updateQueue error: null Jun 17 18:34:18 volumio volumio[4083]: info: MPD running with PID4232 Jun 17 18:34:18 volumio volumio[4083]: ,establishing connection Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:18 volumio sudo[4337]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 18:34:18 volumio sudo[4337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:18 volumio sudo[4337]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:18 volumio volumio[4083]: info: Shairport-Sync Started Jun 17 18:34:18 volumio volumio[4083]: Error adding Membership: Error: addMembership EINVAL Jun 17 18:34:18 volumio volumio[4083]: info: Shairport-Sync Started Jun 17 18:34:18 volumio volumio[4083]: info: Shairport-Sync Started Jun 17 18:34:18 volumio volumio[4083]: info: touch_display: systemctl daemon-reload succeeded. Jun 17 18:34:18 volumio sudo[4344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 17 18:34:18 volumio sudo[4344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:18 volumio sudo[4344]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:18 volumio volumio[4083]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 17 18:34:18 volumio volumio[4083]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 17 18:34:18 volumio volumio[4083]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 17 18:34:18 volumio volumio[4083]: info: camilladsp service started and running in background, instance 1 Jun 17 18:34:18 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:18 volumio volumio[4083]: cannot open device 'volumioHw': Device or resource busy Jun 17 18:34:18 volumio volumio[4083]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 17 18:34:18 volumio volumio[4083]: cannot open device 'volumioHw': Device or resource busy Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp loaded Jun 17 18:34:18 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 18:34:18 volumio sudo[4376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 17 18:34:18 volumio sudo[4376]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 17 18:34:18 volumio volumio[4083]: info: Adding Signal Path Element [object Object] Jun 17 18:34:18 volumio volumio[4083]: info: Adding fusiondspeq DSP Signal Path Element Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - ---- installed callbackRead Jun 17 18:34:18 volumio volumio[4083]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 17 18:34:18 volumio sudo[4376]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:18 volumio volumio[4083]: info: Starting Shairport Sync Jun 17 18:34:18 volumio volumio[4083]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 17 18:34:18 volumio volumio[4083]: info: touch_display: Volumio Kiosk started. Jun 17 18:34:19 volumio sudo[4379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 18:34:19 volumio sudo[4379]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:19 volumio volumio[4083]: info: Completed starting Core Plugins Jun 17 18:34:19 volumio volumio[4083]: info: ------------------------------------------- Jun 17 18:34:19 volumio volumio[4083]: info: ----- MyVolumio plugins startup ---- Jun 17 18:34:19 volumio volumio[4083]: info: ------------------------------------------- Jun 17 18:34:19 volumio volumio[4083]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 17 18:34:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 18:34:19 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 18:34:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:19 volumio sudo[4385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 18:34:19 volumio sudo[4385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 18:34:19 volumio sudo[4385]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:19 volumio sudo[4379]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:19 volumio volumio[4083]: info: touch_display: X display number found: 0 Jun 17 18:34:19 volumio sudo[4387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 18:34:19 volumio sudo[4387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:19 volumio sudo[4387]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:19 volumio volumio[4083]: info: Shairport-Sync Started Jun 17 18:34:19 volumio sudo[4393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 17 18:34:19 volumio sudo[4393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 18:34:19 volumio sudo[4393]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:19 volumio volumio[4083]: info: Upmpdcli Daemon Started Jun 17 18:34:19 volumio volumio[4083]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 17 18:34:19 volumio volumio[4083]: info: touch_display: X display number found: 0 Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:19 volumio volumio[4083]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 17 18:34:19 volumio mpd[4341]: Jun 17 18:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 18:34:19 volumio volumio[4083]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.166 UA: Mozilla/5.0 (Linux; Android 14; T60 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 17 18:34:19 volumio systemd[1]: Started Music Player Daemon. Jun 17 18:34:19 volumio volumio[4083]: error: updateQueue error: null Jun 17 18:34:19 volumio sudo[4310]: pam_unix(sudo:session): session closed for user root Jun 17 18:34:19 volumio volumio[4083]: error: updateQueue error: null Jun 17 18:34:19 volumio volumio[4083]: info: VolumeController::SetAlsaVolume55 Jun 17 18:34:19 volumio volumio[4083]: info: CoreStateMachine::pushState Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioPushState Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetVisibleSources Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 17 18:34:19 volumio volumio[4083]: info: Received Get System Info Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 18:34:19 volumio volumio[4083]: info: Discovery: Getting this device information Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:19 volumio volumio[4083]: info: Listing playlists Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::volumioGetQueue Jun 17 18:34:19 volumio volumio[4083]: info: CoreStateMachine::getQueue Jun 17 18:34:19 volumio volumio[4083]: info: CorePlayQueue::getQueue Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:19 volumio volumio[4083]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 18:34:19 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:19 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:19 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:20 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:20 volumio volumio[4083]: info: FusionDsp - no preset used Jun 17 18:34:20 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:20 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:20 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:20 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:20 volumio volumio[4083]: info: FusionDsp - Jun 17 18:34:21 volumio volumio[4083]: info: Cannot mount NAS Win10_Music at system boot, trial number 3 ,retrying in 5 seconds Jun 17 18:34:22 volumio volumio[4083]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 17 18:34:25 volumio volumio-remote-updater[625]: [2025-06-17 18:34:25] [connect] Successful connection Jun 17 18:34:25 volumio volumio-remote-updater[625]: [2025-06-17 18:34:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750174465 101 Jun 17 18:34:25 volumio volumio[4083]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Jun 17 18:34:26 volumio volumio[4083]: info: Cannot mount NAS Win10_Music at system boot, trial number 4 ,retrying in 5 seconds Jun 17 18:34:26 volumio dhcpcd[2161]: eth0: carrier acquired Jun 17 18:34:26 volumio dhcpcd[2161]: eth0: IAID 32:26:33:94 Jun 17 18:34:26 volumio dhcpcd[2161]: eth0: adding address fe80::dea6:32ff:fe26:3394 Jun 17 18:34:26 volumio dhcpcd[2161]: ipv6_addaddr1: Permission denied Jun 17 18:34:26 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Jun 17 18:34:26 volumio ifplugd(eth0)[801]: Link beat detected. Jun 17 18:34:26 volumio ifplugd(eth0)[801]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 17 18:34:26 volumio dhcpcd[4428]: sending commands to master dhcpcd process Jun 17 18:34:26 volumio dhcpcd[2161]: control command: /sbin/dhcpcd eth0 Jun 17 18:34:26 volumio ifplugd(eth0)[801]: client: sending commands to master dhcpcd process Jun 17 18:34:26 volumio dhcpcd[2161]: eth0: rebinding lease of 192.168.0.101 Jun 17 18:34:27 volumio dhcpcd[2161]: eth0: probing address 192.168.0.101/24 Jun 17 18:34:27 volumio ifplugd(eth0)[801]: Program executed successfully. Jun 17 18:34:27 volumio dhcpcd[2161]: eth0: soliciting an IPv6 router Jun 17 18:34:31 volumio volumio[4083]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 17 18:34:32 volumio dhcpcd[2161]: eth0: leased 192.168.0.101 for 7200 seconds Jun 17 18:34:32 volumio dhcpcd[2161]: eth0: adding route to 192.168.0.0/24 Jun 17 18:34:32 volumio avahi-daemon[623]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.101. Jun 17 18:34:32 volumio avahi-daemon[623]: New relevant interface eth0.IPv4 for mDNS. Jun 17 18:34:32 volumio dhcpcd[2161]: eth0: adding default route via 192.168.0.1 Jun 17 18:34:32 volumio dhcpcd[2161]: wlan0: deleting default route Jun 17 18:34:32 volumio avahi-daemon[623]: Registering new address record for 192.168.0.101 on eth0.IPv4. Jun 17 18:34:32 volumio dnsmasq[2280]: reading /etc/resolv.conf Jun 17 18:34:32 volumio dnsmasq[2280]: using nameserver 208.67.222.222#53 Jun 17 18:34:32 volumio dnsmasq[2280]: using nameserver 208.67.220.220#53 Jun 17 18:34:32 volumio dnsmasq[2280]: using nameserver 192.168.0.1#53 Jun 17 18:34:32 volumio kernel: CIFS: VFS: \\Win10.local has not responded in 180 seconds. Reconnecting... Jun 17 18:34:33 volumio ntpd[803]: Listen normally on 6 eth0 192.168.0.101:123 Jun 17 18:34:33 volumio ntpd[803]: 178.124.164.105 local addr 169.254.239.17 -> 192.168.0.101 Jun 17 18:34:33 volumio ntpd[803]: 185.123.187.25 local addr 169.254.239.17 -> 192.168.0.101 Jun 17 18:34:33 volumio ntpd[803]: 178.159.240.10 local addr 169.254.239.17 -> 192.168.0.101 Jun 17 18:34:33 volumio ntpd[803]: 178.124.164.106 local addr 169.254.239.17 -> 192.168.0.101 Jun 17 18:34:33 volumio ntpd[803]: 91.215.176.2 local addr 169.254.239.17 -> 192.168.0.101 Jun 17 18:34:33 volumio ntpd[803]: 178.124.134.106 local addr 169.254.239.17 -> 192.168.0.101 Jun 17 18:34:33 volumio ntpd[803]: new interface(s) found: waking up resolver Jun 17 18:34:38 volumio volumio[4083]: info: Volumio Network Manager: Network status updated: 1 Jun 17 18:34:39 volumio volumio[4083]: error: MyVolumio Plugin failed to start in a timely fashion Jun 17 18:34:39 volumio volumio[4083]: [Metrics] CommandRouter: 31s 334.43ms Jun 17 18:34:39 volumio volumio[4083]: info: CoreCommandRouter::volumiosetStartupVolume Jun 17 18:34:39 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 18:34:39 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 18:34:39 volumio volumio[4083]: info: CoreCommandRouter::Close All Modals sent Jun 17 18:34:39 volumio volumio[4083]: info: CoreCommandRouter::Close All Modals sent Jun 17 18:34:39 volumio volumio[4083]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 17 18:34:39 volumio volumio[4083]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 17 18:34:39 volumio volumio[4083]: info: camilladsp stopping service pid 4352... Jun 17 18:34:39 volumio volumio[4083]: info: camilladsp service terminated, instance 1 Jun 17 18:34:39 volumio volumio[4083]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 17 18:34:39 volumio volumio[4083]: info: camilladsp service started and running in background, instance 1 Jun 17 18:34:40 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 17 18:34:40 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 17 18:34:40 volumio volumio[4083]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 17 18:34:41 volumio ntpd[803]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 18:34:41 volumio ntpd[803]: Soliciting pool server 82.209.210.87 Jun 17 18:34:41 volumio ntpd[803]: Soliciting pool server 194.158.196.172 Jun 17 18:34:41 volumio ntpd[803]: Soliciting pool server 2a03:9120:0:1:221:5aff:fedd:7ad4 Jun 17 18:34:41 volumio ntpd[803]: Soliciting pool server 2a0c:b1c2::c Jun 17 18:34:42 volumio ntpd[803]: Soliciting pool server 2a03:9120:0:1:221:5aff:fedd:7ad4 Jun 17 18:34:42 volumio ntpd[803]: Soliciting pool server 2a0c:b1c2::c Jun 17 18:34:44 volumio volumio[4083]: info: Discovery: adding 5601819d-7602-48a9-8989-1e21419c6fe4 Jun 17 18:34:44 volumio volumio[4083]: info: Discovery: Found device Volumio Jun 17 18:34:44 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:44 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:44 volumio volumio[4083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 17 18:34:44 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:44 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:44 volumio volumio[4083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 17 18:34:44 volumio volumio[4083]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 17 18:34:44 volumio volumio[4083]: info: Discovery: this is already registered, 5601819d-7602-48a9-8989-1e21419c6fe4 Jun 17 18:34:44 volumio volumio[4083]: info: Discovery: Found device Volumio Jun 17 18:34:44 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:44 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:44 volumio volumio[4083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jun 17 18:34:44 volumio volumio[4083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 17 18:34:44 volumio volumio[4083]: info: CoreCommandRouter::volumioGetState Jun 17 18:34:44 volumio volumio[4083]: info: CorePlayQueue::getTrack 0 Jun 17 18:34:44 volumio volumio[4083]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 18:34:44 volumio volumio[4083]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Jun 17 18:34:44 volumio volumio[4083]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jun 17 18:34:44 volumio volumio[4083]: errno: -3007, Jun 17 18:34:44 volumio volumio[4083]: code: 'ENOTFOUND', Jun 17 18:34:44 volumio volumio[4083]: syscall: 'getaddrinfo', Jun 17 18:34:44 volumio volumio[4083]: hostname: 'ws.audioscrobbler.com' Jun 17 18:34:44 volumio volumio[4083]: } Jun 17 18:34:44 volumio volumio[4083]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 18:34:45 volumio sudo[4584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 18:33 Jun 17 18:34:45 volumio sudo[4584]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"