-- Logs begin at Sat 2025-03-01 05:30:24 UTC, end at Sat 2025-03-01 05:34:53 UTC. -- Mar 01 05:33:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 05:33:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 05:33:10 volumio-x86 volumio[1131]: info: Discovery: Getting this device information Mar 01 05:33:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioGetState Mar 01 05:33:10 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 05:33:12 volumio-x86 volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 05:33:13 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Preparing to save Alsa Options, stopping services first Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioGetState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::pause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::servicePause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ControllerMpd::pause Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand pause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SXW CTUAD384 PRM-A"}} Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Setting mixer PCM for card SXW CTUAD384 PRM-A Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Updating Volume Controller Parameters: Device: 5 Name: SXW CTUAD384 PRM-A Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Disabling external Volume Control Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Preparing to generate the ALSA configuration file Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Mar 01 05:33:16 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:16 volumio-x86 volumio[1131]: info: sendMpdCommand pause took 96 milliseconds Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Mar 01 05:33:16 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Mar 01 05:33:16 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:16 volumio-x86 volumio[1131]: info: sendMpdCommand status took 2 milliseconds Mar 01 05:33:16 volumio-x86 volumio[1131]: info: sendMpdCommand status took 2 milliseconds Mar 01 05:33:16 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:16 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 01 05:33:16 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":38566,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"Ring Ring Ring","artist":"S.H.E","album":"Forever 新歌+精选","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - Ring Ring Ring.flac","trackType":"flac"} Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService pause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus pause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":38566,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"Ring Ring Ring","artist":"S.H.E","album":"Forever 新歌+精选","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - Ring Ring Ring.flac","trackType":"flac"} Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService pause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus pause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":38566,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"Ring Ring Ring","artist":"S.H.E","album":"Forever 新歌+精选","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - Ring Ring Ring.flac","trackType":"flac"} Mar 01 05:33:16 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService pause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus pause Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ------------------------------ 29ms Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ------------------------------ 28ms Mar 01 05:33:16 volumio-x86 volumio[1131]: info: ------------------------------ 27ms Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 05:33:16 volumio-x86 volumio[1131]: info: VolumeController:: Volume=41 Mute =false Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Asound.conf file written Mar 01 05:33:16 volumio-x86 sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 01 05:33:16 volumio-x86 sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:33:16 volumio-x86 sudo[2587]: pam_unix(sudo:session): session closed for user root Mar 01 05:33:16 volumio-x86 volumio[1131]: No state is present for card PRMA Mar 01 05:33:16 volumio-x86 volumio[1131]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:f801" "" "" Mar 01 05:33:16 volumio-x86 volumio[1131]: Hardware is initialized using a generic method Mar 01 05:33:16 volumio-x86 volumio[1131]: No state is present for card PRMA Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Output device has changed, restarting MPD Mar 01 05:33:16 volumio-x86 sudo[2592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 05:33:16 volumio-x86 sudo[2592]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Output device has changed, restarting Shairport Sync Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:33:16 volumio-x86 sudo[2592]: pam_unix(sudo:session): session closed for user root Mar 01 05:33:16 volumio-x86 sudo[2595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 05:33:16 volumio-x86 sudo[2595]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:33:16 volumio-x86 systemd[1]: Stopping Music Player Daemon... Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Output device has changed, restarting MPD Mar 01 05:33:16 volumio-x86 sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 05:33:16 volumio-x86 sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Output device has changed, restarting Shairport Sync Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:33:16 volumio-x86 sudo[2601]: pam_unix(sudo:session): session closed for user root Mar 01 05:33:16 volumio-x86 sudo[2604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 05:33:16 volumio-x86 sudo[2604]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 05:33:16 volumio-x86 volumio[1131]: info: MPD Permissions set Mar 01 05:33:16 volumio-x86 volumio[1131]: info: MPD Permissions set Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 systemd[1]: mpd.service: Succeeded. Mar 01 05:33:16 volumio-x86 systemd[1]: Stopped Music Player Daemon. Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 systemd[1]: Starting Music Player Daemon... Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 05:33:16 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Starting Shairport Sync Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Starting Shairport Sync Mar 01 05:33:16 volumio-x86 sudo[2623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 05:33:16 volumio-x86 sudo[2623]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:33:16 volumio-x86 sudo[2613]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 05:33:16 volumio-x86 sudo[2613]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:33:16 volumio-x86 sudo[2613]: pam_unix(sudo:session): session closed for user root Mar 01 05:33:16 volumio-x86 sudo[2625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 05:33:16 volumio-x86 sudo[2625]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:33:16 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 05:33:16 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Mar 01 05:33:16 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 05:33:16 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 05:33:16 volumio-x86 sudo[2623]: pam_unix(sudo:session): session closed for user root Mar 01 05:33:16 volumio-x86 sudo[2625]: pam_unix(sudo:session): session closed for user root Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Shairport-Sync Started Mar 01 05:33:16 volumio-x86 volumio[1131]: info: Shairport-Sync Started Mar 01 05:33:16 volumio-x86 mpd[2629]: Mar 01 05:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 01 05:33:16 volumio-x86 systemd[1]: Started Music Player Daemon. Mar 01 05:33:16 volumio-x86 sudo[2604]: pam_unix(sudo:session): session closed for user root Mar 01 05:33:16 volumio-x86 sudo[2595]: pam_unix(sudo:session): session closed for user root Mar 01 05:33:16 volumio-x86 volumio[1131]: error: MPD error: The expression evaluated to a falsy value: Mar 01 05:33:16 volumio-x86 volumio[1131]: assert.ok(self.idling) Mar 01 05:33:16 volumio-x86 volumio[1131]: error: The expression evaluated to a falsy value: Mar 01 05:33:16 volumio-x86 volumio[1131]: assert.ok(self.idling) Mar 01 05:33:16 volumio-x86 volumio[1131]: error: updateQueue error: null Mar 01 05:33:17 volumio-x86 volumio[1131]: error: Upnp client error: Error: This socket has been ended by the other party Mar 01 05:33:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPlay Mar 01 05:33:19 volumio-x86 volumio[1131]: info: CoreStateMachine::play index undefined Mar 01 05:33:19 volumio-x86 volumio[1131]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 05:33:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:19 volumio-x86 volumio[1131]: info: CoreStateMachine::startPlaybackTimer Mar 01 05:33:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:19 volumio-x86 volumio[1131]: info: ControllerMpd::resume Mar 01 05:33:19 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand play Mar 01 05:33:19 volumio-x86 volumio[1131]: info: sendMpdCommand play took 1 milliseconds Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreStateMachine::getcurrentVolume Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 05:33:20 volumio-x86 volumio[1131]: info: VolumeController:: Volume=41 Mute =false Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreStateMachine::updateTrackBlock Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrackBlock Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 05:33:20 volumio-x86 volumio[1131]: info: VolumeController:: Volume=41 Mute =false Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:20 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:21 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPlay Mar 01 05:33:21 volumio-x86 volumio[1131]: info: CoreStateMachine::play index undefined Mar 01 05:33:21 volumio-x86 volumio[1131]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 05:33:21 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:21 volumio-x86 volumio[1131]: info: CoreStateMachine::startPlaybackTimer Mar 01 05:33:21 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:21 volumio-x86 volumio[1131]: info: ControllerMpd::resume Mar 01 05:33:21 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand play Mar 01 05:33:21 volumio-x86 volumio[1131]: info: sendMpdCommand play took 1 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioNext Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::next Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::stop Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::updateTrackBlock Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrackBlock Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::serviceStop Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 0 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::serviceStop Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::stop Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand stop took 4 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::play index undefined Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::startPlaybackTimer Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::clearAddPlayTracks NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::updateTrackBlock Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrackBlock Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand stop took 1 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Mar 01 05:33:23 volumio-x86 volumio[1131]: ---------------------------- MPD announces system playlist update Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Ignoring MPD Status Update Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand clear took 0 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand add "NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac" Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Mar 01 05:33:23 volumio-x86 volumio[1131]: ---------------------------- MPD announces system playlist update Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Ignoring MPD Status Update Mar 01 05:33:23 volumio-x86 volumio[1131]: error: updateQueue error: null Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Mar 01 05:33:23 volumio-x86 volumio[1131]: ---------------------------- MPD announces system playlist update Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Ignoring MPD Status Update Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ------------------------------ 3ms Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand add "NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac" took 2 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ------------------------------ 2ms Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand play Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Mar 01 05:33:23 volumio-x86 volumio[1131]: ---------------------------- MPD announces system playlist update Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Ignoring MPD Status Update Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ------------------------------ 3ms Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand play took 2 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ------------------------------ 1ms Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Mar 01 05:33:23 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Mar 01 05:33:23 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Mar 01 05:33:23 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand status took 2 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand status took 2 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Mar 01 05:33:23 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus stop Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ------------------------------ 16ms Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ------------------------------ 25ms Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ------------------------------ 24ms Mar 01 05:33:23 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:33:23 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:33:23 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:23 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:23 volumio-x86 volumio[1131]: info: ------------------------------ 33ms Mar 01 05:33:23 volumio-x86 volumio[1131]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/S.H.E/%E4%BD%A0%E6%9B%BE%E6%98%AF%E5%B0%91%E5%B9%B4/c7342987-8c2e-4b4f-a04b-9225eb0b8916.jpg' Mar 01 05:33:23 volumio-x86 volumio[1131]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/S.H.E/%E4%BD%A0%E6%9B%BE%E6%98%AF%E5%B0%91%E5%B9%B4/c7342987-8c2e-4b4f-a04b-9225eb0b8916.jpg' Mar 01 05:33:23 volumio-x86 volumio[1131]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/S.H.E/%E4%BD%A0%E6%9B%BE%E6%98%AF%E5%B0%91%E5%B9%B4/c7342987-8c2e-4b4f-a04b-9225eb0b8916.jpg' Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioSeek Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::seek Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: TRACKBLOCK {"uri":"mnt/NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","service":"mpd","name":"你曾是少年","artist":"S.H.E","album":"你曾是少年","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=198&web=S.H.E/%E4%BD%A0%E6%9B%BE%E6%98%AF%E5%B0%91%E5%B9%B4/extralarge&path=%2FNAS%2F%E9%A3%9E%E7%89%9B%2F1-%E4%B8%AA%E4%BA%BA%E4%B8%93%E8%BE%91%2FSHE&metadata=false","duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::startPlaybackTimer Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: ControllerMpd::seek Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:25 volumio-x86 volumio[1131]: error: null Mar 01 05:33:25 volumio-x86 volumio[1131]: info: Mar 01 05:33:25 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:25 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:25 volumio-x86 volumio[1131]: info: Mar 01 05:33:25 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:25 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:25 volumio-x86 volumio[1131]: info: sendMpdCommand status took 14 milliseconds Mar 01 05:33:25 volumio-x86 volumio[1131]: info: sendMpdCommand status took 5 milliseconds Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:25 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 01 05:33:25 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:33:25 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39000,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"596 Kbps","isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:33:25 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39842,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"836 Kbps","isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:33:25 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:33:25 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:25 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:26 volumio-x86 volumio[1131]: info: ------------------------------ 40ms Mar 01 05:33:26 volumio-x86 volumio[1131]: info: ------------------------------ 31ms Mar 01 05:33:33 volumio-x86 volumio[1131]: info: VolumeController::SetAlsaVolume44 Mar 01 05:33:33 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:33 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:33 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:33:33 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioSeek Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::seek Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: TRACKBLOCK {"uri":"mnt/NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","service":"mpd","name":"你曾是少年","artist":"S.H.E","album":"你曾是少年","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=198&web=S.H.E/%E4%BD%A0%E6%9B%BE%E6%98%AF%E5%B0%91%E5%B9%B4/extralarge&path=%2FNAS%2F%E9%A3%9E%E7%89%9B%2F1-%E4%B8%AA%E4%BA%BA%E4%B8%93%E8%BE%91%2FSHE&metadata=false","duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::startPlaybackTimer Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: ControllerMpd::seek Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:35 volumio-x86 volumio[1131]: error: null Mar 01 05:33:35 volumio-x86 volumio[1131]: info: Mar 01 05:33:35 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:35 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:35 volumio-x86 volumio[1131]: info: Mar 01 05:33:35 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:33:35 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:33:35 volumio-x86 volumio[1131]: info: sendMpdCommand status took 4 milliseconds Mar 01 05:33:35 volumio-x86 volumio[1131]: info: sendMpdCommand status took 4 milliseconds Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:33:35 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 05:33:35 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:33:35 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":79000,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"818 Kbps","isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:33:35 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":79000,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"42283 Kbps","isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:33:35 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:33:35 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:33:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:33:36 volumio-x86 volumio[1131]: info: ------------------------------ 40ms Mar 01 05:33:36 volumio-x86 volumio[1131]: info: ------------------------------ 39ms Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 05:33:38 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Preparing to save Alsa Options, stopping services first Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioGetState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::pause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::servicePause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: ControllerMpd::pause Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand pause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Found match in Cards Database: setting mixer for card Analog Out Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Setting mixer Master for card Analog Out Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Disabling external Volume Control Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Preparing to generate the ALSA configuration file Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Mar 01 05:34:10 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:10 volumio-x86 volumio[1131]: info: sendMpdCommand pause took 95 milliseconds Mar 01 05:34:10 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Mar 01 05:34:10 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:10 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:10 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:34:10 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:10 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:34:10 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":114583,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1209 Kbps","isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService pause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus pause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:34:10 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":114583,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1209 Kbps","isStreaming":false,"title":"你曾是少年","artist":"S.H.E","album":"你曾是少年","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 你曾是少年.flac","trackType":"flac"} Mar 01 05:34:10 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService pause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus pause Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:34:10 volumio-x86 volumio[1131]: info: ------------------------------ 26ms Mar 01 05:34:10 volumio-x86 volumio[1131]: info: ------------------------------ 26ms Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 05:34:10 volumio-x86 volumio[1131]: info: VolumeController:: Volume=41 Mute =false Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Asound.conf file written Mar 01 05:34:10 volumio-x86 sudo[2796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 01 05:34:10 volumio-x86 sudo[2796]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:34:10 volumio-x86 sudo[2796]: pam_unix(sudo:session): session closed for user root Mar 01 05:34:10 volumio-x86 volumio[1131]: No state is present for card PRMA Mar 01 05:34:10 volumio-x86 volumio[1131]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:f801" "" "" Mar 01 05:34:10 volumio-x86 volumio[1131]: Hardware is initialized using a generic method Mar 01 05:34:10 volumio-x86 volumio[1131]: No state is present for card PRMA Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Output device has changed, restarting MPD Mar 01 05:34:10 volumio-x86 sudo[2801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 05:34:10 volumio-x86 sudo[2801]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Output device has changed, restarting Shairport Sync Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:34:10 volumio-x86 sudo[2801]: pam_unix(sudo:session): session closed for user root Mar 01 05:34:10 volumio-x86 sudo[2803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 05:34:10 volumio-x86 sudo[2803]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:34:10 volumio-x86 systemd[1]: Stopping Music Player Daemon... Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Output device has changed, restarting MPD Mar 01 05:34:10 volumio-x86 systemd[1]: mpd.service: Succeeded. Mar 01 05:34:10 volumio-x86 systemd[1]: Stopped Music Player Daemon. Mar 01 05:34:10 volumio-x86 sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 05:34:10 volumio-x86 sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:34:10 volumio-x86 systemd[1]: Starting Music Player Daemon... Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Output device has changed, restarting Shairport Sync Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:34:10 volumio-x86 sudo[2811]: pam_unix(sudo:session): session closed for user root Mar 01 05:34:10 volumio-x86 sudo[2814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 05:34:10 volumio-x86 sudo[2814]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:34:10 volumio-x86 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 01 05:34:10 volumio-x86 systemd[1]: mpd.service: Succeeded. Mar 01 05:34:10 volumio-x86 systemd[1]: Stopped Music Player Daemon. Mar 01 05:34:10 volumio-x86 volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 05:34:10 volumio-x86 volumio[1131]: info: MPD Permissions set Mar 01 05:34:10 volumio-x86 volumio[1131]: info: MPD Permissions set Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:10 volumio-x86 systemd[1]: Starting Music Player Daemon... Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:34:10 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 05:34:11 volumio-x86 sudo[2819]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 05:34:11 volumio-x86 sudo[2819]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:34:11 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 05:34:11 volumio-x86 volumio[1131]: info: Starting Shairport Sync Mar 01 05:34:11 volumio-x86 sudo[2819]: pam_unix(sudo:session): session closed for user root Mar 01 05:34:11 volumio-x86 volumio[1131]: info: Starting Shairport Sync Mar 01 05:34:11 volumio-x86 sudo[2835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 05:34:11 volumio-x86 sudo[2835]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:34:11 volumio-x86 sudo[2837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 05:34:11 volumio-x86 sudo[2837]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 05:34:11 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 05:34:11 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Mar 01 05:34:11 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 05:34:11 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 05:34:11 volumio-x86 sudo[2835]: pam_unix(sudo:session): session closed for user root Mar 01 05:34:11 volumio-x86 volumio[1131]: info: Shairport-Sync Started Mar 01 05:34:11 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 05:34:11 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 01 05:34:11 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Mar 01 05:34:11 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 05:34:11 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 05:34:11 volumio-x86 sudo[2837]: pam_unix(sudo:session): session closed for user root Mar 01 05:34:11 volumio-x86 volumio[1131]: info: Shairport-Sync Started Mar 01 05:34:11 volumio-x86 mpd[2833]: Mar 01 05:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 01 05:34:11 volumio-x86 systemd[1]: Started Music Player Daemon. Mar 01 05:34:11 volumio-x86 sudo[2803]: pam_unix(sudo:session): session closed for user root Mar 01 05:34:11 volumio-x86 sudo[2814]: pam_unix(sudo:session): session closed for user root Mar 01 05:34:11 volumio-x86 volumio[1131]: error: MPD error: The expression evaluated to a falsy value: Mar 01 05:34:11 volumio-x86 volumio[1131]: assert.ok(self.idling) Mar 01 05:34:11 volumio-x86 volumio[1131]: error: The expression evaluated to a falsy value: Mar 01 05:34:11 volumio-x86 volumio[1131]: assert.ok(self.idling) Mar 01 05:34:11 volumio-x86 volumio[1131]: error: updateQueue error: null Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreStateMachine::getcurrentVolume Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 05:34:14 volumio-x86 volumio[1131]: info: VolumeController:: Volume=41 Mute =false Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreStateMachine::updateTrackBlock Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrackBlock Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 05:34:14 volumio-x86 volumio[1131]: info: VolumeController:: Volume=41 Mute =false Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:14 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioNext Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::next Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::stop Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::updateTrackBlock Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrackBlock Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::serviceStop Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 1 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::serviceStop Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::stop Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand stop took 3 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::play index undefined Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::startPlaybackTimer Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::clearAddPlayTracks NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::updateTrackBlock Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrackBlock Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand stop took 0 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Mar 01 05:34:17 volumio-x86 volumio[1131]: ---------------------------- MPD announces system playlist update Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Ignoring MPD Status Update Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand clear took 0 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand add "NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac" Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Mar 01 05:34:17 volumio-x86 volumio[1131]: ---------------------------- MPD announces system playlist update Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Ignoring MPD Status Update Mar 01 05:34:17 volumio-x86 volumio[1131]: error: updateQueue error: null Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Mar 01 05:34:17 volumio-x86 volumio[1131]: ---------------------------- MPD announces system playlist update Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Ignoring MPD Status Update Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ------------------------------ 2ms Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand add "NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac" took 2 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ------------------------------ 1ms Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand play Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Mar 01 05:34:17 volumio-x86 volumio[1131]: ---------------------------- MPD announces system playlist update Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Ignoring MPD Status Update Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ------------------------------ 2ms Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand play took 2 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ------------------------------ 1ms Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Mar 01 05:34:17 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Mar 01 05:34:17 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Mar 01 05:34:17 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand status took 7 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand status took 6 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand status took 2 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus stop Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ------------------------------ 15ms Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ------------------------------ 19ms Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Mar 01 05:34:17 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"561 Kbps","isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ------------------------------ 27ms Mar 01 05:34:17 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"547 Kbps","isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:17 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:34:17 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:17 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:17 volumio-x86 volumio[1131]: info: ------------------------------ 24ms Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioSeek Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::seek Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: TRACKBLOCK {"uri":"mnt/NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","service":"mpd","name":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=198&web=S.H.E/%E6%96%97%E7%89%9B%EF%BC%8C%E8%A6%81%E4%B8%8D%E8%A6%81%20%E7%94%B5%E8%A7%86%E5%8E%9F%E5%A3%B0%E5%B8%A6/extralarge&path=%2FNAS%2F%E9%A3%9E%E7%89%9B%2F1-%E4%B8%AA%E4%BA%BA%E4%B8%93%E8%BE%91%2FSHE&metadata=false","duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::startPlaybackTimer Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: ControllerMpd::seek Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:19 volumio-x86 volumio[1131]: error: null Mar 01 05:34:19 volumio-x86 volumio[1131]: info: Mar 01 05:34:19 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:19 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:19 volumio-x86 volumio[1131]: info: Mar 01 05:34:19 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:19 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:19 volumio-x86 volumio[1131]: info: sendMpdCommand status took 2 milliseconds Mar 01 05:34:19 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:19 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 01 05:34:19 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:34:19 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42865,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1052 Kbps","isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:34:19 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42865,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"973 Kbps","isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:19 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:34:19 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:19 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:19 volumio-x86 volumio[1131]: info: ------------------------------ 24ms Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::pause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::servicePause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: ControllerMpd::pause Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand pause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: Mar 01 05:34:35 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:35 volumio-x86 volumio[1131]: info: sendMpdCommand pause took 1 milliseconds Mar 01 05:34:35 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:35 volumio-x86 volumio[1131]: info: Mar 01 05:34:35 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:35 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:35 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:35 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:34:35 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:34:35 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":59370,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1078 Kbps","isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService pause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus pause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:34:35 volumio-x86 volumio[1131]: info: ------------------------------ 8ms Mar 01 05:34:35 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:34:35 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":59370,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1078 Kbps","isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:35 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService pause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus pause Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:35 volumio-x86 volumio[1131]: info: CoreStateMachine::stPlaybackTimer Mar 01 05:34:35 volumio-x86 volumio[1131]: info: ------------------------------ 12ms Mar 01 05:34:43 volumio-x86 kernel: usb 1-2: USB disconnect, device number 3 Mar 01 05:34:43 volumio-x86 volumio[1131]: info: Mar 01 05:34:43 volumio-x86 volumio[1131]: ---------------------------- USB Audio Device Detached Mar 01 05:34:43 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Mar 01 05:34:43 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 05:34:43 volumio-x86 volumio[1131]: info: No valid Plugin REST Endpoint Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: new full-speed USB device number 4 using xhci_hcd Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: config 1 has an invalid interface number: 3 but max is 2 Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: config 1 has an invalid interface number: 3 but max is 2 Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: config 1 has an invalid interface number: 3 but max is 2 Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: config 1 has no interface number 2 Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: New USB device found, idVendor=262a, idProduct=9023, bcdDevice= 0.01 Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: Product: DAC Audio Mar 01 05:34:47 volumio-x86 kernel: usb 1-2: Manufacturer: E+ Corp. Mar 01 05:34:47 volumio-x86 kernel: hid-generic 0003:262A:9023.0001: No inputs registered, leaving Mar 01 05:34:47 volumio-x86 kernel: hid-generic 0003:262A:9023.0001: hidraw0: USB HID v1.00 Device [E+ Corp. DAC Audio] on usb-0000:00:14.0-2/input0 Mar 01 05:34:47 volumio-x86 volumio[1131]: info: Mar 01 05:34:47 volumio-x86 volumio[1131]: ---------------------------- USB Audio Device Attached Mar 01 05:34:47 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 01 05:34:47 volumio-x86 systemd-udevd[2976]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPlay Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::play index undefined Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::startPlaybackTimer Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: info: ControllerMpd::resume Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand play Mar 01 05:34:52 volumio-x86 volumio[1131]: info: Mar 01 05:34:52 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:52 volumio-x86 volumio[1131]: info: sendMpdCommand play took 2 milliseconds Mar 01 05:34:52 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:52 volumio-x86 volumio[1131]: info: Mar 01 05:34:52 volumio-x86 volumio[1131]: ---------------------------- MPD announces state update: player Mar 01 05:34:52 volumio-x86 volumio[1131]: info: ControllerMpd::getState Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand status Mar 01 05:34:52 volumio-x86 volumio[1131]: info: sendMpdCommand status took 1 milliseconds Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseState Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 05:34:52 volumio-x86 volumio[1131]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: ControllerMpd::parseTrackInfo Mar 01 05:34:52 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60258,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"952 Kbps","isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus pause Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: ControllerMpd::pushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreCommandRouter::servicePushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60258,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"952 Kbps","isStreaming":false,"title":"爱来过","artist":"S.H.E","album":"斗牛，要不要 电视原声带","uri":"NAS/飞牛/1-个人专辑/SHE/S.H.E - 爱来过.flac","trackType":"flac"} Mar 01 05:34:52 volumio-x86 volumio[1131]: verbose: CURRENT POSITION 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState stateService play Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::syncState currentStatus play Mar 01 05:34:52 volumio-x86 volumio[1131]: info: Received an update from plugin. extracting info from payload Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreStateMachine::pushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CorePlayQueue::getTrack 2 Mar 01 05:34:52 volumio-x86 volumio[1131]: info: CoreCommandRouter::volumioPushState Mar 01 05:34:52 volumio-x86 volumio[1131]: info: ------------------------------ 14ms Mar 01 05:34:52 volumio-x86 volumio[1131]: info: ------------------------------ 14ms Mar 01 05:34:52 volumio-x86 volumio[1131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 05:34:52 volumio-x86 volumio[1131]: Error: connect ETIMEDOUT 199.59.149.136:443 Mar 01 05:34:52 volumio-x86 volumio[1131]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 01 05:34:52 volumio-x86 volumio[1131]: errno: -110, Mar 01 05:34:52 volumio-x86 volumio[1131]: code: 'ETIMEDOUT', Mar 01 05:34:52 volumio-x86 volumio[1131]: syscall: 'connect', Mar 01 05:34:52 volumio-x86 volumio[1131]: address: '199.59.149.136', Mar 01 05:34:52 volumio-x86 volumio[1131]: port: 443 Mar 01 05:34:52 volumio-x86 volumio[1131]: } Mar 01 05:34:52 volumio-x86 volumio[1131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 05:34:53 volumio-x86 sudo[2994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 05:33 Mar 01 05:34:53 volumio-x86 sudo[2994]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"