-- Logs begin at Tue 2024-11-19 22:20:42 CET, end at Sun 2024-11-24 21:10:14 CET. -- Nov 24 21:09:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:09:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:09:11 volumio volumio[828]: info: Discovery: Getting this device information Nov 24 21:09:11 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:09:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:09:18 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:09:18 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:09:18 volumio volumio[828]: info: Discovery: Getting this device information Nov 24 21:09:18 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:09:18 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:09:26 volumio volumio[828]: info: CoreCommandRouter::volumioGetQueue Nov 24 21:09:26 volumio volumio[828]: info: CoreStateMachine::getQueue Nov 24 21:09:26 volumio volumio[828]: info: CorePlayQueue::getQueue Nov 24 21:09:33 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:09:33 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:09:33 volumio volumio[828]: info: Discovery: Getting this device information Nov 24 21:09:33 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:09:33 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 24 21:09:35 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 21:10:03 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:10:03 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:10:03 volumio volumio[828]: info: Discovery: Getting this device information Nov 24 21:10:03 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:03 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:10:07 volumio volumio[828]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:07 volumio volumio[828]: info: Restoring Previous Volume level: 42 false true Nov 24 21:10:07 volumio volumio[828]: info: VolumeController::SetAlsaVolume100 Nov 24 21:10:07 volumio volumio[828]: info: Enable softmixer device for audio device number 1 Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::volumioStop Nov 24 21:10:07 volumio volumio[828]: info: CoreStateMachine::stop Nov 24 21:10:07 volumio volumio[828]: info: CoreStateMachine::serviceStop Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::serviceStop Nov 24 21:10:07 volumio volumio[828]: info: Enable softmixer device for audio device undefined Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 21:10:07 volumio volumio[828]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 24 21:10:07 volumio volumio[828]: info: Disabling external Volume Control Nov 24 21:10:07 volumio volumio[828]: info: Output device has changed, restarting MPD Nov 24 21:10:07 volumio sudo[30034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 21:10:07 volumio sudo[30034]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:07 volumio sudo[30034]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:07 volumio volumio[828]: info: Output device has changed, restarting Shairport Sync Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:10:07 volumio sudo[30037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 21:10:07 volumio sudo[30037]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:07 volumio systemd[1]: Stopping Music Player Daemon... Nov 24 21:10:07 volumio volumio[828]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 21:10:07 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:07 volumio systemd[1]: mpd.service: Succeeded. Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio volumio[828]: info: Volume configurations have been set Nov 24 21:10:07 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio systemd[1]: Stopped Music Player Daemon. Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:07 volumio systemd[1]: Starting Music Player Daemon... Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 21:10:08 volumio volumio[828]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 24 21:10:08 volumio volumio[828]: info: Disabling external Volume Control Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:08 volumio volumio[828]: info: CoreStateMachine::pushState Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioPushState Nov 24 21:10:08 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:08 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:08 volumio sudo[30042]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 21:10:08 volumio sudo[30042]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:08 volumio sudo[30058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:08 volumio sudo[30042]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:08 volumio sudo[30058]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:08 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:08 volumio sudo[30063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:08 volumio sudo[30063]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:08 volumio sudo[30066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:08 volumio sudo[30066]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:08 volumio sudo[30069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:08 volumio volumio[828]: info: CoreStateMachine::pushState Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioPushState Nov 24 21:10:08 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Nov 24 21:10:08 volumio sudo[30069]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:08 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Nov 24 21:10:08 volumio systemd[1]: volspotconnect.service: Succeeded. Nov 24 21:10:08 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 21:10:08 volumio volumio[828]: info: MPD Permissions set Nov 24 21:10:08 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 21:10:08 volumio sudo[30063]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:08 volumio sudo[30066]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:08 volumio sudo[30058]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:08 volumio volumio[30071]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 24 21:10:08 volumio volumio[30071]: Reading Config from "/tmp/volspotify.toml" Nov 24 21:10:08 volumio volumio[30071]: Malformed config key: unterminated string at line 29 column 20 Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::servicePushState Nov 24 21:10:08 volumio volumio[828]: info: CoreStateMachine::pushState Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioPushState Nov 24 21:10:08 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Nov 24 21:10:08 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::servicePushState Nov 24 21:10:08 volumio volumio[828]: info: CoreStateMachine::pushState Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioPushState Nov 24 21:10:08 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 21:10:08 volumio volumio[828]: [SpotifyConnect] Vollibrespot Daemon service restarted! Nov 24 21:10:08 volumio volumio[828]: [SpotifyConnect] Vollibrespot Daemon service restarted! Nov 24 21:10:08 volumio volumio[828]: info: Software Volume ALSA configuration written Nov 24 21:10:08 volumio volumio[828]: info: Preparing to generate the ALSA configuration file Nov 24 21:10:08 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 21:10:08 volumio sudo[30069]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:08 volumio volumio[30074]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 24 21:10:08 volumio volumio[30074]: Reading Config from "/tmp/volspotify.toml" Nov 24 21:10:08 volumio volumio[30074]: Malformed config key: unterminated string at line 29 column 20 Nov 24 21:10:08 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Nov 24 21:10:08 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 24 21:10:08 volumio volumio[828]: info: VolumeController:: Volume=100 Mute =false Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:08 volumio volumio[828]: info: CoreStateMachine::pushState Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioPushState Nov 24 21:10:08 volumio volumio[828]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 24 21:10:08 volumio volumio[828]: info: Reading ALSA contributions from plugins. Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 21:10:08 volumio volumio[828]: [SpotifyConnect] Vollibrespot Daemon service restarted! Nov 24 21:10:08 volumio volumio[828]: info: VolumeController::SetAlsaVolume0 Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:08 volumio volumio[828]: info: CoreStateMachine::pushState Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::volumioPushState Nov 24 21:10:08 volumio volumio[828]: info: Starting Shairport Sync Nov 24 21:10:08 volumio sudo[30087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 21:10:08 volumio sudo[30087]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:08 volumio volumio[828]: info: Asound.conf file written Nov 24 21:10:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 21:10:08 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 24 21:10:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 21:10:08 volumio sudo[30090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 24 21:10:08 volumio sudo[30090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:08 volumio sudo[30090]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 21:10:08 volumio sudo[30087]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:08 volumio volumio[828]: info: Output device has changed, restarting MPD Nov 24 21:10:08 volumio sudo[30099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 21:10:08 volumio sudo[30099]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:08 volumio volumio[828]: info: Output device has changed, restarting Shairport Sync Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:08 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:10:08 volumio sudo[30099]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:08 volumio sudo[30102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 21:10:08 volumio sudo[30102]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:09 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 24 21:10:09 volumio systemd[1]: mpd.service: Succeeded. Nov 24 21:10:09 volumio systemd[1]: Stopped Music Player Daemon. Nov 24 21:10:09 volumio volumio[828]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 21:10:09 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:09 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:09 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:09 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:09 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:09 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:09 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:09 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:09 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:09 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:09 volumio systemd[1]: Starting Music Player Daemon... Nov 24 21:10:09 volumio sudo[30107]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 21:10:09 volumio sudo[30107]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:09 volumio sudo[30107]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:10 volumio volumio[828]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 24 21:10:10 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Nov 24 21:10:10 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Nov 24 21:10:10 volumio volumio[828]: /usr/share/alsa/init/bcm2835:20: control element not found Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: Output device has changed, restarting MPD Nov 24 21:10:10 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 21:10:10 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 21:10:10 volumio volumio[30115]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 24 21:10:10 volumio volumio[30115]: Reading Config from "/tmp/volspotify.toml" Nov 24 21:10:10 volumio volumio[30115]: Malformed config key: missing field `Authentication` Nov 24 21:10:10 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Nov 24 21:10:10 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 24 21:10:10 volumio sudo[30119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 21:10:10 volumio sudo[30119]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:10 volumio sudo[30119]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:10 volumio volumio[828]: info: Output device has changed, restarting Shairport Sync Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:10:10 volumio sudo[30123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 21:10:10 volumio sudo[30123]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:10 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 24 21:10:10 volumio systemd[1]: mpd.service: Succeeded. Nov 24 21:10:10 volumio systemd[1]: Stopped Music Player Daemon. Nov 24 21:10:10 volumio volumio[828]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:10 volumio systemd[1]: Starting Music Player Daemon... Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Creating VLS config file Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 21:10:10 volumio volumio[828]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 24 21:10:10 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 24 21:10:10 volumio volumio[828]: info: Disabling external Volume Control Nov 24 21:10:10 volumio sudo[30128]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 21:10:10 volumio sudo[30128]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:10 volumio sudo[30128]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:10 volumio sudo[30141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:10 volumio sudo[30141]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:10 volumio sudo[30144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:10 volumio sudo[30144]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:10 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 21:10:10 volumio sudo[30147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:10 volumio sudo[30147]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:10 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 21:10:10 volumio sudo[30141]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:10 volumio sudo[30144]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:10 volumio volumio[30149]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 24 21:10:10 volumio volumio[30149]: Reading Config from "/tmp/volspotify.toml" Nov 24 21:10:10 volumio volumio[30149]: Malformed config key: missing field `Authentication` Nov 24 21:10:10 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Nov 24 21:10:10 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 24 21:10:10 volumio sudo[30151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:10 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 21:10:10 volumio sudo[30151]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:10 volumio volumio[828]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 24 21:10:10 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 21:10:10 volumio sudo[30155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:10 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 21:10:10 volumio sudo[30155]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:10 volumio sudo[30147]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:11 volumio sudo[30151]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:11 volumio volumio[30157]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 24 21:10:11 volumio volumio[30157]: Reading Config from "/tmp/volspotify.toml" Nov 24 21:10:11 volumio volumio[30157]: Malformed config key: missing field `Authentication` Nov 24 21:10:11 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Nov 24 21:10:11 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 24 21:10:11 volumio sudo[30161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 24 21:10:11 volumio volumio[828]: [SpotifyConnect] Vollibrespot Daemon service restarted! Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:11 volumio volumio[828]: info: CoreStateMachine::pushState Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::volumioPushState Nov 24 21:10:11 volumio sudo[30161]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:11 volumio volumio[828]: info: MPD Permissions set Nov 24 21:10:11 volumio volumio[828]: info: Shairport-Sync Started Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 21:10:11 volumio systemd[1]: volspotconnect.service: Start request repeated too quickly. Nov 24 21:10:11 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 24 21:10:11 volumio systemd[1]: Failed to start Volspotconnect2 Daemon. Nov 24 21:10:11 volumio sudo[30161]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:11 volumio sudo[30155]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:10:11 volumio volumio[828]: info: Starting Shairport Sync Nov 24 21:10:11 volumio volumio[828]: info: Starting Shairport Sync Nov 24 21:10:11 volumio sudo[30172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 21:10:11 volumio sudo[30172]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:11 volumio sudo[30175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 21:10:11 volumio sudo[30175]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 21:10:11 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 24 21:10:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 21:10:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 21:10:11 volumio sudo[30172]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:11 volumio volumio[828]: info: Shairport-Sync Started Nov 24 21:10:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 21:10:11 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 24 21:10:11 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 24 21:10:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 21:10:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 21:10:11 volumio sudo[30175]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:11 volumio volumio[828]: info: Shairport-Sync Started Nov 24 21:10:12 volumio volumio[828]: info: VolumeController::SetAlsaVolume42 Nov 24 21:10:12 volumio volumio[828]: info: CoreCommandRouter::volumioGetState Nov 24 21:10:12 volumio volumio[828]: info: CoreStateMachine::pushState Nov 24 21:10:12 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:10:12 volumio volumio[828]: info: CoreCommandRouter::volumioPushState Nov 24 21:10:12 volumio volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 24 21:10:12 volumio sudo[30188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 21:10:12 volumio sudo[30188]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 24 21:10:12 volumio systemd[1]: mpd.service: Succeeded. Nov 24 21:10:12 volumio systemd[1]: Stopped Music Player Daemon. Nov 24 21:10:12 volumio systemd[1]: Starting Music Player Daemon... Nov 24 21:10:12 volumio sudo[30190]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 21:10:12 volumio sudo[30190]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:10:12 volumio sudo[30190]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:13 volumio mpd[30192]: Nov 24 21:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 21:10:13 volumio systemd[1]: Started Music Player Daemon. Nov 24 21:10:13 volumio sudo[30102]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:13 volumio sudo[30123]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:13 volumio sudo[30037]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:13 volumio sudo[30188]: pam_unix(sudo:session): session closed for user root Nov 24 21:10:13 volumio volumio[828]: error: MPD error: The expression evaluated to a falsy value: Nov 24 21:10:13 volumio volumio[828]: assert.ok(self.idling) Nov 24 21:10:13 volumio volumio[828]: error: The expression evaluated to a falsy value: Nov 24 21:10:13 volumio volumio[828]: assert.ok(self.idling) Nov 24 21:10:13 volumio volumio[828]: error: MPD error: The expression evaluated to a falsy value: Nov 24 21:10:13 volumio volumio[828]: assert.ok(self.idling) Nov 24 21:10:13 volumio volumio[828]: error: The expression evaluated to a falsy value: Nov 24 21:10:13 volumio volumio[828]: assert.ok(self.idling) Nov 24 21:10:13 volumio volumio[828]: error: MPD error: The expression evaluated to a falsy value: Nov 24 21:10:13 volumio volumio[828]: assert.ok(self.idling) Nov 24 21:10:13 volumio volumio[828]: error: The expression evaluated to a falsy value: Nov 24 21:10:13 volumio volumio[828]: assert.ok(self.idling) Nov 24 21:10:13 volumio volumio[828]: error: updateQueue error: null Nov 24 21:10:14 volumio volumio[828]: info: Checking Spotify Web API Nov 24 21:10:14 volumio volumio[828]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 21:10:14 volumio volumio[828]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Nov 24 21:10:14 volumio volumio[828]: at healthCheck (dgram.js:897:11) Nov 24 21:10:14 volumio volumio[828]: at Socket.send (dgram.js:622:3) Nov 24 21:10:14 volumio volumio[828]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) Nov 24 21:10:14 volumio volumio[828]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) Nov 24 21:10:14 volumio volumio[828]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2723:18) Nov 24 21:10:14 volumio volumio[828]: at listOnTimeout (internal/timers.js:554:17) Nov 24 21:10:14 volumio volumio[828]: at processTimers (internal/timers.js:497:7) { Nov 24 21:10:14 volumio volumio[828]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Nov 24 21:10:14 volumio volumio[828]: } Nov 24 21:10:14 volumio volumio[828]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 21:10:14 volumio sudo[30204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 21:09 Nov 24 21:10:14 volumio sudo[30204]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"