-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Fri 2026-04-03 22:16:04 CST. -- Apr 03 22:15:35 volumio nmbd[681]: [2026/04/03 22:15:35.337974, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 03 22:15:35 volumio nmbd[681]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.4 for name WORKGROUP<1d>. Apr 03 22:15:35 volumio nmbd[681]: This response was from IP 192.168.2.2, reporting an IP address of 192.168.2.2. Apr 03 22:15:41 volumio volumio[894]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 03 22:15:41 volumio volumio[894]: info: Preparing to save Alsa Options, stopping services first Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::volumioPause Apr 03 22:15:41 volumio volumio[894]: info: CoreStateMachine::pause Apr 03 22:15:41 volumio volumio[894]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"SPDIF"}} Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 22:15:41 volumio volumio[894]: info: Found match in Cards Database: setting mixer for card SPDIF Apr 03 22:15:41 volumio volumio[894]: info: Device 1 does not have any Mixer Control Available Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 03 22:15:41 volumio volumio[894]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 03 22:15:41 volumio volumio[894]: info: Disabling external Volume Control Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 22:15:41 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:41 volumio volumio[894]: info: Preparing to generate the ALSA configuration file Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:15:41 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:15:41 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 03 22:15:41 volumio volumio[894]: info: Asound.conf file unchanged, so no further update is needed Apr 03 22:15:41 volumio volumio[894]: info: Output device has changed, restarting MPD Apr 03 22:15:41 volumio volumio[894]: info: Output device has changed, restarting Shairport Sync Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 22:15:41 volumio sudo[17088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 22:15:41 volumio sudo[17088]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:41 volumio sudo[17090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 22:15:41 volumio sudo[17088]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:41 volumio sudo[17090]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:41 volumio volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:41 volumio systemd[1]: Stopping Music Player Daemon... Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 03 22:15:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:41 volumio volumio[894]: info: MPD Permissions set Apr 03 22:15:41 volumio volumio[894]: info: Starting Shairport Sync Apr 03 22:15:41 volumio sudo[17114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 22:15:41 volumio sudo[17114]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:41 volumio systemd[1]: mpd.service: Succeeded. Apr 03 22:15:41 volumio systemd[1]: Stopped Music Player Daemon. Apr 03 22:15:41 volumio systemd[1]: Starting Music Player Daemon... Apr 03 22:15:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 22:15:41 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 03 22:15:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 22:15:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 22:15:41 volumio sudo[17114]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:41 volumio volumio[894]: info: Shairport-Sync Started Apr 03 22:15:41 volumio sudo[17120]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 03 22:15:41 volumio sudo[17120]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:41 volumio sudo[17120]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:42 volumio volumio[894]: error: Upnp client error: Error: This socket has been ended by the other party Apr 03 22:15:42 volumio mpd[17136]: Apr 03 22:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 03 22:15:42 volumio systemd[1]: Started Music Player Daemon. Apr 03 22:15:42 volumio sudo[17090]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:42 volumio volumio[894]: error: updateQueue error: null Apr 03 22:15:45 volumio volumio[894]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 03 22:15:45 volumio volumio[894]: info: CoreStateMachine::getcurrentVolume Apr 03 22:15:45 volumio volumio[894]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 22:15:45 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:15:45 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:45 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:15:45 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:15:45 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:15:49 volumio volumio[894]: info: Clearing queue after UPNP request Apr 03 22:15:49 volumio volumio[894]: info: CoreStateMachine::ClearQueue Apr 03 22:15:49 volumio volumio[894]: info: CoreStateMachine::stop Apr 03 22:15:49 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 22:15:49 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock Apr 03 22:15:49 volumio volumio[894]: info: CorePlayQueue::getTrackBlock Apr 03 22:15:49 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer Apr 03 22:15:49 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:15:49 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Apr 03 22:15:49 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 22:15:49 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:49 volumio volumio[894]: info: CoreStateMachine::serviceStop Apr 03 22:15:49 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Apr 03 22:15:49 volumio volumio[894]: info: ControllerMpd::stop Apr 03 22:15:49 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 22:15:49 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue Apr 03 22:15:49 volumio volumio[894]: info: CorePlayQueue::saveQueue Apr 03 22:15:49 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:49 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue Apr 03 22:15:49 volumio volumio[894]: info: Apr 03 22:15:49 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:15:49 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:15:49 volumio volumio[894]: info: sendMpdCommand stop took 9 milliseconds Apr 03 22:15:49 volumio volumio[894]: error: updateQueue error: null Apr 03 22:15:49 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:15:49 volumio volumio[894]: info: Starting UPNP Playback Apr 03 22:15:49 volumio volumio[894]: info: Preparing playback through UPNP Apr 03 22:15:49 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Apr 03 22:15:49 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Apr 03 22:15:49 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 22:15:49 volumio volumio[894]: info: Apr 03 22:15:49 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:15:49 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:15:49 volumio volumio[894]: info: Apr 03 22:15:49 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:15:49 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:15:49 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:15:49 volumio volumio[894]: info: Apr 03 22:15:49 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:15:49 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:15:49 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:15:49 volumio volumio[894]: info: ------------------------------ 3ms Apr 03 22:15:49 volumio volumio[894]: info: Apr 03 22:15:49 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:15:49 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:15:49 volumio volumio[894]: info: Apr 03 22:15:49 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:15:49 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:15:49 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:15:49 volumio volumio[894]: info: Apr 03 22:15:49 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:15:49 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:15:49 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:15:49 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:15:50 volumio volumio[894]: info: Apr 03 22:15:50 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:15:50 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:15:50 volumio volumio[894]: info: Apr 03 22:15:50 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:15:50 volumio volumio[894]: info: sendMpdCommand status took 4 milliseconds Apr 03 22:15:50 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:15:50 volumio volumio[894]: info: sendMpdCommand status took 1 milliseconds Apr 03 22:15:50 volumio volumio[894]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Apr 03 22:15:50 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:15:50 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:15:50 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"醒不来的梦(释怀版)","artist":"洋澜一 (Performer)","album":null,"uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:15:50 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Apr 03 22:15:50 volumio volumio[894]: info: ------------------------------ 7ms Apr 03 22:15:50 volumio volumio[894]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Apr 03 22:15:50 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:15:50 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:15:50 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"醒不来的梦(释怀版)","artist":"洋澜一 (Performer)","album":null,"uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:15:50 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play Apr 03 22:15:50 volumio volumio[894]: info: Received an update from plugin. extracting info from payload Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:50 volumio volumio[894]: info: ------------------------------ 12ms Apr 03 22:15:50 volumio volumio[894]: info: Apr 03 22:15:50 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:15:50 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:15:50 volumio volumio[894]: info: Apr 03 22:15:50 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:15:50 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:15:50 volumio volumio[894]: info: ------------------------------ 6ms Apr 03 22:15:50 volumio volumio[894]: info: sendMpdCommand status took 5 milliseconds Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:15:50 volumio volumio[894]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 03 22:15:50 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Apr 03 22:15:50 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:15:50 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:15:50 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"醒不来的梦","artist":"洋澜一","album":"醒不来的梦 (释怀版)","uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:15:50 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play Apr 03 22:15:50 volumio volumio[894]: info: Received an update from plugin. extracting info from payload Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:50 volumio volumio[894]: info: ------------------------------ 15ms Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:15:50 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:15:50 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:15:50 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:15:50 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:15:57 volumio volumio[894]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 03 22:15:57 volumio volumio[894]: info: Preparing to save Alsa Options, stopping services first Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::volumioPause Apr 03 22:15:57 volumio volumio[894]: info: CoreStateMachine::pause Apr 03 22:15:57 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer Apr 03 22:15:57 volumio volumio[894]: info: CoreStateMachine::servicePause Apr 03 22:15:57 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Apr 03 22:15:57 volumio volumio[894]: info: Error: no service or no trackblock to pause Apr 03 22:15:57 volumio volumio[894]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI"}} Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 22:15:57 volumio volumio[894]: info: Found match in Cards Database: setting mixer for card HDMI Apr 03 22:15:57 volumio volumio[894]: info: Device 0 does not have any Mixer Control Available Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 03 22:15:57 volumio volumio[894]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 03 22:15:57 volumio volumio[894]: info: Disabling external Volume Control Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 22:15:57 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:15:57 volumio volumio[894]: info: Preparing to generate the ALSA configuration file Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:15:57 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:15:57 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 03 22:15:57 volumio volumio[894]: info: Asound.conf file written Apr 03 22:15:57 volumio sudo[17276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 03 22:15:57 volumio sudo[17276]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:57 volumio sudo[17276]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:57 volumio volumio[894]: info: Output device has changed, restarting MPD Apr 03 22:15:57 volumio volumio[894]: info: Output device has changed, restarting Shairport Sync Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 22:15:57 volumio sudo[17288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 22:15:57 volumio sudo[17290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 22:15:57 volumio sudo[17288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:57 volumio sudo[17288]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:57 volumio sudo[17290]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:57 volumio volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 22:15:57 volumio volumio[894]: info: Output device has changed, restarting MPD Apr 03 22:15:57 volumio systemd[1]: Stopping Music Player Daemon... Apr 03 22:15:57 volumio volumio[894]: info: Output device has changed, restarting Shairport Sync Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 22:15:57 volumio sudo[17317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 22:15:57 volumio sudo[17320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 22:15:57 volumio sudo[17317]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:58 volumio sudo[17317]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:58 volumio volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 22:15:58 volumio sudo[17320]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:58 volumio volumio[894]: info: MPD Permissions set Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio systemd[1]: mpd.service: Succeeded. Apr 03 22:15:58 volumio systemd[1]: Stopped Music Player Daemon. Apr 03 22:15:58 volumio systemd[1]: Starting Music Player Daemon... Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 03 22:15:58 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 03 22:15:58 volumio systemd[1]: mpd.service: Succeeded. Apr 03 22:15:58 volumio systemd[1]: Stopped Music Player Daemon. Apr 03 22:15:58 volumio systemd[1]: Starting Music Player Daemon... Apr 03 22:15:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 22:15:58 volumio volumio[894]: info: MPD Permissions set Apr 03 22:15:58 volumio volumio[894]: info: Starting Shairport Sync Apr 03 22:15:58 volumio volumio[894]: info: Starting Shairport Sync Apr 03 22:15:58 volumio sudo[17359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 22:15:58 volumio sudo[17359]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:58 volumio sudo[17361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 22:15:58 volumio sudo[17361]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 22:15:58 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 03 22:15:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 22:15:58 volumio sudo[17356]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 03 22:15:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 22:15:58 volumio sudo[17359]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:58 volumio sudo[17356]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 22:15:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 22:15:58 volumio sudo[17356]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:58 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 03 22:15:58 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 03 22:15:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 22:15:58 volumio volumio[894]: info: Shairport-Sync Started Apr 03 22:15:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 22:15:58 volumio sudo[17361]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:58 volumio volumio[894]: info: Shairport-Sync Started Apr 03 22:15:59 volumio mpd[17389]: Apr 03 22:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 03 22:15:59 volumio systemd[1]: Started Music Player Daemon. Apr 03 22:15:59 volumio sudo[17320]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:59 volumio sudo[17290]: pam_unix(sudo:session): session closed for user root Apr 03 22:15:59 volumio volumio[894]: error: MPD error: The expression evaluated to a falsy value: Apr 03 22:15:59 volumio volumio[894]: assert.ok(self.idling) Apr 03 22:15:59 volumio volumio[894]: error: The expression evaluated to a falsy value: Apr 03 22:15:59 volumio volumio[894]: assert.ok(self.idling) Apr 03 22:15:59 volumio volumio[894]: error: updateQueue error: null Apr 03 22:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 03 22:16:01 volumio volumio[894]: info: CoreStateMachine::getcurrentVolume Apr 03 22:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 22:16:01 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:01 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:01 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:02 volumio volumio[894]: info: Clearing queue after UPNP request Apr 03 22:16:02 volumio volumio[894]: info: CoreStateMachine::ClearQueue Apr 03 22:16:02 volumio volumio[894]: info: CoreStateMachine::stop Apr 03 22:16:02 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 22:16:02 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock Apr 03 22:16:02 volumio volumio[894]: info: CorePlayQueue::getTrackBlock Apr 03 22:16:02 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer Apr 03 22:16:02 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:02 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Apr 03 22:16:02 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 22:16:02 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:02 volumio volumio[894]: info: CoreStateMachine::serviceStop Apr 03 22:16:02 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Apr 03 22:16:02 volumio volumio[894]: info: ControllerMpd::stop Apr 03 22:16:02 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 22:16:02 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue Apr 03 22:16:02 volumio volumio[894]: info: CorePlayQueue::saveQueue Apr 03 22:16:02 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:02 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue Apr 03 22:16:02 volumio volumio[894]: info: Apr 03 22:16:02 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:02 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:02 volumio volumio[894]: info: sendMpdCommand stop took 9 milliseconds Apr 03 22:16:02 volumio volumio[894]: info: Apr 03 22:16:02 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:02 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:02 volumio volumio[894]: error: updateQueue error: null Apr 03 22:16:02 volumio volumio[894]: info: ------------------------------ 5ms Apr 03 22:16:02 volumio volumio[894]: info: ------------------------------ 2ms Apr 03 22:16:02 volumio volumio[894]: info: Starting UPNP Playback Apr 03 22:16:02 volumio volumio[894]: info: Preparing playback through UPNP Apr 03 22:16:02 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Apr 03 22:16:02 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Apr 03 22:16:02 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 3ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 2ms Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 2ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 2ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 2ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 1ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 2ms Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand status took 5 milliseconds Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand status took 2 milliseconds Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:16:03 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:16:03 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"醒不来的梦(释怀版)","artist":"洋澜一 (Performer)","album":null,"uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:16:03 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:16:03 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:16:03 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"醒不来的梦(释怀版)","artist":"洋澜一 (Performer)","album":null,"uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:16:03 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play Apr 03 22:16:03 volumio volumio[894]: info: Received an update from plugin. extracting info from payload Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 18ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 20ms Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces system playlist update Apr 03 22:16:03 volumio volumio[894]: info: Ignoring MPD Status Update Apr 03 22:16:03 volumio volumio[894]: info: Apr 03 22:16:03 volumio volumio[894]: ---------------------------- MPD announces state update: player Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::getState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 16ms Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand status took 15 milliseconds Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 6ms Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand status took 5 milliseconds Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseState Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:16:03 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:16:03 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"醒不来的梦","artist":"洋澜一","album":"醒不来的梦 (释怀版)","uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:16:03 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play Apr 03 22:16:03 volumio volumio[894]: info: Received an update from plugin. extracting info from payload Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:16:03 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:16:03 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"醒不来的梦","artist":"洋澜一","album":"醒不来的梦 (释怀版)","uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:16:03 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play Apr 03 22:16:03 volumio volumio[894]: info: Received an update from plugin. extracting info from payload Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 46ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 42ms Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 03 22:16:03 volumio volumio[894]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 03 22:16:03 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:16:03 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:16:03 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"醒不来的梦","artist":"洋澜一","album":"醒不来的梦 (释怀版)","uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:16:03 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play Apr 03 22:16:03 volumio volumio[894]: info: Received an update from plugin. extracting info from payload Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: ControllerMpd::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::servicePushState Apr 03 22:16:03 volumio volumio[894]: verbose: In UPNP mode Apr 03 22:16:03 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"醒不来的梦","artist":"洋澜一","album":"醒不来的梦 (释怀版)","uri":"http://er.sycdn.kuwo.cn/a72f680dbc754a01bc8ba347efdd424f/69cfcb94/resource/30106/trackmedia/M500001Wm6kw1K8VWQ.mp3","trackType":"mp3"} Apr 03 22:16:03 volumio volumio[894]: verbose: CURRENT POSITION 0 Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState stateService play Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play Apr 03 22:16:03 volumio volumio[894]: info: Received an update from plugin. extracting info from payload Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::pushState Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 70ms Apr 03 22:16:03 volumio volumio[894]: info: ------------------------------ 61ms Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Apr 03 22:16:03 volumio volumio[894]: info: CoreStateMachine::getQueue Apr 03 22:16:03 volumio volumio[894]: info: CorePlayQueue::getQueue Apr 03 22:16:03 volumio volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 22:16:03 volumio volumio[894]: Error: connect ETIMEDOUT 199.59.150.39:443 Apr 03 22:16:03 volumio volumio[894]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 03 22:16:03 volumio volumio[894]: errno: -110, Apr 03 22:16:03 volumio volumio[894]: code: 'ETIMEDOUT', Apr 03 22:16:03 volumio volumio[894]: syscall: 'connect', Apr 03 22:16:03 volumio volumio[894]: address: '199.59.150.39', Apr 03 22:16:03 volumio volumio[894]: port: 443 Apr 03 22:16:03 volumio volumio[894]: } Apr 03 22:16:03 volumio volumio[894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 22:16:04 volumio sudo[17439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-03 22:15 Apr 03 22:16:04 volumio sudo[17439]: 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 12:25:22 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="cf5455596c5328b0cbc691c04ff0184a"