-- Logs begin at Thu 2025-06-12 16:57:36 CST, end at Thu 2025-06-12 17:01:18 CST. -- Jun 12 17:00:00 volumio-x86 mpd[3608]: Jun 12 17:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 17:00:00 volumio-x86 systemd[1]: Started Music Player Daemon. Jun 12 17:00:00 volumio-x86 sudo[3580]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:00 volumio-x86 sudo[3590]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:00 volumio-x86 volumio[2284]: error: MPD error: The expression evaluated to a falsy value: Jun 12 17:00:00 volumio-x86 volumio[2284]: assert.ok(self.idling) Jun 12 17:00:00 volumio-x86 volumio[2284]: error: The expression evaluated to a falsy value: Jun 12 17:00:00 volumio-x86 volumio[2284]: assert.ok(self.idling) Jun 12 17:00:00 volumio-x86 volumio[2284]: error: updateQueue error: null Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreStateMachine::getcurrentVolume Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioRetrievevolume Jun 12 17:00:02 volumio-x86 volumio[2284]: info: VolumeController:: Volume=22 Mute =false Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreStateMachine::pushState Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioPushState Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreStateMachine::updateTrackBlock Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrackBlock Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioRetrievevolume Jun 12 17:00:02 volumio-x86 volumio[2284]: info: VolumeController:: Volume=22 Mute =false Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreStateMachine::pushState Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:00:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioPushState Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jun 12 17:00:03 volumio-x86 sudo[3641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 17:00:03 volumio-x86 sudo[3641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:03 volumio-x86 sudo[3641]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:03 volumio-x86 volumio[2284]: info: MPD Permissions set Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 sudo[3644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 17:00:03 volumio-x86 sudo[3644]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:03 volumio-x86 systemd[1]: Stopping Music Player Daemon... Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 17:00:03 volumio-x86 systemd[1]: mpd.service: Succeeded. Jun 12 17:00:03 volumio-x86 systemd[1]: Stopped Music Player Daemon. Jun 12 17:00:03 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:03 volumio-x86 systemd[1]: Starting Music Player Daemon... Jun 12 17:00:03 volumio-x86 sudo[3651]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 17:00:03 volumio-x86 sudo[3651]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:03 volumio-x86 sudo[3651]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:04 volumio-x86 mpd[3654]: Jun 12 17:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 17:00:04 volumio-x86 systemd[1]: Started Music Player Daemon. Jun 12 17:00:04 volumio-x86 sudo[3644]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:04 volumio-x86 volumio[2284]: error: updateQueue error: null Jun 12 17:00:09 volumio-x86 volumio[2284]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 12 17:00:09 volumio-x86 volumio[2284]: info: Retrying Login Due to Network Error Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jun 12 17:00:11 volumio-x86 sudo[3661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 17:00:11 volumio-x86 sudo[3661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:11 volumio-x86 sudo[3661]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:11 volumio-x86 sudo[3664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 17:00:11 volumio-x86 volumio[2284]: info: MPD Permissions set Jun 12 17:00:11 volumio-x86 sudo[3664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:11 volumio-x86 systemd[1]: Stopping Music Player Daemon... Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 17:00:11 volumio-x86 systemd[1]: mpd.service: Succeeded. Jun 12 17:00:11 volumio-x86 systemd[1]: Stopped Music Player Daemon. Jun 12 17:00:11 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:11 volumio-x86 systemd[1]: Starting Music Player Daemon... Jun 12 17:00:11 volumio-x86 sudo[3678]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 17:00:11 volumio-x86 sudo[3678]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:11 volumio-x86 sudo[3678]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:12 volumio-x86 mpd[3688]: Jun 12 17:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 17:00:12 volumio-x86 systemd[1]: Started Music Player Daemon. Jun 12 17:00:12 volumio-x86 sudo[3664]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:12 volumio-x86 volumio[2284]: error: updateQueue error: null Jun 12 17:00:19 volumio-x86 volumio[2284]: info: MyVolumio login type: Token Jun 12 17:00:19 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioGetState Jun 12 17:00:19 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jun 12 17:00:21 volumio-x86 sudo[3702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 17:00:21 volumio-x86 sudo[3702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:21 volumio-x86 sudo[3702]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:21 volumio-x86 volumio[2284]: info: MPD Permissions set Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 sudo[3705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 17:00:21 volumio-x86 sudo[3705]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 17:00:21 volumio-x86 systemd[1]: Stopping Music Player Daemon... Jun 12 17:00:21 volumio-x86 systemd[1]: mpd.service: Succeeded. Jun 12 17:00:21 volumio-x86 systemd[1]: Stopped Music Player Daemon. Jun 12 17:00:21 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:00:21 volumio-x86 systemd[1]: Starting Music Player Daemon... Jun 12 17:00:21 volumio-x86 sudo[3713]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 17:00:21 volumio-x86 sudo[3713]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:00:21 volumio-x86 sudo[3713]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:22 volumio-x86 mpd[3715]: Jun 12 17:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 17:00:22 volumio-x86 systemd[1]: Started Music Player Daemon. Jun 12 17:00:22 volumio-x86 sudo[3705]: pam_unix(sudo:session): session closed for user root Jun 12 17:00:22 volumio-x86 volumio[2284]: error: updateQueue error: null Jun 12 17:00:41 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioGetState Jun 12 17:00:41 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:00:44 volumio-x86 kernel: EXT4-fs (mmcblk0p3): Delayed block allocation failed for inode 1233305 at logical offset 24 with max blocks 4 with error 117 Jun 12 17:00:44 volumio-x86 kernel: EXT4-fs (mmcblk0p3): This should not happen!! Data will be lost Jun 12 17:00:49 volumio-x86 volumio[2284]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 12 17:00:49 volumio-x86 volumio[2284]: info: Retrying Login Due to Network Error Jun 12 17:00:55 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioPlay Jun 12 17:00:55 volumio-x86 volumio[2284]: info: CoreStateMachine::play index undefined Jun 12 17:00:55 volumio-x86 volumio[2284]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 17:00:55 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:00:55 volumio-x86 volumio[2284]: info: CoreStateMachine::startPlaybackTimer Jun 12 17:00:55 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:00:55 volumio-x86 volumio[2284]: info: ControllerMpd::resume Jun 12 17:00:55 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand play Jun 12 17:00:55 volumio-x86 volumio[2284]: info: sendMpdCommand play took 2 milliseconds Jun 12 17:00:59 volumio-x86 volumio[2284]: info: MyVolumio login type: Token Jun 12 17:01:00 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioPlay Jun 12 17:01:00 volumio-x86 volumio[2284]: info: CoreStateMachine::play index undefined Jun 12 17:01:00 volumio-x86 volumio[2284]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 17:01:00 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:00 volumio-x86 volumio[2284]: info: CoreStateMachine::startPlaybackTimer Jun 12 17:01:00 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:00 volumio-x86 volumio[2284]: info: ControllerMpd::resume Jun 12 17:01:00 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand play Jun 12 17:01:00 volumio-x86 volumio[2284]: info: sendMpdCommand play took 2 milliseconds Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioNext Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::next Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::stop Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::updateTrackBlock Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrackBlock Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::stPlaybackTimer Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::pushState Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioPushState Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::serviceStop Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreCommandRouter::serviceStop Jun 12 17:01:01 volumio-x86 volumio[2284]: info: ControllerMpd::stop Jun 12 17:01:01 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 17:01:01 volumio-x86 volumio[2284]: info: sendMpdCommand stop took 28 milliseconds Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::play index undefined Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 1 Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::startPlaybackTimer Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 1 Jun 12 17:01:01 volumio-x86 volumio[2284]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/Love,Sandy/林忆莲-为你我受冷风吹.flac Jun 12 17:01:01 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CoreStateMachine::updateTrackBlock Jun 12 17:01:01 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrackBlock Jun 12 17:01:01 volumio-x86 volumio[2284]: info: sendMpdCommand stop took 2 milliseconds Jun 12 17:01:01 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand clear Jun 12 17:01:01 volumio-x86 volumio[2284]: info: Jun 12 17:01:01 volumio-x86 volumio[2284]: ---------------------------- MPD announces system playlist update Jun 12 17:01:01 volumio-x86 volumio[2284]: info: Ignoring MPD Status Update Jun 12 17:01:01 volumio-x86 volumio[2284]: info: sendMpdCommand clear took 2 milliseconds Jun 12 17:01:01 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/Love,Sandy/林忆莲-为你我受冷风吹.flac" Jun 12 17:01:01 volumio-x86 volumio[2284]: error: updateQueue error: null Jun 12 17:01:01 volumio-x86 volumio[2284]: info: Jun 12 17:01:01 volumio-x86 volumio[2284]: ---------------------------- MPD announces system playlist update Jun 12 17:01:01 volumio-x86 volumio[2284]: info: Ignoring MPD Status Update Jun 12 17:01:01 volumio-x86 volumio[2284]: info: ------------------------------ 5ms Jun 12 17:01:01 volumio-x86 volumio[2284]: info: sendMpdCommand add "INTERNAL/musics/Love,Sandy/林忆莲-为你我受冷风吹.flac" took 3 milliseconds Jun 12 17:01:01 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand play Jun 12 17:01:01 volumio-x86 volumio[2284]: info: ------------------------------ 4ms Jun 12 17:01:01 volumio-x86 volumio[2284]: info: sendMpdCommand play took 3 milliseconds Jun 12 17:01:01 volumio-x86 volumio[2284]: info: Jun 12 17:01:01 volumio-x86 volumio[2284]: ---------------------------- MPD announces state update: player Jun 12 17:01:01 volumio-x86 volumio[2284]: info: ControllerMpd::getState Jun 12 17:01:01 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand status Jun 12 17:01:01 volumio-x86 kernel: intel_sst_acpi 808622A8:00: FW Version 01.0b.02.02 Jun 12 17:01:02 volumio-x86 volumio[2284]: info: Jun 12 17:01:02 volumio-x86 volumio[2284]: ---------------------------- MPD announces state update: player Jun 12 17:01:02 volumio-x86 volumio[2284]: info: sendMpdCommand status took 238 milliseconds Jun 12 17:01:02 volumio-x86 volumio[2284]: info: ControllerMpd::getState Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand status Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: ControllerMpd::parseState Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 17:01:02 volumio-x86 volumio[2284]: info: sendMpdCommand status took 2 milliseconds Jun 12 17:01:02 volumio-x86 volumio[2284]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: ControllerMpd::parseState Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: ControllerMpd::parseTrackInfo Jun 12 17:01:02 volumio-x86 volumio[2284]: info: ControllerMpd::pushState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::servicePushState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 1 Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"671 Kbps","isStreaming":false,"title":"为你我受冷风吹","artist":"林忆莲","album":"Love,Sandy","uri":"INTERNAL/musics/Love,Sandy/林忆莲-为你我受冷风吹.flac","trackType":"flac"} Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: CURRENT POSITION 1 Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreStateMachine::syncState stateService play Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreStateMachine::syncState currentStatus stop Jun 12 17:01:02 volumio-x86 volumio[2284]: info: ------------------------------ 246ms Jun 12 17:01:02 volumio-x86 volumio[2284]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: ControllerMpd::parseTrackInfo Jun 12 17:01:02 volumio-x86 volumio[2284]: info: ControllerMpd::pushState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::servicePushState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 1 Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"712 Kbps","isStreaming":false,"title":"为你我受冷风吹","artist":"林忆莲","album":"Love,Sandy","uri":"INTERNAL/musics/Love,Sandy/林忆莲-为你我受冷风吹.flac","trackType":"flac"} Jun 12 17:01:02 volumio-x86 volumio[2284]: verbose: CURRENT POSITION 1 Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreStateMachine::syncState stateService play Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreStateMachine::syncState currentStatus play Jun 12 17:01:02 volumio-x86 volumio[2284]: info: Received an update from plugin. extracting info from payload Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreStateMachine::pushState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 1 Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioPushState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreStateMachine::pushState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 1 Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioPushState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: ------------------------------ 37ms Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:02 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 1 Jun 12 17:01:02 volumio-x86 sudo[3866]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jun 12 17:01:02 volumio-x86 sudo[3866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:05 volumio-x86 volumio[2284]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 12 17:01:05 volumio-x86 volumio[2284]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:05 volumio-x86 volumio[2284]: info: CorePlayQueue::getTrack 1 Jun 12 17:01:07 volumio-x86 volumio[2284]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 17:01:07 volumio-x86 volumio[2284]: Error: connect ETIMEDOUT 52.58.1.161:80 Jun 12 17:01:07 volumio-x86 volumio[2284]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 12 17:01:07 volumio-x86 volumio[2284]: errno: -110, Jun 12 17:01:07 volumio-x86 volumio[2284]: code: 'ETIMEDOUT', Jun 12 17:01:07 volumio-x86 volumio[2284]: syscall: 'connect', Jun 12 17:01:07 volumio-x86 volumio[2284]: address: '52.58.1.161', Jun 12 17:01:07 volumio-x86 volumio[2284]: port: 80 Jun 12 17:01:07 volumio-x86 volumio[2284]: } Jun 12 17:01:07 volumio-x86 volumio[2284]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 17:01:07 volumio-x86 sudo[3909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 17:00 Jun 12 17:01:07 volumio-x86 sudo[3909]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:07 volumio-x86 sudo[3909]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:08 volumio-x86 volumio-remote-updater[772]: [2025-06-12 17:01:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 12 17:01:08 volumio-x86 volumio-remote-updater[772]: [2025-06-12 17:01:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 12 17:01:08 volumio-x86 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 12 17:01:08 volumio-x86 systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 12 17:01:08 volumio-x86 systemd[1]: Started dynamicswap service. Jun 12 17:01:08 volumio-x86 systemd[1]: dynamicswap.service: Succeeded. Jun 12 17:01:08 volumio-x86 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 12 17:01:08 volumio-x86 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 12 17:01:08 volumio-x86 systemd[1]: Stopped Volumio Backend Module. Jun 12 17:01:08 volumio-x86 systemd[1]: Started Volumio Backend Module. Jun 12 17:01:08 volumio-x86 systemd[1]: Started dynamicswap service. Jun 12 17:01:09 volumio-x86 systemd[1]: dynamicswap.service: Succeeded. Jun 12 17:01:10 volumio-x86 volumio[3924]: info: ------------------------------------------- Jun 12 17:01:10 volumio-x86 volumio[3924]: info: ----- Volumio3 ---- Jun 12 17:01:10 volumio-x86 volumio[3924]: info: ------------------------------------------- Jun 12 17:01:10 volumio-x86 volumio[3924]: info: ----- System startup ---- Jun 12 17:01:10 volumio-x86 volumio[3924]: info: ------------------------------------------- Jun 12 17:01:10 volumio-x86 volumio[3924]: info: MYVOLUMIO Environment detected Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Plugin folders cleanup Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning into folder /volumio/app/plugins/ Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category audio_interface Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category miscellanea Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category music_service Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category plugins.json Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category system_controller Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category user_interface Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning into folder /data/plugins/ Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category music_service Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category system_controller Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Scanning category user_interface Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Plugin folders cleanup completed Jun 12 17:01:10 volumio-x86 volumio[3924]: info: ------------------------------------------- Jun 12 17:01:10 volumio-x86 volumio[3924]: info: ----- Core plugins startup ---- Jun 12 17:01:10 volumio-x86 volumio[3924]: info: ------------------------------------------- Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Loading plugins from folder /volumio/app/plugins/ Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Adding plugin upnp to MyMusic Plugins Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Loading plugins from folder /data/plugins/ Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Loading plugin "system"... Jun 12 17:01:10 volumio-x86 volumio[3924]: info: Loading plugin "appearance"... Jun 12 17:01:11 volumio-x86 volumio[3924]: info: Loading plugin "network"... Jun 12 17:01:11 volumio-x86 volumio[3924]: info: Refreshing Cached IP Addresses Jun 12 17:01:11 volumio-x86 sudo[3951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 17:01:11 volumio-x86 sudo[3951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:11 volumio-x86 sudo[3951]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:11 volumio-x86 sudo[3953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 17:01:11 volumio-x86 sudo[3953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:11 volumio-x86 sudo[3953]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:11 volumio-x86 volumio[3924]: info: Loading plugin "services"... Jun 12 17:01:11 volumio-x86 volumio[3924]: info: Loading plugin "alsa_controller"... Jun 12 17:01:11 volumio-x86 sudo[3966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 12 17:01:11 volumio-x86 sudo[3966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:11 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 17:01:11 volumio-x86 volumio[3924]: info: Loading plugin "wizard"... Jun 12 17:01:11 volumio-x86 volumio[3924]: info: Loading plugin "networkfs"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Starting Udev Watcher for removable devices Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Ignoring mount for partition: boot Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Ignoring mount for partition: volumio Jun 12 17:01:12 volumio-x86 sudo[3979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Ignoring mount for partition: volumio_data Jun 12 17:01:12 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 12 17:01:12 volumio-x86 sudo[3979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "volumio_command_line_client"... Jun 12 17:01:12 volumio-x86 sudo[3979]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:12 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Jun 12 17:01:12 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 12 17:01:12 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "upnp"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: [1749718872079] Starting Upmpd Daemon Jun 12 17:01:12 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "my_music"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "mpd"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "upnp_browser"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "alarm-clock"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "airplay_emulation"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Starting Shairport Sync Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "last_100"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "webradio"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "i2s_dacs"... Jun 12 17:01:12 volumio-x86 volumio[3924]: info: I2S DAC not set, start Auto-detection Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "volumiodiscovery"... Jun 12 17:01:12 volumio-x86 volumio[3924]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 12 17:01:12 volumio-x86 volumio[3924]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 12 17:01:12 volumio-x86 volumio[3924]: *** WARNING *** For more information see Jun 12 17:01:12 volumio-x86 volumio[3924]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 12 17:01:12 volumio-x86 node[3924]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 12 17:01:12 volumio-x86 volumio[3924]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 12 17:01:12 volumio-x86 volumio[3924]: *** WARNING *** For more information see Jun 12 17:01:12 volumio-x86 node[3924]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 12 17:01:12 volumio-x86 node[3924]: *** WARNING *** For more information see Jun 12 17:01:12 volumio-x86 node[3924]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 12 17:01:12 volumio-x86 node[3924]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 12 17:01:12 volumio-x86 node[3924]: *** WARNING *** For more information see Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Discovery: Started advertising with name: Volumio-x86 Jun 12 17:01:12 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 12 17:01:12 volumio-x86 volumio[3924]: info: Loading plugin "jellyfin"... Jun 12 17:01:13 volumio-x86 volumio[3924]: info: Loading plugin "autostart"... Jun 12 17:01:13 volumio-x86 volumio[3924]: info: Applying required configuration parameters for plugin autostart Jun 12 17:01:13 volumio-x86 volumio[3924]: info: AutoStart - onVolumioStart - read config.json Jun 12 17:01:13 volumio-x86 volumio[3924]: info: Loading plugin "peppy_screensaver"... Jun 12 17:01:13 volumio-x86 volumio-remote-updater[772]: [2025-06-12 17:01:13] [connect] Successful connection Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Applying required configuration parameters for plugin peppy_screensaver Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Loading plugin "outputs"... Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Loading plugin "albumart"... Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Plugin example_plugin is not enabled Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Loading plugin "inputs"... Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Loading plugin "updater_comm"... Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Plugin mpdemulation is not enabled Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Loading plugin "rest_api"... Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Loading plugin "websocket"... Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Starting Socket.io Server version 2.3.0 Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Plugin RoonBridge is not enabled Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Loading plugin "Systeminfo"... Jun 12 17:01:14 volumio-x86 volumio[3924]: Forking 3 albumart workers Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Loading i18n strings for locale zh Jun 12 17:01:14 volumio-x86 volumio[3924]: Updating browse sources language Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::initPlayerControls Jun 12 17:01:14 volumio-x86 volumio[3924]: Starting albumart workers Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 17:01:14 volumio-x86 volumio[3924]: Express server listening on port 3000 Jun 12 17:01:14 volumio-x86 volumio[3924]: [Metrics] WebUI: 5s 178.81ms Jun 12 17:01:14 volumio-x86 volumio[3924]: info: Setting Device type: x86 Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreStateMachine::resetVolumioState Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreStateMachine::getcurrentVolume Jun 12 17:01:14 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioRetrievevolume Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:01:15 volumio-x86 volumio[3924]: Starting albumart workers Jun 12 17:01:15 volumio-x86 volumio[3924]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Completed loading Core Plugins Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Preparing to generate the ALSA configuration file Jun 12 17:01:15 volumio-x86 volumio[3924]: Starting albumart workers Jun 12 17:01:15 volumio-x86 sudo[3966]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Volumio Network Manager: Network status updated: 3 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 12 17:01:15 volumio-x86 volumio[3924]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Reading ALSA contributions from plugins. Jun 12 17:01:15 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 12 17:01:15 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 12 17:01:15 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.123 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 12 17:01:15 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.123 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 12 17:01:15 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 12 17:01:15 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.123 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 12 17:01:15 volumio-x86 volumio-remote-updater[772]: [2025-06-12 17:01:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749718873 101 Jun 12 17:01:15 volumio-x86 volumio[3924]: 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: 7 Jun 12 17:01:15 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.123 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 12 17:01:15 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.123 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 12 17:01:15 volumio-x86 volumio[3924]: 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 12 17:01:15 volumio-x86 volumio[3924]: info: Reloading queue from file Jun 12 17:01:15 volumio-x86 volumio[3924]: info: VolumeController:: Volume=22 Mute =false Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreStateMachine::pushState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioPushState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreStateMachine::updateTrackBlock Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrackBlock Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioRetrievevolume Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreStateMachine::setRepeat true single undefined Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreStateMachine::pushState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioPushState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreStateMachine::setRandom true Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreStateMachine::pushState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioPushState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Received Get System Info Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Discovery: Getting this device information Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Received Get System Info Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Discovery: Getting this device information Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Listing playlists Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetQueue Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreStateMachine::getQueue Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getQueue Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Discovery: adding c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Discovery: Found device Volumio-x86 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: VolumeController:: Volume=22 Mute =false Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreStateMachine::pushState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:15 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioPushState Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Discovery: this is already registered, c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a Jun 12 17:01:15 volumio-x86 volumio[3924]: info: Discovery: Found device Volumio-x86 Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Asound.conf file unchanged, so no further update is needed Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Output device has changed, restarting MPD Jun 12 17:01:16 volumio-x86 sudo[4068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 17:01:16 volumio-x86 sudo[4068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Output device has changed, restarting Shairport Sync Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:01:16 volumio-x86 sudo[4068]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:16 volumio-x86 sudo[4071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 17:01:16 volumio-x86 sudo[4071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:16 volumio-x86 systemd[1]: Stopping Music Player Daemon... Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 17:01:16 volumio-x86 volumio[3924]: info: ___________ START PLUGINS ___________ Jun 12 17:01:16 volumio-x86 volumio[3924]: info: ControllerMpd::onStart: Initializing MPD Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Creating MPD Configuration file Jun 12 17:01:16 volumio-x86 sudo[4077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 17:01:16 volumio-x86 sudo[4077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 12 17:01:16 volumio-x86 sudo[4077]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:16 volumio-x86 volumio[3924]: info: [1749718876151] CoreMusicLibrary::Adding element 媒体服务器 Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 17:01:16 volumio-x86 sudo[4080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 17:01:16 volumio-x86 sudo[4080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 17:01:16 volumio-x86 systemd[1]: mpd.service: Succeeded. Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:01:16 volumio-x86 systemd[1]: Stopped Music Player Daemon. Jun 12 17:01:16 volumio-x86 systemd[1]: Starting Music Player Daemon... Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 12 17:01:16 volumio-x86 volumio[3924]: info: [1749718876281] CoreMusicLibrary::Adding element Last_100 Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 12 17:01:16 volumio-x86 volumio[3924]: info: [1749718876285] CoreMusicLibrary::Adding element Webradio Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Initializing BBC Radios Jun 12 17:01:16 volumio-x86 sudo[4086]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 17:01:16 volumio-x86 sudo[4086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:16 volumio-x86 sudo[4086]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Discovery: Getting this device information Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 12 17:01:16 volumio-x86 volumio[3924]: info: [1749718876386] CoreMusicLibrary::Adding element Jellyfin Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 17:01:16 volumio-x86 volumio[3924]: Cannot find translation for source Jellyfin Jun 12 17:01:16 volumio-x86 volumio[3924]: info: [jellyfin] Initialized plugin with device info: {"id":"c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a","host":"http://192.168.3.24","name":"Volumio-x86","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":22,"mute":false,"artist":"林忆莲","track":"不爱的理由","albumart":"http://192.168.3.24/albumart?cacheid=373&web=%E6%9E%97%E5%BF%86%E8%8E%B2/Love%2CSandy/extralarge&path=%2FINTERNAL%2Fmusics%2FLove%2CSandy&metadata=false"}} Jun 12 17:01:16 volumio-x86 volumio[3924]: info: AutoStart - onStart Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Loading i18n strings for locale zh Jun 12 17:01:16 volumio-x86 volumio[3924]: info: /tmp/myfifo created Jun 12 17:01:16 volumio-x86 volumio[3924]: info: /tmp/myfifosa created Jun 12 17:01:16 volumio-x86 sudo[4107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jun 12 17:01:16 volumio-x86 sudo[4107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:16 volumio-x86 sudo[4107]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:16 volumio-x86 volumio[3924]: info: snd-dummy loaded Jun 12 17:01:16 volumio-x86 volumio[3924]: error: Plugin peppy_screensaver failed to start! Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jun 12 17:01:16 volumio-x86 volumio[3924]: info: Volumio Calling Home Jun 12 17:01:16 volumio-x86 volumio[3924]: info: MPD Permissions set Jun 12 17:01:16 volumio-x86 volumio[3924]: info: MPD Permissions set Jun 12 17:01:16 volumio-x86 volumio[3924]: 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 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 17:01:16 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 17:01:17 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Starting Shairport Sync Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Starting Shairport Sync Jun 12 17:01:17 volumio-x86 sudo[4128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 17:01:17 volumio-x86 sudo[4128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Starting Shairport Sync Jun 12 17:01:17 volumio-x86 sudo[4131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 17:01:17 volumio-x86 sudo[4131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:17 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 12 17:01:17 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Jun 12 17:01:17 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:17 volumio-x86 sudo[4134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 17:01:17 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 12 17:01:17 volumio-x86 sudo[4131]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:17 volumio-x86 sudo[4134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 17:01:17 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.24 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 12 17:01:17 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.24 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jun 12 17:01:17 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.24 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Jun 12 17:01:17 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.24 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Jun 12 17:01:17 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 12 17:01:17 volumio-x86 volumio[3924]: verbose: New Socket.io Connection to 192.168.3.24 from 192.168.3.42 UA: Mozilla/5.0 (Linux; Android 15; 2304FPN6DC Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Jun 12 17:01:17 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 12 17:01:17 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Jun 12 17:01:17 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 17:01:17 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 12 17:01:17 volumio-x86 sudo[4128]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Volumio called home Jun 12 17:01:17 volumio-x86 sudo[4134]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Received Get System Info Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Discovery: Getting this device information Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CoreCommandRouter::volumioGetState Jun 12 17:01:17 volumio-x86 volumio[3924]: info: CorePlayQueue::getTrack 0 Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Listing playlists Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Shairport-Sync Started Jun 12 17:01:17 volumio-x86 volumio[3924]: Error adding Membership: Error: addMembership EINVAL Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Shairport-Sync Started Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Shairport-Sync Started Jun 12 17:01:17 volumio-x86 mpd[4101]: Jun 12 17:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 17:01:17 volumio-x86 systemd[1]: Started Music Player Daemon. Jun 12 17:01:17 volumio-x86 sudo[4080]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:17 volumio-x86 sudo[4071]: pam_unix(sudo:session): session closed for user root Jun 12 17:01:17 volumio-x86 volumio[3924]: info: Completed starting Core Plugins Jun 12 17:01:17 volumio-x86 volumio[3924]: info: ------------------------------------------- Jun 12 17:01:17 volumio-x86 volumio[3924]: info: ----- MyVolumio plugins startup ---- Jun 12 17:01:17 volumio-x86 volumio[3924]: info: ------------------------------------------- Jun 12 17:01:17 volumio-x86 volumio[3924]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 12 17:01:17 volumio-x86 volumio[3924]: info: MPD running with PID4101 Jun 12 17:01:17 volumio-x86 volumio[3924]: ,establishing connection Jun 12 17:01:17 volumio-x86 volumio[3924]: error: MPD error: The expression evaluated to a falsy value: Jun 12 17:01:17 volumio-x86 volumio[3924]: assert.ok(self.idling) Jun 12 17:01:17 volumio-x86 volumio[3924]: error: The expression evaluated to a falsy value: Jun 12 17:01:17 volumio-x86 volumio[3924]: assert.ok(self.idling) Jun 12 17:01:17 volumio-x86 volumio[3924]: error: MPD error: The expression evaluated to a falsy value: Jun 12 17:01:17 volumio-x86 volumio[3924]: assert.ok(self.idling) Jun 12 17:01:17 volumio-x86 volumio[3924]: error: The expression evaluated to a falsy value: Jun 12 17:01:17 volumio-x86 volumio[3924]: assert.ok(self.idling) Jun 12 17:01:17 volumio-x86 volumio[3924]: error: updateQueue error: null Jun 12 17:01:17 volumio-x86 volumio[3924]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 17:01:17 volumio-x86 volumio[3924]: Error: connect ECONNREFUSED 47.88.58.234:443 Jun 12 17:01:17 volumio-x86 volumio[3924]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 12 17:01:17 volumio-x86 volumio[3924]: errno: -111, Jun 12 17:01:17 volumio-x86 volumio[3924]: code: 'ECONNREFUSED', Jun 12 17:01:17 volumio-x86 volumio[3924]: syscall: 'connect', Jun 12 17:01:17 volumio-x86 volumio[3924]: address: '47.88.58.234', Jun 12 17:01:17 volumio-x86 volumio[3924]: port: 443 Jun 12 17:01:17 volumio-x86 volumio[3924]: } Jun 12 17:01:17 volumio-x86 volumio[3924]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 17:01:18 volumio-x86 sudo[4154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 17:00 Jun 12 17:01:18 volumio-x86 sudo[4154]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"