-- Logs begin at Sun 2024-06-02 21:45:59 CST, end at Thu 2025-12-18 16:57:43 CST. -- Dec 18 16:56:09 volumio volumio[662]: info: VolumeController::SetAlsaVolume88 Dec 18 16:56:09 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:56:09 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:09 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:09 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:34 volumio volumio[662]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:34 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 18 16:56:38 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 16:56:46 volumio volumio[662]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 18 16:56:46 volumio volumio[662]: info: Preparing to save Alsa Options, stopping services first Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::volumioPause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::servicePause Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::servicePause Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::pause Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand pause Dec 18 16:56:46 volumio volumio[662]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"C-Media USB Headphone Set"}} Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 16:56:46 volumio volumio[662]: info: Setting mixer Headphone for card C-Media USB Headphone Set Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 18 16:56:46 volumio volumio[662]: info: Updating Volume Controller Parameters: Device: 5 Name: C-Media USB Headphone Set Mixer: Headphone Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 18 16:56:46 volumio volumio[662]: info: Disabling external Volume Control Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 16:56:46 volumio volumio[662]: info: Preparing to generate the ALSA configuration file Dec 18 16:56:46 volumio volumio[662]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 18 16:56:46 volumio volumio[662]: info: Reading ALSA contributions from plugins. Dec 18 16:56:46 volumio volumio[662]: info: Dec 18 16:56:46 volumio volumio[662]: ---------------------------- MPD announces state update: player Dec 18 16:56:46 volumio volumio[662]: info: sendMpdCommand pause took 215 milliseconds Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::getState Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:46 volumio volumio[662]: info: Dec 18 16:56:46 volumio volumio[662]: ---------------------------- MPD announces state update: player Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::getState Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:46 volumio volumio[662]: info: Dec 18 16:56:46 volumio volumio[662]: ---------------------------- MPD announces state update: player Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::getState Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:46 volumio volumio[662]: info: Dec 18 16:56:46 volumio volumio[662]: ---------------------------- MPD announces state update: player Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::getState Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:46 volumio volumio[662]: info: sendMpdCommand status took 23 milliseconds Dec 18 16:56:46 volumio volumio[662]: info: sendMpdCommand status took 23 milliseconds Dec 18 16:56:46 volumio volumio[662]: info: sendMpdCommand status took 22 milliseconds Dec 18 16:56:46 volumio volumio[662]: info: sendMpdCommand status took 21 milliseconds Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::parseState Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::parseState Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::parseState Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::parseState Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:46 volumio volumio[662]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 18 16:56:46 volumio volumio[662]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 18 16:56:46 volumio volumio[662]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:46 volumio volumio[662]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::pushState Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::servicePushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":69437,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"加州旅馆","artist":"老鹰乐队","album":null,"uri":"USB/1234-5678/群星-加州旅馆.ape","trackType":"ape"} Dec 18 16:56:46 volumio volumio[662]: verbose: CURRENT POSITION 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::syncState stateService pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::syncState currentStatus pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::pushState Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::servicePushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":69437,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"加州旅馆","artist":"老鹰乐队","album":null,"uri":"USB/1234-5678/群星-加州旅馆.ape","trackType":"ape"} Dec 18 16:56:46 volumio volumio[662]: verbose: CURRENT POSITION 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::syncState stateService pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::syncState currentStatus pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::pushState Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::servicePushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":69437,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"加州旅馆","artist":"老鹰乐队","album":null,"uri":"USB/1234-5678/群星-加州旅馆.ape","trackType":"ape"} Dec 18 16:56:46 volumio volumio[662]: verbose: CURRENT POSITION 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::syncState stateService pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::syncState currentStatus pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:56:46 volumio volumio[662]: info: ControllerMpd::pushState Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::servicePushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":69437,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"加州旅馆","artist":"老鹰乐队","album":null,"uri":"USB/1234-5678/群星-加州旅馆.ape","trackType":"ape"} Dec 18 16:56:46 volumio volumio[662]: verbose: CURRENT POSITION 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::syncState stateService pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::syncState currentStatus pause Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:56:46 volumio volumio[662]: info: ------------------------------ 142ms Dec 18 16:56:46 volumio volumio[662]: info: ------------------------------ 142ms Dec 18 16:56:46 volumio volumio[662]: info: ------------------------------ 141ms Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 16:56:46 volumio volumio[662]: info: VolumeController:: Volume=88 Mute =false Dec 18 16:56:46 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:56:46 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:46 volumio volumio[662]: info: Asound.conf file unchanged, so no further update is needed Dec 18 16:56:46 volumio volumio[662]: info: Output device has changed, restarting MPD Dec 18 16:56:46 volumio sudo[3037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 16:56:46 volumio sudo[3037]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:46 volumio volumio[662]: info: Output device has changed, restarting Shairport Sync Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:56:46 volumio sudo[3037]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:46 volumio sudo[3040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 16:56:46 volumio sudo[3040]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:46 volumio systemd[1]: Stopping Music Player Daemon... Dec 18 16:56:46 volumio volumio[662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 16:56:46 volumio volumio[662]: info: MPD Permissions set Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio systemd[1]: mpd.service: Succeeded. Dec 18 16:56:46 volumio systemd[1]: Stopped Music Player Daemon. Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 16:56:46 volumio systemd[1]: Starting Music Player Daemon... Dec 18 16:56:46 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:46 volumio volumio[662]: info: Starting Shairport Sync Dec 18 16:56:46 volumio sudo[3053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 16:56:46 volumio sudo[3053]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 18 16:56:47 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 18 16:56:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 18 16:56:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 18 16:56:47 volumio sudo[3053]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:47 volumio volumio[662]: info: Shairport-Sync Started Dec 18 16:56:47 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:47 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:56:47 volumio mpd[3049]: Dec 18 16:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 16:56:47 volumio systemd[1]: Started Music Player Daemon. Dec 18 16:56:47 volumio sudo[3040]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:47 volumio volumio[662]: error: updateQueue error: null Dec 18 16:56:50 volumio volumio[662]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 18 16:56:50 volumio volumio[662]: info: CoreStateMachine::getcurrentVolume Dec 18 16:56:50 volumio volumio[662]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 16:56:50 volumio volumio[662]: info: VolumeController:: Volume=88 Mute =false Dec 18 16:56:50 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:56:50 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:50 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:50 volumio volumio[662]: info: CoreStateMachine::updateTrackBlock Dec 18 16:56:50 volumio volumio[662]: info: CorePlayQueue::getTrackBlock Dec 18 16:56:50 volumio volumio[662]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 16:56:50 volumio volumio[662]: info: VolumeController:: Volume=88 Mute =false Dec 18 16:56:50 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:56:50 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:50 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:50 volumio volumio[662]: error: Upnp client error: Error: This socket has been ended by the other party Dec 18 16:56:53 volumio volumio[662]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:53 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:58 volumio volumio[662]: info: CoreCommandRouter::volumioPlay Dec 18 16:56:58 volumio volumio[662]: info: CoreStateMachine::play index undefined Dec 18 16:56:58 volumio volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:56:58 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:58 volumio volumio[662]: info: CoreStateMachine::startPlaybackTimer Dec 18 16:56:58 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:56:58 volumio volumio[662]: info: ControllerMpd::resume Dec 18 16:56:58 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand play Dec 18 16:56:58 volumio volumio[662]: info: sendMpdCommand play took 1 milliseconds Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::volumioPlay Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::play index 7 Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::stop Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::updateTrackBlock Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrackBlock Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::serviceStop Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrack 14 Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::serviceStop Dec 18 16:57:16 volumio volumio[662]: info: ControllerMpd::stop Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand stop took 19 milliseconds Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::play index undefined Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrack 7 Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::startPlaybackTimer Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrack 7 Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::clearAddPlayTracks USB/1234-5678/杨乐婷-初恋情人.wav Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand stop took 1 milliseconds Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 16:57:16 volumio volumio[662]: info: Dec 18 16:57:16 volumio volumio[662]: ---------------------------- MPD announces system playlist update Dec 18 16:57:16 volumio volumio[662]: info: Ignoring MPD Status Update Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand clear took 1 milliseconds Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand add "USB/1234-5678/杨乐婷-初恋情人.wav" Dec 18 16:57:16 volumio volumio[662]: error: updateQueue error: null Dec 18 16:57:16 volumio volumio[662]: info: Dec 18 16:57:16 volumio volumio[662]: ---------------------------- MPD announces system playlist update Dec 18 16:57:16 volumio volumio[662]: info: Ignoring MPD Status Update Dec 18 16:57:16 volumio volumio[662]: info: ------------------------------ 2ms Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand add "USB/1234-5678/杨乐婷-初恋情人.wav" took 1 milliseconds Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand play Dec 18 16:57:16 volumio volumio[662]: info: ------------------------------ 3ms Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand play took 2 milliseconds Dec 18 16:57:16 volumio volumio[662]: info: Dec 18 16:57:16 volumio volumio[662]: ---------------------------- MPD announces state update: player Dec 18 16:57:16 volumio volumio[662]: info: ControllerMpd::getState Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:57:16 volumio volumio[662]: info: Dec 18 16:57:16 volumio volumio[662]: ---------------------------- MPD announces state update: player Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand status took 23 milliseconds Dec 18 16:57:16 volumio volumio[662]: info: ControllerMpd::getState Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::parseState Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand status took 1 milliseconds Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::parseState Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:57:16 volumio volumio[662]: info: ControllerMpd::pushState Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::servicePushState Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrack 7 Dec 18 16:57:16 volumio volumio[662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"杨乐婷-初恋情人.wav","artist":null,"album":null,"uri":"USB/1234-5678/杨乐婷-初恋情人.wav","trackType":"wav"} Dec 18 16:57:16 volumio volumio[662]: verbose: CURRENT POSITION 7 Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::syncState stateService play Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::syncState currentStatus stop Dec 18 16:57:16 volumio volumio[662]: info: ------------------------------ 33ms Dec 18 16:57:16 volumio volumio[662]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 16:57:16 volumio volumio[662]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:57:16 volumio volumio[662]: info: ControllerMpd::pushState Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::servicePushState Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrack 7 Dec 18 16:57:16 volumio volumio[662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"杨乐婷-初恋情人.wav","artist":null,"album":null,"uri":"USB/1234-5678/杨乐婷-初恋情人.wav","trackType":"wav"} Dec 18 16:57:16 volumio volumio[662]: verbose: CURRENT POSITION 7 Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::syncState stateService play Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::syncState currentStatus play Dec 18 16:57:16 volumio volumio[662]: info: Received an update from plugin. extracting info from payload Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrack 7 Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:57:16 volumio volumio[662]: info: CoreStateMachine::pushState Dec 18 16:57:16 volumio volumio[662]: info: CorePlayQueue::getTrack 7 Dec 18 16:57:16 volumio volumio[662]: info: CoreCommandRouter::volumioPushState Dec 18 16:57:16 volumio volumio[662]: info: ------------------------------ 106ms Dec 18 16:57:20 volumio volumio[662]: info: CoreCommandRouter::volumioGetState Dec 18 16:57:20 volumio volumio[662]: info: CorePlayQueue::getTrack 7 Dec 18 16:57:43 volumio volumio[662]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 16:57:43 volumio volumio[662]: Error: connect ETIMEDOUT 31.13.76.65:443 Dec 18 16:57:43 volumio volumio[662]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 18 16:57:43 volumio volumio[662]: errno: -110, Dec 18 16:57:43 volumio volumio[662]: code: 'ETIMEDOUT', Dec 18 16:57:43 volumio volumio[662]: syscall: 'connect', Dec 18 16:57:43 volumio volumio[662]: address: '31.13.76.65', Dec 18 16:57:43 volumio volumio[662]: port: 443 Dec 18 16:57:43 volumio volumio[662]: } Dec 18 16:57:43 volumio volumio[662]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 16:57:43 volumio sudo[3138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 16:56 Dec 18 16:57:43 volumio sudo[3138]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"