-- Logs begin at Sun 2025-05-18 16:55:19 CST, end at Sun 2025-05-18 17:16:24 CST. -- May 18 17:15:27 volumio volumio[4134]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 18 17:15:27 volumio volumio[4134]: info: Retrying Login Due to Network Error May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 18 17:15:34 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 18 17:15:37 volumio volumio[4134]: info: MyVolumio login type: Token May 18 17:15:38 volumio volumio[4134]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 18 17:15:38 volumio volumio[4134]: info: Preparing to save Alsa Options, stopping services first May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::volumioGetState May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::volumioPause May 18 17:15:38 volumio volumio[4134]: info: CoreStateMachine::pause May 18 17:15:38 volumio volumio[4134]: info: CoreStateMachine::stPlaybackTimer May 18 17:15:38 volumio volumio[4134]: info: CoreStateMachine::servicePause May 18 17:15:38 volumio volumio[4134]: info: CorePlayQueue::getTrack 0 May 18 17:15:38 volumio volumio[4134]: info: Error: no service or no trackblock to pause May 18 17:15:38 volumio volumio[4134]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"}} May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 17:15:38 volumio volumio[4134]: info: Setting mixer PCM for card Combo384 Amanero May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 18 17:15:38 volumio volumio[4134]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 18 17:15:38 volumio volumio[4134]: info: Disabling external Volume Control May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 17:15:38 volumio volumio[4134]: info: Preparing to generate the ALSA configuration file May 18 17:15:38 volumio volumio[4134]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 18 17:15:38 volumio volumio[4134]: info: Reading ALSA contributions from plugins. May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 18 17:15:38 volumio volumio[4134]: info: VolumeController:: Volume=0 Mute =false May 18 17:15:38 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:38 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:38 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:38 volumio volumio[4134]: info: Asound.conf file written May 18 17:15:38 volumio sudo[8908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 18 17:15:38 volumio sudo[8908]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 17:15:38 volumio sudo[8908]: pam_unix(sudo:session): session closed for user root May 18 17:15:38 volumio volumio[4134]: info: Output device has changed, restarting MPD May 18 17:15:38 volumio sudo[8914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 17:15:38 volumio sudo[8914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 17:15:38 volumio sudo[8914]: pam_unix(sudo:session): session closed for user root May 18 17:15:38 volumio volumio[4134]: info: Output device has changed, restarting Shairport Sync May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 17:15:38 volumio sudo[8917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 17:15:38 volumio sudo[8917]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 17:15:38 volumio systemd[1]: Stopping Music Player Daemon... May 18 17:15:38 volumio volumio[4134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 17:15:38 volumio volumio[4134]: info: Output device has changed, restarting MPD May 18 17:15:38 volumio systemd[1]: mpd.service: Succeeded. May 18 17:15:38 volumio systemd[1]: Stopped Music Player Daemon. May 18 17:15:38 volumio sudo[8923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 17:15:38 volumio sudo[8923]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 17:15:38 volumio volumio[4134]: info: Output device has changed, restarting Shairport Sync May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 17:15:38 volumio sudo[8923]: pam_unix(sudo:session): session closed for user root May 18 17:15:38 volumio sudo[8927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 17:15:38 volumio sudo[8927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 17:15:38 volumio volumio[4134]: info: MPD Permissions set May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio systemd[1]: Starting Music Player Daemon... May 18 17:15:38 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 18 17:15:38 volumio systemd[1]: mpd.service: Succeeded. May 18 17:15:38 volumio systemd[1]: Stopped Music Player Daemon. May 18 17:15:38 volumio systemd[1]: Starting Music Player Daemon... May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 17:15:38 volumio sudo[8935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 18 17:15:38 volumio sudo[8935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 17:15:38 volumio sudo[8935]: pam_unix(sudo:session): session closed for user root May 18 17:15:38 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 17:15:38 volumio volumio[4134]: info: Starting Shairport Sync May 18 17:15:38 volumio volumio[4134]: info: Starting Shairport Sync May 18 17:15:38 volumio sudo[8950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 17:15:38 volumio sudo[8950]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 17:15:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 17:15:38 volumio systemd[1]: shairport-sync.service: Succeeded. May 18 17:15:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 18 17:15:38 volumio sudo[8953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 17:15:38 volumio sudo[8953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 17:15:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 17:15:38 volumio sudo[8950]: pam_unix(sudo:session): session closed for user root May 18 17:15:38 volumio volumio[4134]: info: Shairport-Sync Started May 18 17:15:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 17:15:38 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 18 17:15:38 volumio systemd[1]: shairport-sync.service: Succeeded. May 18 17:15:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 18 17:15:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 17:15:38 volumio sudo[8953]: pam_unix(sudo:session): session closed for user root May 18 17:15:38 volumio volumio[4134]: info: Shairport-Sync Started May 18 17:15:38 volumio mpd[8947]: May 18 17:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 18 17:15:38 volumio systemd[1]: Started Music Player Daemon. May 18 17:15:38 volumio sudo[8917]: pam_unix(sudo:session): session closed for user root May 18 17:15:38 volumio sudo[8927]: pam_unix(sudo:session): session closed for user root May 18 17:15:38 volumio volumio[4134]: error: MPD error: The expression evaluated to a falsy value: May 18 17:15:38 volumio volumio[4134]: assert.ok(self.idling) May 18 17:15:38 volumio volumio[4134]: error: The expression evaluated to a falsy value: May 18 17:15:38 volumio volumio[4134]: assert.ok(self.idling) May 18 17:15:38 volumio volumio[4134]: error: updateQueue error: null May 18 17:15:38 volumio volumio[4134]: error: Upnp client error: Error: This socket has been ended by the other party May 18 17:15:42 volumio volumio[4134]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 18 17:15:42 volumio volumio[4134]: info: CoreStateMachine::getcurrentVolume May 18 17:15:42 volumio volumio[4134]: info: CoreCommandRouter::volumioRetrievevolume May 18 17:15:42 volumio volumio[4134]: info: VolumeController:: Volume=0 Mute =false May 18 17:15:42 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:42 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:42 volumio volumio[4134]: info: CoreStateMachine::updateTrackBlock May 18 17:15:42 volumio volumio[4134]: info: CorePlayQueue::getTrackBlock May 18 17:15:42 volumio volumio[4134]: info: CoreCommandRouter::volumioRetrievevolume May 18 17:15:42 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:42 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:42 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:42 volumio volumio[4134]: info: VolumeController:: Volume=0 Mute =false May 18 17:15:42 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:42 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:42 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:42 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:42 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:43 volumio volumio[4134]: info: Clearing queue after UPNP request May 18 17:15:43 volumio volumio[4134]: info: CoreStateMachine::ClearQueue May 18 17:15:43 volumio volumio[4134]: info: CoreStateMachine::stop May 18 17:15:43 volumio volumio[4134]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 17:15:43 volumio volumio[4134]: info: CoreStateMachine::updateTrackBlock May 18 17:15:43 volumio volumio[4134]: info: CorePlayQueue::getTrackBlock May 18 17:15:43 volumio volumio[4134]: info: CoreStateMachine::stPlaybackTimer May 18 17:15:43 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:43 volumio volumio[4134]: info: CorePlayQueue::getTrack 0 May 18 17:15:43 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 17:15:43 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:43 volumio volumio[4134]: info: CoreStateMachine::serviceStop May 18 17:15:43 volumio volumio[4134]: info: CorePlayQueue::getTrack 0 May 18 17:15:43 volumio volumio[4134]: info: ControllerMpd::stop May 18 17:15:43 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand stop May 18 17:15:43 volumio volumio[4134]: info: CorePlayQueue::clearPlayQueue May 18 17:15:43 volumio volumio[4134]: info: CorePlayQueue::saveQueue May 18 17:15:43 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:43 volumio volumio[4134]: info: CoreCommandRouter::volumioPushQueue May 18 17:15:43 volumio volumio[4134]: info: May 18 17:15:43 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:43 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:43 volumio volumio[4134]: info: sendMpdCommand stop took 13 milliseconds May 18 17:15:43 volumio volumio[4134]: error: updateQueue error: null May 18 17:15:43 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:43 volumio volumio[4134]: info: May 18 17:15:43 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:43 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:43 volumio volumio[4134]: error: updateQueue error: null May 18 17:15:43 volumio volumio[4134]: info: ------------------------------ 0ms May 18 17:15:43 volumio volumio[4134]: info: Starting UPNP Playback May 18 17:15:43 volumio volumio[4134]: info: Preparing playback through UPNP May 18 17:15:43 volumio volumio[4134]: info: CoreCommandRouter::volumioGetState May 18 17:15:43 volumio volumio[4134]: info: CorePlayQueue::getTrack 0 May 18 17:15:43 volumio volumio[4134]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 0ms May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 0ms May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 0ms May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 0ms May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:44 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:44 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:44 volumio volumio[4134]: info: sendMpdCommand status took 9 milliseconds May 18 17:15:44 volumio volumio[4134]: info: sendMpdCommand status took 8 milliseconds May 18 17:15:44 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:44 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:44 volumio volumio[4134]: info: May 18 17:15:44 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:44 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 3ms May 18 17:15:44 volumio volumio[4134]: info: sendMpdCommand status took 2 milliseconds May 18 17:15:44 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 17:15:44 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 1 milliseconds May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:44 volumio volumio[4134]: info: sendMpdCommand status took 1 milliseconds May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:44 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:44 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:44 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"破碎的梦","artist":"张梦弘","album":"破碎的梦","uri":"http://fsandroid.kugou.com/202505181715/979ef9a3c68631caf93651c36517480b/v3/5c53158689efe9a71ea3bd9a81658e68/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx552440522_quviper_tape_s492818067.mp3","trackType":"mp3"} May 18 17:15:44 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus stop May 18 17:15:44 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:44 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:44 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"破碎的梦","artist":"张梦弘","album":"破碎的梦","uri":"http://fsandroid.kugou.com/202505181715/979ef9a3c68631caf93651c36517480b/v3/5c53158689efe9a71ea3bd9a81658e68/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx552440522_quviper_tape_s492818067.mp3","trackType":"mp3"} May 18 17:15:44 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:44 volumio volumio[4134]: info: Received an update from plugin. extracting info from payload May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 19ms May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 25ms May 18 17:15:44 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 13 milliseconds May 18 17:15:44 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 13 milliseconds May 18 17:15:44 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:44 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:44 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:44 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"破碎的梦","artist":"张梦弘","album":"破碎的梦","uri":"http://fsandroid.kugou.com/202505181715/979ef9a3c68631caf93651c36517480b/v3/5c53158689efe9a71ea3bd9a81658e68/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx552440522_quviper_tape_s492818067.mp3","trackType":"mp3"} May 18 17:15:44 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:44 volumio volumio[4134]: info: Received an update from plugin. extracting info from payload May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:44 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:44 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:44 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"破碎的梦","artist":"张梦弘","album":"破碎的梦","uri":"http://fsandroid.kugou.com/202505181715/979ef9a3c68631caf93651c36517480b/v3/5c53158689efe9a71ea3bd9a81658e68/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx552440522_quviper_tape_s492818067.mp3","trackType":"mp3"} May 18 17:15:44 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:44 volumio volumio[4134]: info: Received an update from plugin. extracting info from payload May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 44ms May 18 17:15:44 volumio volumio[4134]: info: ------------------------------ 45ms May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:44 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:44 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:44 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:44 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:44 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:44 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:44 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:44 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:48 volumio volumio[4134]: info: CoreCommandRouter::volumioGetState May 18 17:15:55 volumio volumio[4134]: info: May 18 17:15:55 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:55 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:55 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:55 volumio volumio[4134]: info: May 18 17:15:55 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:55 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:55 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:55 volumio volumio[4134]: info: sendMpdCommand status took 1 milliseconds May 18 17:15:55 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:55 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:55 volumio volumio[4134]: info: sendMpdCommand status took 3 milliseconds May 18 17:15:55 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 17:15:55 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:55 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:55 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:55 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:55 volumio volumio[4134]: info: CorePlayQueue::getTrack 0 May 18 17:15:55 volumio volumio[4134]: 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":"破碎的梦","artist":"张梦弘","album":"破碎的梦","uri":"http://fsandroid.kugou.com/202505181715/979ef9a3c68631caf93651c36517480b/v3/5c53158689efe9a71ea3bd9a81658e68/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx552440522_quviper_tape_s492818067.mp3","trackType":"mp3"} May 18 17:15:55 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::syncState stateService stop May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::stPlaybackTimer May 18 17:15:55 volumio volumio[4134]: info: ------------------------------ 14ms May 18 17:15:55 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 10 milliseconds May 18 17:15:55 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:55 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:55 volumio volumio[4134]: info: CorePlayQueue::getTrack 0 May 18 17:15:55 volumio volumio[4134]: 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":"破碎的梦","artist":"张梦弘","album":"破碎的梦","uri":"http://fsandroid.kugou.com/202505181715/979ef9a3c68631caf93651c36517480b/v3/5c53158689efe9a71ea3bd9a81658e68/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx552440522_quviper_tape_s492818067.mp3","trackType":"mp3"} May 18 17:15:55 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::syncState stateService stop May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus stop May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:55 volumio volumio[4134]: info: No code May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:55 volumio volumio[4134]: info: ------------------------------ 29ms May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioGetQueue May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::getQueue May 18 17:15:55 volumio volumio[4134]: info: CorePlayQueue::getQueue May 18 17:15:55 volumio volumio[4134]: info: Clearing queue after UPNP request May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::ClearQueue May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::stop May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 17:15:55 volumio volumio[4134]: info: CorePlayQueue::clearPlayQueue May 18 17:15:55 volumio volumio[4134]: info: CorePlayQueue::saveQueue May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioPushQueue May 18 17:15:55 volumio volumio[4134]: info: May 18 17:15:55 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:55 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:55 volumio volumio[4134]: info: May 18 17:15:55 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:55 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:55 volumio volumio[4134]: error: updateQueue error: null May 18 17:15:55 volumio volumio[4134]: error: updateQueue error: null May 18 17:15:55 volumio volumio[4134]: info: ------------------------------ 0ms May 18 17:15:55 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:55 volumio volumio[4134]: info: Starting UPNP Playback May 18 17:15:55 volumio volumio[4134]: info: Preparing playback through UPNP May 18 17:15:55 volumio volumio[4134]: info: CoreCommandRouter::volumioGetState May 18 17:15:55 volumio volumio[4134]: info: CorePlayQueue::getTrack 0 May 18 17:15:55 volumio volumio[4134]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 2ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 2ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 2ms May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 3ms May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 2ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 2ms May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 1ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand status took 3 milliseconds May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand status took 3 milliseconds May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 0 milliseconds May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:56 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:56 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"七元 - 逍遥叹","artist":"七元","album":null,"uri":"http://fsandroid.kugou.com/202505181715/352d89e4df9417ba37997636eeb60fe7/v3/6340cbc614b3e816ac0c0de1b273a1ed/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","trackType":"mp3"} May 18 17:15:56 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus stop May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 5ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand status took 2 milliseconds May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 1 milliseconds May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand status took 0 milliseconds May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:56 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:56 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"七元 - 逍遥叹","artist":"七元","album":null,"uri":"http://fsandroid.kugou.com/202505181715/352d89e4df9417ba37997636eeb60fe7/v3/6340cbc614b3e816ac0c0de1b273a1ed/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","trackType":"mp3"} May 18 17:15:56 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:56 volumio volumio[4134]: info: Received an update from plugin. extracting info from payload May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 20ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 14 milliseconds May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 15 milliseconds May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:56 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:56 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","artist":null,"album":null,"uri":"http://fsandroid.kugou.com/202505181715/352d89e4df9417ba37997636eeb60fe7/v3/6340cbc614b3e816ac0c0de1b273a1ed/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","trackType":"mp3"} May 18 17:15:56 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:56 volumio volumio[4134]: info: Received an update from plugin. extracting info from payload May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:56 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:56 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","artist":null,"album":null,"uri":"http://fsandroid.kugou.com/202505181715/352d89e4df9417ba37997636eeb60fe7/v3/6340cbc614b3e816ac0c0de1b273a1ed/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","trackType":"mp3"} May 18 17:15:56 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:56 volumio volumio[4134]: info: Received an update from plugin. extracting info from payload May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 44ms May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 43ms May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces system playlist update May 18 17:15:56 volumio volumio[4134]: info: Ignoring MPD Status Update May 18 17:15:56 volumio volumio[4134]: info: May 18 17:15:56 volumio volumio[4134]: ---------------------------- MPD announces state update: player May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::getState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand status May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 36ms May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand status took 37 milliseconds May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 8ms May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand status took 9 milliseconds May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseState May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 8 milliseconds May 18 17:15:56 volumio volumio[4134]: info: sendMpdCommand playlistinfo took 8 milliseconds May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:56 volumio volumio[4134]: verbose: ControllerMpd::parseTrackInfo May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:56 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:56 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":312,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","artist":null,"album":null,"uri":"http://fsandroid.kugou.com/202505181715/352d89e4df9417ba37997636eeb60fe7/v3/6340cbc614b3e816ac0c0de1b273a1ed/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","trackType":"mp3"} May 18 17:15:56 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:56 volumio volumio[4134]: info: Received an update from plugin. extracting info from payload May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: ControllerMpd::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::servicePushState May 18 17:15:56 volumio volumio[4134]: verbose: In UPNP mode May 18 17:15:56 volumio volumio[4134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":312,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","artist":null,"album":null,"uri":"http://fsandroid.kugou.com/202505181715/352d89e4df9417ba37997636eeb60fe7/v3/6340cbc614b3e816ac0c0de1b273a1ed/yp/full/ap1005_us997964742_df1ttwxw3rd2ec2ypamh0q1itk_pi2_mx510007391_quviper_tape_s1922991869.mp3","trackType":"mp3"} May 18 17:15:56 volumio volumio[4134]: verbose: CURRENT POSITION 0 May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState stateService play May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::syncState currentStatus play May 18 17:15:56 volumio volumio[4134]: info: Received an update from plugin. extracting info from payload May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: CoreStateMachine::pushState May 18 17:15:56 volumio volumio[4134]: info: CoreCommandRouter::volumioPushState May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 76ms May 18 17:15:56 volumio volumio[4134]: info: ------------------------------ 47ms May 18 17:16:07 volumio volumio[4134]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 18 17:16:07 volumio volumio[4134]: info: Retrying Login Due to Network Error May 18 17:16:17 volumio volumio[4134]: info: MyVolumio login type: Token May 18 17:16:24 volumio volumio[4134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 17:16:24 volumio volumio[4134]: Error: connect ETIMEDOUT 199.59.149.207:443 May 18 17:16:24 volumio volumio[4134]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 18 17:16:24 volumio volumio[4134]: errno: -110, May 18 17:16:24 volumio volumio[4134]: code: 'ETIMEDOUT', May 18 17:16:24 volumio volumio[4134]: syscall: 'connect', May 18 17:16:24 volumio volumio[4134]: address: '199.59.149.207', May 18 17:16:24 volumio volumio[4134]: port: 443 May 18 17:16:24 volumio volumio[4134]: } May 18 17:16:24 volumio volumio[4134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 17:16:24 volumio sudo[9635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 17:15 May 18 17:16:24 volumio sudo[9635]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"