-- Logs begin at Fri 2024-09-06 14:36:22 UTC, end at Fri 2024-09-06 15:11:34 UTC. -- Sep 06 15:10:24 volumio volumio[7137]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 15:10:24 volumio volumio[7137]: info: Output device has changed, restarting MPD Sep 06 15:10:24 volumio sudo[7894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 06 15:10:24 volumio sudo[7894]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 15:10:24 volumio volumio[7137]: info: Output device has changed, restarting Shairport Sync Sep 06 15:10:24 volumio sudo[7894]: pam_unix(sudo:session): session closed for user root Sep 06 15:10:24 volumio sudo[7897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 15:10:24 volumio sudo[7897]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 15:10:24 volumio systemd[1]: Stopping Music Player Daemon... Sep 06 15:10:24 volumio volumio[7137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 15:10:24 volumio systemd[1]: Starting Music Player Daemon... Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 15:10:24 volumio volumio[7137]: info: Setting mixer Master for card HDA ATI SB Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 06 15:10:24 volumio volumio[7137]: info: Updating Volume Controller Parameters: Device: 1 Name: HDA ATI SB Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 15:10:24 volumio volumio[7137]: info: MPD Permissions set Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 06 15:10:24 volumio volumio[7137]: info: Starting Shairport Sync Sep 06 15:10:24 volumio sudo[7925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 15:10:24 volumio sudo[7925]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 15:10:24 volumio volumio[7137]: info: VolumeController:: Volume=41 Mute =false Sep 06 15:10:24 volumio volumio[7137]: info: CoreStateMachine::pushState Sep 06 15:10:24 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::volumioPushState Sep 06 15:10:24 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 15:10:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 15:10:24 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 06 15:10:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 15:10:24 volumio sudo[7925]: pam_unix(sudo:session): session closed for user root Sep 06 15:10:24 volumio volumio[7137]: info: Shairport-Sync Started Sep 06 15:10:25 volumio volumio[7137]: error: Upnp client error: Error: This socket has been ended by the other party Sep 06 15:10:25 volumio systemd[1]: Started Music Player Daemon. Sep 06 15:10:25 volumio sudo[7897]: pam_unix(sudo:session): session closed for user root Sep 06 15:10:25 volumio volumio[7137]: error: updateQueue error: null Sep 06 15:10:26 volumio volumio[7137]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 06 15:10:26 volumio volumio[7137]: info: CoreStateMachine::getcurrentVolume Sep 06 15:10:26 volumio volumio[7137]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 15:10:27 volumio volumio[7137]: info: VolumeController:: Volume=41 Mute =false Sep 06 15:10:27 volumio volumio[7137]: info: CoreStateMachine::pushState Sep 06 15:10:27 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:10:27 volumio volumio[7137]: info: CoreCommandRouter::volumioPushState Sep 06 15:10:27 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 15:10:27 volumio volumio[7137]: info: CoreStateMachine::updateTrackBlock Sep 06 15:10:27 volumio volumio[7137]: info: CorePlayQueue::getTrackBlock Sep 06 15:10:27 volumio volumio[7137]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 15:10:27 volumio volumio[7137]: info: VolumeController:: Volume=41 Mute =false Sep 06 15:10:27 volumio volumio[7137]: info: CoreStateMachine::pushState Sep 06 15:10:27 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:10:27 volumio volumio[7137]: info: CoreCommandRouter::volumioPushState Sep 06 15:10:27 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 15:10:57 volumio volumio[7137]: info: CoreCommandRouter::volumioPause Sep 06 15:10:57 volumio volumio[7137]: info: CoreStateMachine::pause Sep 06 15:10:57 volumio volumio[7137]: info: CoreStateMachine::stPlaybackTimer Sep 06 15:10:57 volumio volumio[7137]: info: CoreStateMachine::servicePause Sep 06 15:10:57 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:10:57 volumio volumio[7137]: info: CoreCommandRouter::servicePause Sep 06 15:10:57 volumio volumio[7137]: info: ControllerMpd::pause Sep 06 15:10:57 volumio volumio[7137]: verbose: ControllerMpd::sendMpdCommand pause Sep 06 15:10:57 volumio volumio[7137]: info: sendMpdCommand pause took 2 milliseconds Sep 06 15:10:59 volumio volumio[7137]: info: CoreCommandRouter::volumioPause Sep 06 15:10:59 volumio volumio[7137]: info: CoreStateMachine::pause Sep 06 15:11:08 volumio volumio[7137]: info: CoreCommandRouter::volumioPause Sep 06 15:11:08 volumio volumio[7137]: info: CoreStateMachine::pause Sep 06 15:11:16 volumio volumio[7137]: info: CoreCommandRouter::volumioPause Sep 06 15:11:16 volumio volumio[7137]: info: CoreStateMachine::pause Sep 06 15:11:26 volumio volumio[7137]: info: CoreCommandRouter::volumioSeek Sep 06 15:11:26 volumio volumio[7137]: info: CoreStateMachine::seek Sep 06 15:11:26 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:11:26 volumio volumio[7137]: info: TRACKBLOCK {"uri":"mnt/USB/无损音乐/姚缨格/姚璎格-茫茫草原.flac","service":"mpd","name":"茫茫草原","artist":"姚璎格","album":"红·苏联经典","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=219&web=%E5%A7%9A%E7%92%8E%E6%A0%BC/%E7%BA%A2%C2%B7%E8%8B%8F%E8%81%94%E7%BB%8F%E5%85%B8/extralarge&path=%2FUSB%2F%E6%97%A0%E6%8D%9F%E9%9F%B3%E4%B9%90%2F%E5%A7%9A%E7%BC%A8%E6%A0%BC&metadata=false","duration":302,"trackType":"flac"} Sep 06 15:11:26 volumio volumio[7137]: info: CoreStateMachine::startPlaybackTimer Sep 06 15:11:26 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:11:26 volumio volumio[7137]: info: ControllerMpd::seek Sep 06 15:11:26 volumio volumio[7137]: info: CoreStateMachine::pushState Sep 06 15:11:26 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:11:26 volumio volumio[7137]: info: CoreCommandRouter::volumioPushState Sep 06 15:11:26 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 15:11:26 volumio volumio[7137]: error: [2@0] {} Bad song index Sep 06 15:11:30 volumio volumio[7137]: info: CoreCommandRouter::volumioSeek Sep 06 15:11:30 volumio volumio[7137]: info: CoreStateMachine::seek Sep 06 15:11:30 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:11:30 volumio volumio[7137]: info: TRACKBLOCK {"uri":"mnt/USB/无损音乐/姚缨格/姚璎格-茫茫草原.flac","service":"mpd","name":"茫茫草原","artist":"姚璎格","album":"红·苏联经典","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=219&web=%E5%A7%9A%E7%92%8E%E6%A0%BC/%E7%BA%A2%C2%B7%E8%8B%8F%E8%81%94%E7%BB%8F%E5%85%B8/extralarge&path=%2FUSB%2F%E6%97%A0%E6%8D%9F%E9%9F%B3%E4%B9%90%2F%E5%A7%9A%E7%BC%A8%E6%A0%BC&metadata=false","duration":302,"trackType":"flac"} Sep 06 15:11:30 volumio volumio[7137]: info: CoreStateMachine::startPlaybackTimer Sep 06 15:11:30 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:11:30 volumio volumio[7137]: info: ControllerMpd::seek Sep 06 15:11:30 volumio volumio[7137]: info: CoreStateMachine::pushState Sep 06 15:11:30 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:11:30 volumio volumio[7137]: info: CoreCommandRouter::volumioPushState Sep 06 15:11:30 volumio volumio[7137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 15:11:30 volumio volumio[7137]: error: [2@0] {} Bad song index Sep 06 15:11:33 volumio volumio[7137]: info: CoreCommandRouter::volumioPlay Sep 06 15:11:33 volumio volumio[7137]: UNSET VOLATILE Sep 06 15:11:33 volumio volumio[7137]: info: CoreStateMachine::play index undefined Sep 06 15:11:33 volumio volumio[7137]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 15:11:33 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:11:33 volumio volumio[7137]: info: CoreStateMachine::startPlaybackTimer Sep 06 15:11:33 volumio volumio[7137]: info: CorePlayQueue::getTrack 142 Sep 06 15:11:33 volumio volumio[7137]: info: ControllerMpd::resume Sep 06 15:11:33 volumio volumio[7137]: verbose: ControllerMpd::sendMpdCommand play Sep 06 15:11:33 volumio volumio[7137]: info: sendMpdCommand play took 4 milliseconds Sep 06 15:11:33 volumio volumio[7137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 15:11:34 volumio volumio[7137]: { Error: connect ETIMEDOUT 31.13.87.9:443 Sep 06 15:11:34 volumio volumio[7137]: at Object._errnoException (util.js:1022:11) Sep 06 15:11:34 volumio volumio[7137]: at _exceptionWithHostPort (util.js:1044:20) Sep 06 15:11:34 volumio volumio[7137]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 06 15:11:34 volumio volumio[7137]: code: 'ETIMEDOUT', Sep 06 15:11:34 volumio volumio[7137]: errno: 'ETIMEDOUT', Sep 06 15:11:34 volumio volumio[7137]: syscall: 'connect', Sep 06 15:11:34 volumio volumio[7137]: address: '31.13.87.9', Sep 06 15:11:34 volumio volumio[7137]: port: 443 } Sep 06 15:11:34 volumio volumio[7137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 15:11:34 volumio sudo[8081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 15:10 Sep 06 15:11:34 volumio sudo[8081]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="537e3f50b5a8aa9bdf42f94adc80b348609b5c47" VOLUMIO_BE_VERSION="d7e6c614214d5dc616fddcbcfefc47e1990da7b1" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 8 12:33:16 CEST 2021" VOLUMIO_VERSION="2.909" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="6e5c976a6d85b8b7d7e4e3388d208cd8"