-- Logs begin at Wed 2025-03-05 21:57:12 CST, end at Wed 2025-03-05 22:06:10 CST. -- Mar 05 22:05:09 volumio volumio[1204]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 05 22:05:09 volumio volumio[1204]: info: Preparing to save Alsa Options, stopping services first Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Mar 05 22:05:09 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::volumioPause Mar 05 22:05:09 volumio volumio[1204]: info: CoreStateMachine::pause Mar 05 22:05:09 volumio volumio[1204]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 05 22:05:09 volumio volumio[1204]: info: Found match in Cards Database: setting mixer for card Analog Out Mar 05 22:05:09 volumio volumio[1204]: info: Setting mixer Master for card Analog Out Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 05 22:05:09 volumio volumio[1204]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 05 22:05:09 volumio volumio[1204]: info: Disabling external Volume Control Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 05 22:05:09 volumio volumio[1204]: info: Preparing to generate the ALSA configuration file Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:09 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 05 22:05:10 volumio volumio[1204]: info: VolumeController:: Volume=41 Mute =false Mar 05 22:05:10 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:10 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:10 volumio volumio[1204]: info: Asound.conf file written Mar 05 22:05:10 volumio sudo[2989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 05 22:05:10 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:10 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:10 volumio volumio[1204]: info: Output device has changed, restarting MPD Mar 05 22:05:10 volumio sudo[2994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 22:05:10 volumio sudo[2994]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:10 volumio volumio[1204]: info: Output device has changed, restarting Shairport Sync Mar 05 22:05:10 volumio sudo[2994]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:10 volumio sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 22:05:10 volumio sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:10 volumio systemd[1]: Stopping Music Player Daemon... Mar 05 22:05:10 volumio volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 22:05:10 volumio volumio[1204]: info: Output device has changed, restarting MPD Mar 05 22:05:10 volumio systemd[1]: mpd.service: Succeeded. Mar 05 22:05:10 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 22:05:10 volumio sudo[3004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 22:05:10 volumio sudo[3004]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:10 volumio systemd[1]: Starting Music Player Daemon... Mar 05 22:05:10 volumio volumio[1204]: info: Output device has changed, restarting Shairport Sync Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 22:05:10 volumio volumio[1204]: info: MPD Permissions set Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 05 22:05:10 volumio sudo[3004]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:10 volumio sudo[3007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 22:05:10 volumio sudo[3007]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:10 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 05 22:05:10 volumio systemd[1]: mpd.service: Succeeded. Mar 05 22:05:10 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 22:05:10 volumio systemd[1]: Starting Music Player Daemon... Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 05 22:05:10 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:10 volumio volumio[1204]: info: Starting Shairport Sync Mar 05 22:05:10 volumio volumio[1204]: info: Starting Shairport Sync Mar 05 22:05:10 volumio sudo[3018]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 22:05:10 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:10 volumio sudo[3018]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:10 volumio sudo[3026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 05 22:05:10 volumio sudo[3026]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 05 22:05:10 volumio sudo[3031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 05 22:05:10 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 05 22:05:10 volumio sudo[3031]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 05 22:05:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 05 22:05:10 volumio sudo[3026]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:10 volumio volumio[1204]: info: Shairport-Sync Started Mar 05 22:05:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 05 22:05:10 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 05 22:05:10 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 05 22:05:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 05 22:05:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 05 22:05:10 volumio sudo[3031]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:10 volumio volumio[1204]: info: Shairport-Sync Started Mar 05 22:05:10 volumio mpd[3029]: Mar 05 22:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 05 22:05:10 volumio systemd[1]: Started Music Player Daemon. Mar 05 22:05:10 volumio sudo[3007]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:10 volumio sudo[2997]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:10 volumio volumio[1204]: error: MPD error: The expression evaluated to a falsy value: Mar 05 22:05:10 volumio volumio[1204]: assert.ok(self.idling) Mar 05 22:05:10 volumio volumio[1204]: error: The expression evaluated to a falsy value: Mar 05 22:05:10 volumio volumio[1204]: assert.ok(self.idling) Mar 05 22:05:10 volumio volumio[1204]: error: updateQueue error: null Mar 05 22:05:13 volumio volumio[1204]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 05 22:05:13 volumio volumio[1204]: info: CoreStateMachine::getcurrentVolume Mar 05 22:05:13 volumio volumio[1204]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 22:05:13 volumio volumio[1204]: info: VolumeController:: Volume=41 Mute =false Mar 05 22:05:13 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:13 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:13 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:13 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:13 volumio volumio[1204]: info: CoreStateMachine::updateTrackBlock Mar 05 22:05:13 volumio volumio[1204]: info: CorePlayQueue::getTrackBlock Mar 05 22:05:13 volumio volumio[1204]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 22:05:14 volumio volumio[1204]: info: VolumeController:: Volume=41 Mute =false Mar 05 22:05:14 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:14 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:14 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:14 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:14 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:18 volumio volumio[1204]: info: CoreCommandRouter::volumioSeek Mar 05 22:05:18 volumio volumio[1204]: info: CoreStateMachine::seek Mar 05 22:05:18 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:18 volumio volumio[1204]: info: TRACKBLOCK {"uri":"mnt/USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","service":"mpd","name":"Track09","artist":"群星","album":"伶歌2","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=757&web=%E7%BE%A4%E6%98%9F/%E4%BC%B6%E6%AD%8C2/extralarge&path=%2FUSB%2FElements%2F%E9%9F%B3%E4%B9%90%2Fsacd%2F%E6%96%B0%E6%B4%BE%2F%E4%BC%B6%E6%AD%8C2%20FLAC&metadata=false","duration":274,"trackType":"flac"} Mar 05 22:05:18 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer Mar 05 22:05:18 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:18 volumio volumio[1204]: info: ControllerMpd::seek Mar 05 22:05:18 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:18 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:18 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:18 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:18 volumio volumio[1204]: error: [2@0] {seek} Bad song index Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPlay Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::play index undefined Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand stop Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand stop took 1 milliseconds Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand clear Mar 05 22:05:21 volumio volumio[1204]: info: Mar 05 22:05:21 volumio volumio[1204]: ---------------------------- MPD announces system playlist update Mar 05 22:05:21 volumio volumio[1204]: info: Ignoring MPD Status Update Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand clear took 1 milliseconds Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac" Mar 05 22:05:21 volumio volumio[1204]: info: Mar 05 22:05:21 volumio volumio[1204]: ---------------------------- MPD announces system playlist update Mar 05 22:05:21 volumio volumio[1204]: info: Ignoring MPD Status Update Mar 05 22:05:21 volumio volumio[1204]: error: updateQueue error: null Mar 05 22:05:21 volumio volumio[1204]: info: Mar 05 22:05:21 volumio volumio[1204]: ---------------------------- MPD announces system playlist update Mar 05 22:05:21 volumio volumio[1204]: info: Ignoring MPD Status Update Mar 05 22:05:21 volumio volumio[1204]: info: ------------------------------ 2ms Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand add "USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac" took 1 milliseconds Mar 05 22:05:21 volumio volumio[1204]: info: ------------------------------ 1ms Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand play Mar 05 22:05:21 volumio volumio[1204]: info: Mar 05 22:05:21 volumio volumio[1204]: ---------------------------- MPD announces system playlist update Mar 05 22:05:21 volumio volumio[1204]: info: Ignoring MPD Status Update Mar 05 22:05:21 volumio volumio[1204]: info: ------------------------------ 1ms Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand play took 1 milliseconds Mar 05 22:05:21 volumio volumio[1204]: info: ------------------------------ 0ms Mar 05 22:05:21 volumio volumio[1204]: info: Mar 05 22:05:21 volumio volumio[1204]: ---------------------------- MPD announces state update: player Mar 05 22:05:21 volumio volumio[1204]: info: ControllerMpd::getState Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Mar 05 22:05:21 volumio volumio[1204]: info: Mar 05 22:05:21 volumio volumio[1204]: ---------------------------- MPD announces state update: player Mar 05 22:05:21 volumio volumio[1204]: info: ControllerMpd::getState Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Mar 05 22:05:21 volumio volumio[1204]: info: Mar 05 22:05:21 volumio volumio[1204]: ---------------------------- MPD announces state update: player Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand status took 26 milliseconds Mar 05 22:05:21 volumio volumio[1204]: info: ControllerMpd::getState Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::parseState Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand status took 25 milliseconds Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand status took 0 milliseconds Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::parseState Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::parseState Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::parseTrackInfo Mar 05 22:05:21 volumio volumio[1204]: info: ControllerMpd::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::servicePushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"138 Kbps","isStreaming":false,"title":"Track09","artist":"群星","album":"伶歌2","uri":"USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","trackType":"flac"} Mar 05 22:05:21 volumio volumio[1204]: verbose: CURRENT POSITION 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::syncState stateService play Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::syncState currentStatus stop Mar 05 22:05:21 volumio volumio[1204]: info: ------------------------------ 27ms Mar 05 22:05:21 volumio volumio[1204]: info: Mar 05 22:05:21 volumio volumio[1204]: ---------------------------- MPD announces state update: player Mar 05 22:05:21 volumio volumio[1204]: info: ControllerMpd::getState Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand status took 1 milliseconds Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::parseTrackInfo Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::parseTrackInfo Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::parseState Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 22:05:21 volumio volumio[1204]: info: ControllerMpd::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::servicePushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":274,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"137 Kbps","isStreaming":false,"title":"Track09","artist":"群星","album":"伶歌2","uri":"USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","trackType":"flac"} Mar 05 22:05:21 volumio volumio[1204]: verbose: CURRENT POSITION 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::syncState stateService play Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::syncState currentStatus play Mar 05 22:05:21 volumio volumio[1204]: info: Received an update from plugin. extracting info from payload Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:21 volumio volumio[1204]: info: ControllerMpd::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::servicePushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":274,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"136 Kbps","isStreaming":false,"title":"Track09","artist":"群星","album":"伶歌2","uri":"USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","trackType":"flac"} Mar 05 22:05:21 volumio volumio[1204]: verbose: CURRENT POSITION 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::syncState stateService play Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::syncState currentStatus play Mar 05 22:05:21 volumio volumio[1204]: info: Received an update from plugin. extracting info from payload Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:21 volumio volumio[1204]: info: ------------------------------ 34ms Mar 05 22:05:21 volumio volumio[1204]: info: ------------------------------ 9ms Mar 05 22:05:21 volumio volumio[1204]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 05 22:05:21 volumio volumio[1204]: verbose: ControllerMpd::parseTrackInfo Mar 05 22:05:21 volumio volumio[1204]: info: ControllerMpd::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::servicePushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":274,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"136 Kbps","isStreaming":false,"title":"Track09","artist":"群星","album":"伶歌2","uri":"USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","trackType":"flac"} Mar 05 22:05:21 volumio volumio[1204]: verbose: CURRENT POSITION 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::syncState stateService play Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::syncState currentStatus play Mar 05 22:05:21 volumio volumio[1204]: info: Received an update from plugin. extracting info from payload Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:21 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:21 volumio volumio[1204]: info: ------------------------------ 11ms Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 05 22:05:30 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 05 22:05:35 volumio volumio[1204]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 05 22:05:35 volumio volumio[1204]: info: Preparing to save Alsa Options, stopping services first Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Mar 05 22:05:35 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::volumioPause Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::pause Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::stPlaybackTimer Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::servicePause Mar 05 22:05:35 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::servicePause Mar 05 22:05:35 volumio volumio[1204]: info: ControllerMpd::pause Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand pause Mar 05 22:05:35 volumio volumio[1204]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"UA-M10"}} Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 05 22:05:35 volumio volumio[1204]: info: Device 5 does not have any Mixer Control Available Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 05 22:05:35 volumio volumio[1204]: info: Updating Volume Controller Parameters: Device: 5 Name: UA-M10 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 05 22:05:35 volumio volumio[1204]: info: Disabling external Volume Control Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:35 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:35 volumio volumio[1204]: info: Preparing to generate the ALSA configuration file Mar 05 22:05:35 volumio volumio[1204]: info: Mar 05 22:05:35 volumio volumio[1204]: ---------------------------- MPD announces state update: player Mar 05 22:05:35 volumio volumio[1204]: info: sendMpdCommand pause took 79 milliseconds Mar 05 22:05:35 volumio volumio[1204]: info: ControllerMpd::getState Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Mar 05 22:05:35 volumio volumio[1204]: info: Mar 05 22:05:35 volumio volumio[1204]: ---------------------------- MPD announces state update: player Mar 05 22:05:35 volumio volumio[1204]: info: ControllerMpd::getState Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Mar 05 22:05:35 volumio volumio[1204]: info: sendMpdCommand status took 1 milliseconds Mar 05 22:05:35 volumio volumio[1204]: info: sendMpdCommand status took 1 milliseconds Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::parseState Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::parseState Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 22:05:35 volumio volumio[1204]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 05 22:05:35 volumio volumio[1204]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::parseTrackInfo Mar 05 22:05:35 volumio volumio[1204]: verbose: ControllerMpd::parseTrackInfo Mar 05 22:05:35 volumio volumio[1204]: info: ControllerMpd::pushState Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::servicePushState Mar 05 22:05:35 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:35 volumio volumio[1204]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15227,"duration":274,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"566 Kbps","isStreaming":false,"title":"Track09","artist":"群星","album":"伶歌2","uri":"USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","trackType":"flac"} Mar 05 22:05:35 volumio volumio[1204]: verbose: CURRENT POSITION 8 Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::syncState stateService pause Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::syncState currentStatus pause Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:35 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::stPlaybackTimer Mar 05 22:05:35 volumio volumio[1204]: info: ControllerMpd::pushState Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::servicePushState Mar 05 22:05:35 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:35 volumio volumio[1204]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15227,"duration":274,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"566 Kbps","isStreaming":false,"title":"Track09","artist":"群星","album":"伶歌2","uri":"USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","trackType":"flac"} Mar 05 22:05:35 volumio volumio[1204]: verbose: CURRENT POSITION 8 Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::syncState stateService pause Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::syncState currentStatus pause Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:35 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:35 volumio volumio[1204]: info: CoreStateMachine::stPlaybackTimer Mar 05 22:05:35 volumio volumio[1204]: info: ------------------------------ 5ms Mar 05 22:05:35 volumio volumio[1204]: info: ------------------------------ 5ms Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 05 22:05:35 volumio volumio[1204]: info: Asound.conf file written Mar 05 22:05:35 volumio sudo[3154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 05 22:05:35 volumio sudo[3154]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:35 volumio sudo[3154]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:35 volumio volumio[1204]: info: Output device has changed, restarting MPD Mar 05 22:05:35 volumio sudo[3159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 22:05:35 volumio sudo[3159]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:35 volumio volumio[1204]: info: Output device has changed, restarting Shairport Sync Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:35 volumio sudo[3159]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:35 volumio sudo[3162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 22:05:35 volumio sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:35 volumio systemd[1]: Stopping Music Player Daemon... Mar 05 22:05:35 volumio volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 22:05:35 volumio volumio[1204]: info: Output device has changed, restarting MPD Mar 05 22:05:35 volumio systemd[1]: mpd.service: Succeeded. Mar 05 22:05:35 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 22:05:35 volumio sudo[3169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 22:05:35 volumio sudo[3169]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:35 volumio volumio[1204]: info: Output device has changed, restarting Shairport Sync Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:35 volumio sudo[3169]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:35 volumio systemd[1]: Starting Music Player Daemon... Mar 05 22:05:35 volumio sudo[3172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 22:05:35 volumio volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 22:05:35 volumio sudo[3172]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:35 volumio volumio[1204]: info: MPD Permissions set Mar 05 22:05:35 volumio volumio[1204]: info: MPD Permissions set Mar 05 22:05:35 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 05 22:05:35 volumio systemd[1]: mpd.service: Succeeded. Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio systemd[1]: Starting Music Player Daemon... Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 05 22:05:35 volumio sudo[3177]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 22:05:35 volumio sudo[3177]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:35 volumio sudo[3177]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:35 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 22:05:35 volumio volumio[1204]: info: Starting Shairport Sync Mar 05 22:05:35 volumio volumio[1204]: info: Starting Shairport Sync Mar 05 22:05:35 volumio sudo[3193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 05 22:05:35 volumio sudo[3193]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:35 volumio sudo[3196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 05 22:05:35 volumio sudo[3196]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 22:05:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 05 22:05:35 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 05 22:05:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 05 22:05:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 05 22:05:35 volumio sudo[3196]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:35 volumio sudo[3193]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:35 volumio volumio[1204]: info: Shairport-Sync Started Mar 05 22:05:35 volumio volumio[1204]: info: Shairport-Sync Started Mar 05 22:05:35 volumio mpd[3191]: Mar 05 22:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 05 22:05:35 volumio systemd[1]: Started Music Player Daemon. Mar 05 22:05:35 volumio sudo[3172]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:35 volumio sudo[3162]: pam_unix(sudo:session): session closed for user root Mar 05 22:05:35 volumio volumio[1204]: error: MPD error: The expression evaluated to a falsy value: Mar 05 22:05:35 volumio volumio[1204]: assert.ok(self.idling) Mar 05 22:05:35 volumio volumio[1204]: error: The expression evaluated to a falsy value: Mar 05 22:05:35 volumio volumio[1204]: assert.ok(self.idling) Mar 05 22:05:35 volumio volumio[1204]: error: updateQueue error: null Mar 05 22:05:39 volumio volumio[1204]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 05 22:05:39 volumio volumio[1204]: info: CoreStateMachine::getcurrentVolume Mar 05 22:05:39 volumio volumio[1204]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 22:05:39 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:39 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:39 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:39 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:41 volumio volumio[1204]: info: CoreCommandRouter::volumioPlay Mar 05 22:05:41 volumio volumio[1204]: info: CoreStateMachine::play index undefined Mar 05 22:05:41 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 22:05:41 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:41 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer Mar 05 22:05:41 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:41 volumio volumio[1204]: info: ControllerMpd::resume Mar 05 22:05:41 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand play Mar 05 22:05:41 volumio volumio[1204]: info: sendMpdCommand play took 1 milliseconds Mar 05 22:05:44 volumio volumio[1204]: info: CoreCommandRouter::volumioSeek Mar 05 22:05:44 volumio volumio[1204]: info: CoreStateMachine::seek Mar 05 22:05:44 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:44 volumio volumio[1204]: info: TRACKBLOCK {"uri":"mnt/USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","service":"mpd","name":"Track09","artist":"群星","album":"伶歌2","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=757&web=%E7%BE%A4%E6%98%9F/%E4%BC%B6%E6%AD%8C2/extralarge&path=%2FUSB%2FElements%2F%E9%9F%B3%E4%B9%90%2Fsacd%2F%E6%96%B0%E6%B4%BE%2F%E4%BC%B6%E6%AD%8C2%20FLAC&metadata=false","duration":274,"samplerate":"48 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 05 22:05:44 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer Mar 05 22:05:44 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:44 volumio volumio[1204]: info: ControllerMpd::seek Mar 05 22:05:44 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:44 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:44 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:44 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:44 volumio volumio[1204]: error: [2@0] {seek} Bad song index Mar 05 22:05:48 volumio volumio[1204]: info: CoreCommandRouter::volumioSeek Mar 05 22:05:48 volumio volumio[1204]: info: CoreStateMachine::seek Mar 05 22:05:48 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:48 volumio volumio[1204]: info: TRACKBLOCK {"uri":"mnt/USB/Elements/音乐/sacd/新派/伶歌2 FLAC/09 姑苏行 .盛小云.flac","service":"mpd","name":"Track09","artist":"群星","album":"伶歌2","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=757&web=%E7%BE%A4%E6%98%9F/%E4%BC%B6%E6%AD%8C2/extralarge&path=%2FUSB%2FElements%2F%E9%9F%B3%E4%B9%90%2Fsacd%2F%E6%96%B0%E6%B4%BE%2F%E4%BC%B6%E6%AD%8C2%20FLAC&metadata=false","duration":274,"samplerate":"48 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 05 22:05:48 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer Mar 05 22:05:48 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:48 volumio volumio[1204]: info: ControllerMpd::seek Mar 05 22:05:48 volumio volumio[1204]: info: CoreStateMachine::pushState Mar 05 22:05:48 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:48 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 22:05:48 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Mar 05 22:05:48 volumio volumio[1204]: error: [2@0] {seek} Bad song index Mar 05 22:05:49 volumio volumio[1204]: info: CoreCommandRouter::volumioPlay Mar 05 22:05:49 volumio volumio[1204]: info: CoreStateMachine::play index undefined Mar 05 22:05:49 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 22:05:49 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:49 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer Mar 05 22:05:49 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:49 volumio volumio[1204]: info: ControllerMpd::resume Mar 05 22:05:49 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand play Mar 05 22:05:49 volumio volumio[1204]: info: sendMpdCommand play took 0 milliseconds Mar 05 22:05:53 volumio volumio[1204]: info: CoreCommandRouter::volumioPlay Mar 05 22:05:53 volumio volumio[1204]: info: CoreStateMachine::play index undefined Mar 05 22:05:53 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 22:05:53 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:53 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer Mar 05 22:05:53 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:53 volumio volumio[1204]: info: ControllerMpd::resume Mar 05 22:05:53 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand play Mar 05 22:05:53 volumio volumio[1204]: info: sendMpdCommand play took 0 milliseconds Mar 05 22:05:54 volumio volumio[1204]: info: CoreCommandRouter::volumioPlay Mar 05 22:05:54 volumio volumio[1204]: info: CoreStateMachine::play index undefined Mar 05 22:05:54 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 22:05:54 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:54 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer Mar 05 22:05:54 volumio volumio[1204]: info: CorePlayQueue::getTrack 8 Mar 05 22:05:54 volumio volumio[1204]: info: ControllerMpd::resume Mar 05 22:05:54 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand play Mar 05 22:05:54 volumio volumio[1204]: info: sendMpdCommand play took 1 milliseconds Mar 05 22:06:02 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 05 22:06:02 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 05 22:06:03 volumio volumio[1204]: info: Preload queue cleared Mar 05 22:06:07 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 05 22:06:08 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 05 22:06:08 volumio volumio[1204]: info: TuneIn handleBrowseUri: tunein/local Mar 05 22:06:09 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 05 22:06:09 volumio volumio[1204]: info: TuneIn handleBrowseUri: tunein/local Mar 05 22:06:10 volumio volumio[1204]: info: Preload queue cleared Mar 05 22:06:10 volumio volumio[1204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 22:06:10 volumio volumio[1204]: Error: connect ETIMEDOUT 199.16.156.71:80 Mar 05 22:06:10 volumio volumio[1204]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 05 22:06:10 volumio volumio[1204]: errno: -110, Mar 05 22:06:10 volumio volumio[1204]: code: 'ETIMEDOUT', Mar 05 22:06:10 volumio volumio[1204]: syscall: 'connect', Mar 05 22:06:10 volumio volumio[1204]: address: '199.16.156.71', Mar 05 22:06:10 volumio volumio[1204]: port: 80 Mar 05 22:06:10 volumio volumio[1204]: } Mar 05 22:06:10 volumio volumio[1204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 22:06:10 volumio sudo[3265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-05 22:05 Mar 05 22:06:10 volumio sudo[3265]: 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"