Dec 09 17:44:09 volumio su[6337]: pam_unix(su-l:auth): authentication failure; logname=volumio uid=1000 euid=0 tty=/dev/tty1 ruser=volumio rhost= user=root Dec 09 17:44:11 volumio su[6337]: FAILED SU (to root) volumio on tty1 Dec 09 17:44:24 volumio login[1272]: pam_unix(login:session): session closed for user volumio Dec 09 17:44:24 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 09 17:44:24 volumio systemd[1]: Started getty@tty6.service - Getty on tty6. Dec 09 17:44:24 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Dec 09 17:44:24 volumio systemd[1]: session-1.scope: Deactivated successfully. Dec 09 17:44:24 volumio systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. Dec 09 17:44:24 volumio systemd-logind[822]: Session 1 logged out. Waiting for processes to exit. Dec 09 17:44:24 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Dec 09 17:44:24 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 09 17:44:24 volumio systemd-logind[822]: Removed session 1. Dec 09 17:44:25 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 09 17:44:25 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 09 17:44:34 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Dec 09 17:44:34 volumio systemd[2177]: Activating special unit exit.target... Dec 09 17:44:34 volumio systemd[2177]: Stopped target default.target - Main User Target. Dec 09 17:44:34 volumio systemd[2177]: Stopped target basic.target - Basic System. Dec 09 17:44:34 volumio systemd[2177]: Stopped target paths.target - Paths. Dec 09 17:44:34 volumio systemd[2177]: Stopped target sockets.target - Sockets. Dec 09 17:44:34 volumio systemd[2177]: Stopped target timers.target - Timers. Dec 09 17:44:34 volumio systemd[2177]: Closed dirmngr.socket - GnuPG network certificate management daemon. Dec 09 17:44:34 volumio systemd[2177]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 09 17:44:34 volumio systemd[2177]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 09 17:44:34 volumio systemd[2177]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 09 17:44:34 volumio systemd[2177]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 09 17:44:34 volumio systemd[2177]: Removed slice app.slice - User Application Slice. Dec 09 17:44:34 volumio systemd[2177]: Reached target shutdown.target - Shutdown. Dec 09 17:44:34 volumio systemd[2177]: Finished systemd-exit.service - Exit the Session. Dec 09 17:44:34 volumio systemd[2177]: Reached target exit.target - Exit the Session. Dec 09 17:44:34 volumio systemd[1]: user@1000.service: Deactivated successfully. Dec 09 17:44:34 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Dec 09 17:44:34 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 09 17:44:34 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Dec 09 17:44:34 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Dec 09 17:44:34 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 09 17:44:34 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Dec 09 17:44:49 volumio sudo[6525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 17:44:49 volumio sudo[6525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 17:44:49 volumio sudo[6525]: pam_unix(sudo:session): session closed for user root Dec 09 17:44:49 volumio sudo[6527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 17:44:49 volumio sudo[6527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 17:44:49 volumio sudo[6527]: pam_unix(sudo:session): session closed for user root Dec 09 17:44:49 volumio volumio[5803]: verbose: New Socket.io Connection to 192.168.2.15 from 192.168.2.25 UA: Mozilla/5.0 (Linux; U; Android 15; zh-cn; 2210132C Build/AQ3A.240912.001) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.7049.79 Mobile Safari/537.36 XiaoMi/MiuiBrowser/20.7.61205 Engine version: 3 Transport: polling Total Clients: 5 Dec 09 17:44:50 volumio sudo[6533]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 17:44:50 volumio sudo[6533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 17:44:50 volumio sudo[6533]: pam_unix(sudo:session): session closed for user root Dec 09 17:44:50 volumio sudo[6535]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 17:44:50 volumio sudo[6535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 17:44:50 volumio sudo[6535]: pam_unix(sudo:session): session closed for user root Dec 09 17:44:50 volumio volumio[5803]: verbose: New Socket.io Connection to 192.168.2.15 from 192.168.2.25 UA: Mozilla/5.0 (Linux; U; Android 15; zh-cn; 2210132C Build/AQ3A.240912.001) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.7049.79 Mobile Safari/537.36 XiaoMi/MiuiBrowser/20.7.61205 Engine version: 3 Transport: polling Total Clients: 6 Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::volumioGetState Dec 09 17:44:50 volumio volumio[5803]: info: CorePlayQueue::getTrack 1 Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 17:44:50 volumio volumio[5803]: info: Received Get System Info Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 17:44:50 volumio volumio[5803]: info: Discovery: Getting this device information Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::volumioGetState Dec 09 17:44:50 volumio volumio[5803]: info: CorePlayQueue::getTrack 1 Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::volumioGetState Dec 09 17:44:50 volumio volumio[5803]: info: CorePlayQueue::getTrack 1 Dec 09 17:44:50 volumio volumio[5803]: info: Listing playlists Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 17:44:50 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 17:44:51 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 09 17:44:51 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 17:44:51 volumio volumio[5803]: info: Received Get System Info Dec 09 17:44:51 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 17:44:51 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 17:44:51 volumio volumio[5803]: info: Discovery: Getting this device information Dec 09 17:44:51 volumio volumio[5803]: info: CoreCommandRouter::volumioGetState Dec 09 17:44:51 volumio volumio[5803]: info: CorePlayQueue::getTrack 1 Dec 09 17:44:51 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioNext Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::next Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::stop Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::stPlaybackTimer Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::updateTrackBlock Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrackBlock Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 1 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::serviceStop Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 1 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::serviceStop Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::stop Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand stop took 7 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::play index undefined Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::startPlaybackTimer Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3 Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::updateTrackBlock Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrackBlock Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 3 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand stop took 2 milliseconds Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces system playlist update Dec 09 17:44:58 volumio volumio[5803]: info: Ignoring MPD Status Update Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand clear took 6 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 4 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 3 milliseconds Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseTrackInfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3" Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2002年的第一场雪","artist":"刀郎","album":"2002年的第一场雪","uri":"USB/KINGSTON/热歌榜/2002年的第一场雪 - 刀郎.mp3","trackType":"mp3"} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService stop Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus stop Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: No code Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService stop Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus stop Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: No code Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService stop Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus stop Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: No code Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 75ms Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 69ms Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 69ms Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces system playlist update Dec 09 17:44:58 volumio volumio[5803]: info: Ignoring MPD Status Update Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces system playlist update Dec 09 17:44:58 volumio volumio[5803]: info: Ignoring MPD Status Update Dec 09 17:44:58 volumio volumio[5803]: error: updateQueue error: null Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces system playlist update Dec 09 17:44:58 volumio volumio[5803]: info: Ignoring MPD Status Update Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 70ms Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand add "USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3" took 67 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 3ms Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 3ms Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand play Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces system playlist update Dec 09 17:44:58 volumio volumio[5803]: info: Ignoring MPD Status Update Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces system playlist update Dec 09 17:44:58 volumio volumio[5803]: info: Ignoring MPD Status Update Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 28ms Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand play took 27 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 26ms Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 26ms Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 4 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 4 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 2 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 1 milliseconds Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: Dec 09 17:44:58 volumio volumio[5803]: ---------------------------- MPD announces state update: player Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::getState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand status Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 2 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand status took 1 milliseconds Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseTrackInfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseTrackInfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseTrackInfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseTrackInfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseState Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":475,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"冲动的惩罚","artist":"刀郎","album":"2002年的第一场雪","uri":"USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3","trackType":"mp3"} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService play Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus stop Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":475,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"冲动的惩罚","artist":"刀郎","album":"2002年的第一场雪","uri":"USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3","trackType":"mp3"} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService play Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus play Dec 09 17:44:58 volumio volumio[5803]: info: Received an update from plugin. extracting info from payload Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":475,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"冲动的惩罚","artist":"刀郎","album":"2002年的第一场雪","uri":"USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3","trackType":"mp3"} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService play Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus play Dec 09 17:44:58 volumio volumio[5803]: info: Received an update from plugin. extracting info from payload Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":475,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"冲动的惩罚","artist":"刀郎","album":"2002年的第一场雪","uri":"USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3","trackType":"mp3"} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService play Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus play Dec 09 17:44:58 volumio volumio[5803]: info: Received an update from plugin. extracting info from payload Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 26ms Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 51ms Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 50ms Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 49ms Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand playlistinfo took 42 milliseconds Dec 09 17:44:58 volumio volumio[5803]: info: sendMpdCommand playlistinfo took 42 milliseconds Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseTrackInfo Dec 09 17:44:58 volumio volumio[5803]: verbose: ControllerMpd::parseTrackInfo Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":475,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"冲动的惩罚","artist":"刀郎","album":"2002年的第一场雪","uri":"USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3","trackType":"mp3"} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService play Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus play Dec 09 17:44:58 volumio volumio[5803]: info: Received an update from plugin. extracting info from payload Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: ControllerMpd::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::servicePushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":475,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"冲动的惩罚","artist":"刀郎","album":"2002年的第一场雪","uri":"USB/KINGSTON/热歌榜/冲动的惩罚 - 刀郎.mp3","trackType":"mp3"} Dec 09 17:44:58 volumio volumio[5803]: verbose: CURRENT POSITION 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState stateService play Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::syncState currentStatus play Dec 09 17:44:58 volumio volumio[5803]: info: Received an update from plugin. extracting info from payload Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: CoreStateMachine::pushState Dec 09 17:44:58 volumio volumio[5803]: info: CorePlayQueue::getTrack 2 Dec 09 17:44:58 volumio volumio[5803]: info: CoreCommandRouter::volumioPushState Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 79ms Dec 09 17:44:58 volumio volumio[5803]: info: ------------------------------ 79ms Dec 09 17:45:00 volumio volumio[5803]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 09 17:45:10 volumio volumio[5803]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/2002%E5%B9%B4%E7%9A%84%E7%AC%AC%E4%B8%80%E5%9C%BA%E9%9B%AA/1c8e51c6-5c41-420b-a326-4b2b3ba1309a.jpg' Dec 09 17:45:24 volumio volumio[5803]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 17:45:24 volumio volumio[5803]: Error: connect ETIMEDOUT 128.242.240.125:443 Dec 09 17:45:24 volumio volumio[5803]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 09 17:45:24 volumio volumio[5803]: errno: -110, Dec 09 17:45:24 volumio volumio[5803]: code: 'ETIMEDOUT', Dec 09 17:45:24 volumio volumio[5803]: syscall: 'connect', Dec 09 17:45:24 volumio volumio[5803]: address: '128.242.240.125', Dec 09 17:45:24 volumio volumio[5803]: port: 443 Dec 09 17:45:24 volumio volumio[5803]: } Dec 09 17:45:24 volumio volumio[5803]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 17:45:25 volumio sudo[6686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-09 17:44' Dec 09 17:45:25 volumio sudo[6686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"