-- Logs begin at Wed 2024-08-28 09:46:46 UTC, end at Wed 2024-08-28 09:53:56 UTC. -- Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:52:05 volumio volumio[910]: info: Retrieving Cloud Streaming UI Aug 28 09:52:05 volumio volumio[910]: info: Getting Tidal Cloud Configuration Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:52:05 volumio volumio[910]: info: Getting Qobuz Cloud Configuration Aug 28 09:52:05 volumio volumio[910]: info: Asking plugin for UI Config Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:52:05 volumio volumio[910]: info: Getting Spotify Cloud Configuration Aug 28 09:52:05 volumio volumio[910]: info: Asking plugin for UI Config Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:52:05 volumio volumio[910]: info: Saving Spotify Acccount Aug 28 09:52:05 volumio volumio[910]: info: Got Tidal Cloud Configuration Aug 28 09:52:05 volumio volumio[910]: info: Got it Aug 28 09:52:05 volumio volumio[910]: info: Got it Aug 28 09:52:05 volumio volumio[910]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:52:05 volumio volumio[910]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 09:52:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 28 09:52:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:52:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:52:07 volumio volumio[910]: info: Discovery: Getting this device information Aug 28 09:52:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Aug 28 09:52:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Aug 28 09:52:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:52:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:52:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 28 09:52:08 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand rescan Aug 28 09:52:08 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Aug 28 09:52:08 volumio volumio[910]: info: Aug 28 09:52:08 volumio volumio[910]: ---------------------------- MPD announces state update: update Aug 28 09:52:08 volumio volumio[910]: info: sendMpdCommand rescan took 6 milliseconds Aug 28 09:52:08 volumio volumio[910]: info: ControllerMpd::getState Aug 28 09:52:08 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Aug 28 09:52:08 volumio volumio[910]: info: sendMpdCommand status took 6 milliseconds Aug 28 09:52:08 volumio volumio[910]: info: sendMpdCommand status took 1 milliseconds Aug 28 09:52:08 volumio volumio[910]: verbose: ControllerMpd::parseState Aug 28 09:52:18 volumio volumio[910]: info: Command Router : Notfying DB Updatetrue Aug 28 09:52:18 volumio volumio[910]: verbose: ControllerMpd::parseState Aug 28 09:52:18 volumio volumio[910]: info: ControllerMpd::pushState Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::servicePushState Aug 28 09:52:18 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:18 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:18 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:18 volumio volumio[910]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 09:52:18 volumio volumio[910]: verbose: CURRENT POSITION 0 Aug 28 09:52:18 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Aug 28 09:52:18 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Aug 28 09:52:18 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:18 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:18 volumio volumio[910]: info: No code Aug 28 09:52:18 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:18 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:18 volumio volumio[910]: info: ------------------------------ 9522ms Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 09:52:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 09:52:20 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent Aug 28 09:52:21 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 09:52:24 volumio volumio[910]: info: Getting Alsa Cards List without I2S DAC Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 28 09:52:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 09:52:36 volumio volumio[910]: info: MPD Database updated - AlbumList cache refreshed Aug 28 09:52:36 volumio volumio[910]: info: Aug 28 09:52:36 volumio volumio[910]: ---------------------------- MPD announces state update: database Aug 28 09:52:36 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Aug 28 09:52:36 volumio volumio[910]: info: Aug 28 09:52:36 volumio volumio[910]: ---------------------------- MPD announces state update: update Aug 28 09:52:36 volumio volumio[910]: info: ControllerMpd::getState Aug 28 09:52:36 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Aug 28 09:52:36 volumio volumio[910]: info: ControllerMpd::getState Aug 28 09:52:36 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Aug 28 09:52:36 volumio volumio[910]: info: sendMpdCommand status took 64 milliseconds Aug 28 09:52:36 volumio volumio[910]: info: sendMpdCommand status took 64 milliseconds Aug 28 09:52:36 volumio volumio[910]: info: sendMpdCommand status took 64 milliseconds Aug 28 09:52:36 volumio volumio[910]: verbose: ControllerMpd::parseState Aug 28 09:52:42 volumio volumio[910]: info: Command Router : Notfying DB Updatefalse Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent Aug 28 09:52:42 volumio volumio[910]: verbose: ControllerMpd::parseState Aug 28 09:52:42 volumio volumio[910]: verbose: ControllerMpd::parseState Aug 28 09:52:42 volumio volumio[910]: info: ControllerMpd::pushState Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::servicePushState Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 09:52:42 volumio volumio[910]: verbose: CURRENT POSITION 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:42 volumio volumio[910]: info: No code Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:42 volumio volumio[910]: info: ControllerMpd::pushState Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::servicePushState Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 09:52:42 volumio volumio[910]: verbose: CURRENT POSITION 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:42 volumio volumio[910]: info: No code Aug 28 09:52:42 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:42 volumio volumio[910]: info: ------------------------------ 5868ms Aug 28 09:52:42 volumio volumio[910]: info: ------------------------------ 5868ms Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:52:42 volumio volumio[910]: info: Discovery: Getting this device information Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:52:42 volumio volumio[910]: info: Discovery: Getting this device information Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Aug 28 09:52:42 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:52:42 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:52:51 volumio volumio[910]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 28 09:52:51 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 28 09:52:51 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Aug 28 09:52:51 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:51 volumio volumio[910]: info: Restoring Previous Volume level: false true Aug 28 09:52:51 volumio volumio[910]: info: VolumeController::SetAlsaVolume100 Aug 28 09:52:51 volumio volumio[910]: info: Enable softmixer device for audio device number 1 Aug 28 09:52:51 volumio volumio[910]: info: Enable softmixer device for audio device number 1 Aug 28 09:52:51 volumio volumio[910]: info: CoreCommandRouter::volumioStop Aug 28 09:52:51 volumio volumio[910]: info: CoreStateMachine::stop Aug 28 09:52:51 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 09:52:51 volumio volumio[910]: verbose: UNSET VOLATILE Aug 28 09:52:51 volumio volumio[910]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 28 09:52:51 volumio volumio[910]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Aug 28 09:52:51 volumio volumio[910]: info: Output device has changed, restarting MPD Aug 28 09:52:51 volumio sudo[2014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 09:52:51 volumio volumio[910]: info: Output device has changed, restarting Shairport Sync Aug 28 09:52:51 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:51 volumio sudo[2014]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:52:51 volumio sudo[2014]: pam_unix(sudo:session): session closed for user root Aug 28 09:52:51 volumio sudo[2018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 09:52:51 volumio sudo[2018]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:52:51 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 09:52:51 volumio volumio[910]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 09:52:51 volumio volumio[910]: info: Volume configurations have been set Aug 28 09:52:51 volumio volumio[910]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 28 09:52:51 volumio volumio[910]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Aug 28 09:52:52 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:52:52 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:52 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:52 volumio volumio[910]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 28 09:52:52 volumio volumio[910]: info: MPD Permissions set Aug 28 09:52:52 volumio volumio[910]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 28 09:52:52 volumio volumio[910]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 28 09:52:52 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:52 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:52 volumio volumio[910]: info: Asound.conf file written Aug 28 09:52:52 volumio sudo[2042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 28 09:52:52 volumio sudo[2042]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:52:52 volumio sudo[2042]: pam_unix(sudo:session): session closed for user root Aug 28 09:52:52 volumio systemd[1]: Starting Music Player Daemon... Aug 28 09:52:52 volumio systemd[1]: Started Music Player Daemon. Aug 28 09:52:52 volumio sudo[2018]: pam_unix(sudo:session): session closed for user root Aug 28 09:52:53 volumio volumio[910]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 28 09:52:53 volumio volumio[910]: Found hardware: "bcm2835_headpho" "Broadcom Mixer" "" "" "" Aug 28 09:52:53 volumio volumio[910]: Hardware is initialized using a generic method Aug 28 09:52:53 volumio volumio[910]: info: Output device has changed, restarting MPD Aug 28 09:52:53 volumio sudo[2066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 09:52:53 volumio volumio[910]: info: Output device has changed, restarting Shairport Sync Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:52:53 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Aug 28 09:52:53 volumio sudo[2069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 09:52:53 volumio sudo[2069]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:52:53 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 09:52:53 volumio volumio[910]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 28 09:52:53 volumio volumio[910]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio systemd[1]: Starting Music Player Daemon... Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 09:52:53 volumio volumio[910]: info: Getting Alsa Cards List without I2S DAC Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 09:52:53 volumio volumio[910]: error: Upnp client error: Error: This socket has been ended by the other party Aug 28 09:52:53 volumio volumio[910]: info: MPD Permissions set Aug 28 09:52:53 volumio volumio[910]: info: VolumeController:: Volume=undefined Mute =false Aug 28 09:52:53 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:53 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:53 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:53 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:53 volumio volumio[910]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:52:53 volumio volumio[910]: info: Starting Shairport Sync Aug 28 09:52:53 volumio volumio[910]: info: Starting Shairport Sync Aug 28 09:52:53 volumio sudo[2111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 09:52:54 volumio sudo[2111]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:52:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 09:52:54 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 28 09:52:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 09:52:54 volumio sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 09:52:54 volumio sudo[2111]: pam_unix(sudo:session): session closed for user root Aug 28 09:52:54 volumio sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:52:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 09:52:54 volumio volumio[910]: info: Shairport-Sync Started Aug 28 09:52:54 volumio mpd[2099]: output: No 'AudioOutput' defined in config file Aug 28 09:52:54 volumio mpd[2099]: output: Attempt to detect audio output device Aug 28 09:52:54 volumio mpd[2099]: output: Attempting to detect a alsa audio device Aug 28 09:52:54 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 28 09:52:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 09:52:54 volumio sudo[2115]: pam_unix(sudo:session): session closed for user root Aug 28 09:52:54 volumio mpd[2099]: output: Successfully detected a alsa audio device Aug 28 09:52:54 volumio systemd[1]: Started Music Player Daemon. Aug 28 09:52:54 volumio volumio[910]: info: Shairport-Sync Started Aug 28 09:52:54 volumio volumio[910]: error: updateQueue error: null Aug 28 09:52:54 volumio sudo[2069]: pam_unix(sudo:session): session closed for user root Aug 28 09:52:54 volumio volumio[910]: error: updateQueue error: null Aug 28 09:52:55 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 28 09:52:55 volumio sudo[2156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 09:52:55 volumio sudo[2156]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:52:55 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 09:52:55 volumio systemd[1]: Starting Music Player Daemon... Aug 28 09:52:56 volumio volumio[910]: info: VolumeController::SetAlsaVolumeNaN Aug 28 09:52:56 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:52:56 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:52:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:52:56 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:52:57 volumio mpd[2162]: output: No 'AudioOutput' defined in config file Aug 28 09:52:57 volumio mpd[2162]: output: Attempt to detect audio output device Aug 28 09:52:57 volumio mpd[2162]: output: Attempting to detect a alsa audio device Aug 28 09:52:57 volumio mpd[2162]: output: Successfully detected a alsa audio device Aug 28 09:52:57 volumio systemd[1]: Started Music Player Daemon. Aug 28 09:52:57 volumio sudo[2156]: pam_unix(sudo:session): session closed for user root Aug 28 09:52:57 volumio volumio[910]: error: updateQueue error: null Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:53:07 volumio volumio[910]: info: Discovery: Getting this device information Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:53:07 volumio volumio[910]: info: VolumeController::SetAlsaVolume5 Aug 28 09:53:07 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:53:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:53:07 volumio volumio[910]: info: VolumeController::SetAlsaVolume25 Aug 28 09:53:07 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:53:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:53:07 volumio volumio[910]: info: VolumeController::SetAlsaVolume45 Aug 28 09:53:07 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:53:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:53:07 volumio volumio[910]: info: VolumeController::SetAlsaVolume40 Aug 28 09:53:07 volumio volumio[910]: info: CoreStateMachine::pushState Aug 28 09:53:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Aug 28 09:53:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:53:09 volumio volumio[910]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 09:53:09 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:53:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 09:53:11 volumio volumio[910]: info: CURURI: music-library Aug 28 09:53:11 volumio volumio[910]: error: Failed LSINFO: Error: [50@0] {} No database Aug 28 09:53:15 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 09:53:15 volumio volumio[910]: info: CURURI: music-library Aug 28 09:53:15 volumio volumio[910]: error: Failed LSINFO: Error: [50@0] {} No database Aug 28 09:53:21 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 09:53:21 volumio volumio[910]: info: CURURI: albums:// Aug 28 09:53:21 volumio volumio[910]: listAlbums - loading Albums from cache Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:53:37 volumio volumio[910]: info: Discovery: Getting this device information Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:37 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 28 09:53:37 volumio sudo[2211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 28 09:53:37 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:37 volumio sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 28 09:53:37 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:37 volumio sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:37 volumio sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 28 09:53:37 volumio sudo[2217]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:37 volumio sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:37 volumio sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 28 09:53:37 volumio sudo[2227]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:37 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:37 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 28 09:53:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 28 09:53:38 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 09:53:38 volumio sudo[2263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 09:53:38 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:38 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:38 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:38 volumio sudo[2272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 09:53:38 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:38 volumio sudo[2272]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:41 volumio sudo[2272]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:54 volumio volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 09:53:54 volumio volumio[910]: { Error: connect ETIMEDOUT 203.111.254.117:443 Aug 28 09:53:54 volumio volumio[910]: at Object._errnoException (util.js:1022:11) Aug 28 09:53:54 volumio volumio[910]: at _exceptionWithHostPort (util.js:1044:20) Aug 28 09:53:54 volumio volumio[910]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 28 09:53:54 volumio volumio[910]: code: 'ETIMEDOUT', Aug 28 09:53:54 volumio volumio[910]: errno: 'ETIMEDOUT', Aug 28 09:53:54 volumio volumio[910]: syscall: 'connect', Aug 28 09:53:54 volumio volumio[910]: address: '203.111.254.117', Aug 28 09:53:54 volumio volumio[910]: port: 443 } Aug 28 09:53:54 volumio volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 09:53:56 volumio sudo[2348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 09:52 Aug 28 09:53:56 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"