-- Logs begin at Sun 2024-03-17 06:24:21 UTC, end at Tue 2024-04-02 15:02:10 UTC. -- Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:01:11 volumiojh volumio[648]: info: Retrieving Cloud Streaming UI Apr 02 15:01:11 volumiojh volumio[648]: info: Getting Tidal Cloud Configuration Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:01:11 volumiojh volumio[648]: info: Getting Qobuz Cloud Configuration Apr 02 15:01:11 volumiojh volumio[648]: info: Asking plugin for UI Config Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:01:11 volumiojh volumio[648]: info: Getting Spotify Cloud Configuration Apr 02 15:01:11 volumiojh volumio[648]: info: Asking plugin for UI Config Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:01:11 volumiojh volumio[648]: info: Saving Spotify Acccount Apr 02 15:01:11 volumiojh volumio[648]: info: Got it Apr 02 15:01:11 volumiojh volumio[648]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 02 15:01:11 volumiojh volumio[648]: info: Got Tidal Cloud Configuration Apr 02 15:01:11 volumiojh volumio[648]: info: Got it Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::volumioGetBrowseSources Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:11 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 02 15:01:15 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:19 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:23 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:27 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:31 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:35 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:39 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:43 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:47 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:51 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:55 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 15:01:58 volumiojh volumio[648]: info: Getting Alsa Cards List without I2S DAC Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 02 15:01:58 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 02 15:02:01 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 15:02:03 volumiojh volumio[648]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:02:03 volumiojh volumio[648]: info: Device 1 does not have any Mixer Control Available Apr 02 15:02:03 volumiojh volumio[648]: [SpotifyConnect] Creating VLS config file Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:03 volumiojh vtcs[1845]: [2024-04-02 15:02:03.604] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 02 15:02:03 volumiojh vtcs[1845]: [2024-04-02 15:02:03.605] [tisoc] [error] [SpkconServer.cpp:373] recv error. client fd=7 errorno=104 error=Connection reset by peer Apr 02 15:02:03 volumiojh vtcs[1845]: [2024-04-02 15:02:03.605] [tisoc] [error] [SpkconServer.cpp:368] recv error. socket disconnected Apr 02 15:02:03 volumiojh volumio[648]: [SpotifyConnect] Creating VLS config file Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:03 volumiojh sudo[3910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 15:02:03 volumiojh sudo[3910]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:03 volumiojh systemd[1]: Stopping Volumio Tidal Connect Service... Apr 02 15:02:03 volumiojh systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 02 15:02:03 volumiojh systemd[1]: vtcs.service: Succeeded. Apr 02 15:02:03 volumiojh systemd[1]: Stopped Volumio Tidal Connect Service. Apr 02 15:02:03 volumiojh sudo[3910]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 02 15:02:03 volumiojh volumio[648]: info: Updating Volume Controller Parameters: Device: 1 Name: Generic I2S DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:02:03 volumiojh volumio[648]: info: CoreStateMachine::pushState Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::volumioPushState Apr 02 15:02:03 volumiojh volumio[648]: info: Preparing to generate the ALSA configuration file Apr 02 15:02:03 volumiojh volumio[648]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 02 15:02:03 volumiojh sudo[3916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 02 15:02:03 volumiojh sudo[3916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:03 volumiojh volumio[648]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 02 15:02:03 volumiojh systemd[1]: Stopping Volspotconnect2 Daemon... Apr 02 15:02:03 volumiojh systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 02 15:02:03 volumiojh systemd[1]: volspotconnect.service: Succeeded. Apr 02 15:02:03 volumiojh systemd[1]: Stopped Volspotconnect2 Daemon. Apr 02 15:02:03 volumiojh systemd[1]: Started Volspotconnect2 Daemon. Apr 02 15:02:03 volumiojh sudo[3916]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:03 volumiojh sudo[3919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 15:02:03 volumiojh sudo[3919]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:03 volumiojh volumio[3920]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 02 15:02:03 volumiojh volumio[3920]: Reading Config from "/tmp/volspotify.toml" Apr 02 15:02:03 volumiojh volumio[3920]: Malformed config key: missing field `Authentication` Apr 02 15:02:03 volumiojh volumio[648]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 02 15:02:03 volumiojh systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Apr 02 15:02:03 volumiojh systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 02 15:02:03 volumiojh sudo[3924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 02 15:02:03 volumiojh sudo[3919]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:03 volumiojh sudo[3924]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:03 volumiojh systemd[1]: Stopped Volspotconnect2 Daemon. Apr 02 15:02:03 volumiojh systemd[1]: Started Volspotconnect2 Daemon. Apr 02 15:02:03 volumiojh sudo[3924]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:03 volumiojh volumio[3929]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 02 15:02:03 volumiojh volumio[3929]: Reading Config from "/tmp/volspotify.toml" Apr 02 15:02:03 volumiojh volumio[3929]: Malformed config key: expected newline, found an equals at line 29 column 20 Apr 02 15:02:03 volumiojh systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Apr 02 15:02:03 volumiojh systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 15:02:03 volumiojh volumio[648]: info: Getting Alsa Cards List without I2S DAC Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 02 15:02:03 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 02 15:02:04 volumiojh volumio[648]: info: Asound.conf file unchanged, so no further update is needed Apr 02 15:02:04 volumiojh volumio[648]: info: Output device has changed, restarting MPD Apr 02 15:02:04 volumiojh sudo[3938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 02 15:02:04 volumiojh sudo[3938]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:04 volumiojh sudo[3938]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:04 volumiojh volumio[648]: info: Output device has changed, restarting Shairport Sync Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:02:04 volumiojh sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 02 15:02:04 volumiojh sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:04 volumiojh systemd[1]: Stopping Music Player Daemon... Apr 02 15:02:04 volumiojh volumio[648]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 02 15:02:04 volumiojh volumio[648]: [SpotifyConnect] Creating VLS config file Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh systemd[1]: mpd.service: Succeeded. Apr 02 15:02:04 volumiojh systemd[1]: Stopped Music Player Daemon. Apr 02 15:02:04 volumiojh systemd[1]: Starting Music Player Daemon... Apr 02 15:02:04 volumiojh volumio[648]: [SpotifyConnect] Creating VLS config file Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 02 15:02:04 volumiojh sudo[3952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 15:02:04 volumiojh sudo[3952]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:04 volumiojh sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 02 15:02:04 volumiojh sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:04 volumiojh sudo[3952]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:04 volumiojh volumio[648]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 02 15:02:04 volumiojh systemd[1]: Stopped Volspotconnect2 Daemon. Apr 02 15:02:04 volumiojh systemd[1]: Started Volspotconnect2 Daemon. Apr 02 15:02:04 volumiojh sudo[3956]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:04 volumiojh volumio[3962]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 02 15:02:04 volumiojh sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 15:02:04 volumiojh sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:04 volumiojh volumio[3962]: Reading Config from "/tmp/volspotify.toml" Apr 02 15:02:04 volumiojh volumio[3962]: Malformed config key: missing field `Authentication` Apr 02 15:02:04 volumiojh systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Apr 02 15:02:04 volumiojh systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 02 15:02:04 volumiojh sudo[3966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 02 15:02:04 volumiojh volumio[648]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 02 15:02:04 volumiojh sudo[3960]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:04 volumiojh sudo[3966]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:04 volumiojh volumio[648]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 02 15:02:04 volumiojh volumio[648]: info: MPD Permissions set Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh systemd[1]: Stopped Volspotconnect2 Daemon. Apr 02 15:02:04 volumiojh systemd[1]: Started Volspotconnect2 Daemon. Apr 02 15:02:04 volumiojh volumio[3969]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 02 15:02:04 volumiojh volumio[3969]: Reading Config from "/tmp/volspotify.toml" Apr 02 15:02:04 volumiojh volumio[3969]: Malformed config key: expected newline, found an equals at line 29 column 20 Apr 02 15:02:04 volumiojh systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Apr 02 15:02:04 volumiojh systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 02 15:02:04 volumiojh sudo[3966]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:02:04 volumiojh volumio[648]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 02 15:02:04 volumiojh volumio[648]: info: Starting Shairport Sync Apr 02 15:02:04 volumiojh sudo[3976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 02 15:02:04 volumiojh sudo[3976]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:04 volumiojh systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 02 15:02:04 volumiojh systemd[1]: shairport-sync.service: Succeeded. Apr 02 15:02:04 volumiojh systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 02 15:02:04 volumiojh systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 02 15:02:04 volumiojh sudo[3976]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:04 volumiojh volumio[648]: info: Shairport-Sync Started Apr 02 15:02:04 volumiojh mpd[3964]: Apr 02 15:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 02 15:02:04 volumiojh mpd[3964]: Apr 02 15:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 02 15:02:04 volumiojh mpd[3964]: Apr 02 15:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 02 15:02:04 volumiojh systemd[1]: Started Music Player Daemon. Apr 02 15:02:04 volumiojh sudo[3943]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:04 volumiojh volumio[648]: error: updateQueue error: null Apr 02 15:02:06 volumiojh volumio[648]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 02 15:02:06 volumiojh volumio[648]: info: CoreStateMachine::getcurrentVolume Apr 02 15:02:06 volumiojh volumio[648]: info: CoreCommandRouter::volumioRetrievevolume Apr 02 15:02:06 volumiojh volumio[648]: info: CoreStateMachine::pushState Apr 02 15:02:06 volumiojh volumio[648]: info: CoreCommandRouter::volumioPushState Apr 02 15:02:06 volumiojh systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Apr 02 15:02:06 volumiojh systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Apr 02 15:02:06 volumiojh systemd[1]: Stopped Volspotconnect2 Daemon. Apr 02 15:02:06 volumiojh systemd[1]: Started Volspotconnect2 Daemon. Apr 02 15:02:06 volumiojh volumio[3983]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 02 15:02:06 volumiojh volumio[3983]: Reading Config from "/tmp/volspotify.toml" Apr 02 15:02:06 volumiojh volumio[3983]: Malformed config key: expected newline, found an equals at line 29 column 20 Apr 02 15:02:06 volumiojh systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Apr 02 15:02:06 volumiojh systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 02 15:02:06 volumiojh volumio[648]: info: TidalConnect service stoped! Apr 02 15:02:06 volumiojh volumio[648]: info: TidalConnect service stoped! Apr 02 15:02:06 volumiojh volumio[648]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 02 15:02:06 volumiojh volumio[648]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 02 15:02:06 volumiojh sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 02 15:02:06 volumiojh sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:06 volumiojh systemd[1]: Started Volumio Tidal Connect Service. Apr 02 15:02:06 volumiojh sudo[3990]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:07 volumiojh volumio[648]: info: Executing endpoint tc_getconfig Apr 02 15:02:07 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 02 15:02:07 volumiojh vtcs[3992]: STARTING TidalConnect services, version: 1.1.7.5 Apr 02 15:02:07 volumiojh vtcs[3992]: STARTED TidalConnect services. Apr 02 15:02:07 volumiojh volumio[648]: info: Executing endpoint tc_connect Apr 02 15:02:07 volumiojh volumio[648]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 02 15:02:07 volumiojh volumio[648]: Connecting to TidalConnect. Apr 02 15:02:07 volumiojh volumio[648]: info: CoreCommandRouter::servicePushState Apr 02 15:02:07 volumiojh volumio[648]: info: CoreStateMachine::pushState Apr 02 15:02:07 volumiojh volumio[648]: info: CoreCommandRouter::volumioPushState Apr 02 15:02:07 volumiojh volumio[648]: info: CorePlayQueue::getTrack 10 Apr 02 15:02:07 volumiojh volumio[648]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 02 15:02:07 volumiojh volumio[648]: info: CoreCommandRouter::servicePushState Apr 02 15:02:07 volumiojh volumio[648]: info: CoreStateMachine::pushState Apr 02 15:02:07 volumiojh volumio[648]: info: CoreCommandRouter::volumioPushState Apr 02 15:02:07 volumiojh volumio[648]: info: CorePlayQueue::getTrack 10 Apr 02 15:02:07 volumiojh volumio[648]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 02 15:02:07 volumiojh volumio[648]: info: TidalConnect service stoped! Apr 02 15:02:07 volumiojh volumio[648]: info: TidalConnect service stoped! Apr 02 15:02:07 volumiojh volumio[648]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 02 15:02:07 volumiojh volumio[648]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 02 15:02:07 volumiojh sudo[4015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 02 15:02:07 volumiojh sudo[4015]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 15:02:07 volumiojh sudo[4015]: pam_unix(sudo:session): session closed for user root Apr 02 15:02:08 volumiojh systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Apr 02 15:02:08 volumiojh systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 2. Apr 02 15:02:08 volumiojh systemd[1]: Stopped Volspotconnect2 Daemon. Apr 02 15:02:08 volumiojh systemd[1]: volspotconnect.service: Start request repeated too quickly. Apr 02 15:02:08 volumiojh systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 02 15:02:08 volumiojh systemd[1]: Failed to start Volspotconnect2 Daemon. Apr 02 15:02:09 volumiojh volumio[648]: info: Checking Spotify Web API Apr 02 15:02:09 volumiojh volumio[648]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 15:02:09 volumiojh volumio[648]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Apr 02 15:02:09 volumiojh volumio[648]: at healthCheck (dgram.js:897:11) Apr 02 15:02:09 volumiojh volumio[648]: at Socket.send (dgram.js:622:3) Apr 02 15:02:09 volumiojh volumio[648]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) Apr 02 15:02:09 volumiojh volumio[648]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) Apr 02 15:02:09 volumiojh volumio[648]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2723:18) Apr 02 15:02:09 volumiojh volumio[648]: at listOnTimeout (internal/timers.js:554:17) Apr 02 15:02:09 volumiojh volumio[648]: at processTimers (internal/timers.js:497:7) { Apr 02 15:02:09 volumiojh volumio[648]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Apr 02 15:02:09 volumiojh volumio[648]: } Apr 02 15:02:09 volumiojh volumio[648]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 15:02:10 volumiojh sudo[4028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-02 15:01 Apr 02 15:02:10 volumiojh sudo[4028]: 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="9eadfb08439e8c37bef9a38c0641d736c1391e0c" VOLUMIO_FE_VERSION="e6f7875b0e497e33187ca2553d7998c4aeb06af9" VOLUMIO_FE3_VERSION="8d9f9503eeacae3e8f349def90b368b32462d83b" VOLUMIO_BE_VERSION="1cdccc4d006cc26abc6312edbeae58f3081200d4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Feb 2022 07:16:13 PM CET" VOLUMIO_VERSION="3.233" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="88d67d58bfcedcd908dd7b955a1fa5bd"