-- Logs begin at Mon 2025-12-08 17:34:52 EST, end at Wed 2025-12-24 14:24:16 EST. -- Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:10 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 14:23:10 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Preparing to save Alsa Options, stopping services first Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioGetState Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPause Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreStateMachine::pause Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreStateMachine::stPlaybackTimer Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreStateMachine::servicePause Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CorePlayQueue::getTrack 0 Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::servicePause Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 24 14:23:13 smsl-n100 volumio[20136]: info: ControllerMpd::pause Dec 24 14:23:13 smsl-n100 volumio[20136]: verbose: ControllerMpd::sendMpdCommand pause Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"SPDIF"}} Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 14:23:13 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:13 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Found match in Cards Database: setting mixer for card SPDIF Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 24 14:23:13 smsl-n100 vtcs[20567]: [2025-12-24 14:23:13.502] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 24 14:23:13 smsl-n100 vtcs[20567]: [2025-12-24 14:23:13.504] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 24 14:23:13 smsl-n100 sudo[25969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:13 smsl-n100 sudo[25969]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:13 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Dec 24 14:23:13 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:13 smsl-n100 systemd[1]: vtcs.service: Succeeded. Dec 24 14:23:13 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Dec 24 14:23:13 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Device 1 does not have any Mixer Control Available Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 24 14:23:13 smsl-n100 sudo[25969]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:13 smsl-n100 sudo[25975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:13 smsl-n100 sudo[25975]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:13 smsl-n100 sudo[25975]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:13 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Disabling external Volume Control Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:13 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:13 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:13 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:13 smsl-n100 volumio[20136]: info: Preparing to generate the ALSA configuration file Dec 24 14:23:14 smsl-n100 sudo[25981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:14 smsl-n100 sudo[25981]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:14 smsl-n100 sudo[25981]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:14 smsl-n100 sudo[25984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:14 smsl-n100 sudo[25984]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:14 smsl-n100 sudo[25984]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: Not Reporting Auto name since its the default one Dec 24 14:23:14 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:14 smsl-n100 volumio[20136]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 14:23:14 smsl-n100 volumio[20136]: info: Reading ALSA contributions from plugins. Dec 24 14:23:14 smsl-n100 volumio[20136]: info: Dec 24 14:23:14 smsl-n100 volumio[20136]: ---------------------------- MPD announces state update: player Dec 24 14:23:14 smsl-n100 volumio[20136]: info: sendMpdCommand pause took 1085 milliseconds Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ControllerMpd::getState Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::sendMpdCommand status Dec 24 14:23:14 smsl-n100 volumio[20136]: info: Dec 24 14:23:14 smsl-n100 volumio[20136]: ---------------------------- MPD announces state update: player Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ControllerMpd::getState Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::sendMpdCommand status Dec 24 14:23:14 smsl-n100 volumio[20136]: info: Dec 24 14:23:14 smsl-n100 volumio[20136]: ---------------------------- MPD announces state update: player Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ControllerMpd::getState Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::sendMpdCommand status Dec 24 14:23:14 smsl-n100 volumio[20136]: info: Updating RAAT Signal Path Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: Not Reporting Auto name since its the default one Dec 24 14:23:14 smsl-n100 volumio[20136]: info: sendMpdCommand status took 145 milliseconds Dec 24 14:23:14 smsl-n100 volumio[20136]: info: sendMpdCommand status took 130 milliseconds Dec 24 14:23:14 smsl-n100 volumio[20136]: info: sendMpdCommand status took 125 milliseconds Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::parseState Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::parseState Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::parseState Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 14:23:14 smsl-n100 volumio[20136]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 24 14:23:14 smsl-n100 volumio[20136]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 24 14:23:14 smsl-n100 volumio[20136]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::parseTrackInfo Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::parseTrackInfo Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: ControllerMpd::parseTrackInfo Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ControllerMpd::pushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::servicePushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CorePlayQueue::getTrack 0 Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19165,"duration":5480,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DJ Peer Pressure Live! Soulfood Sunday 12/21/25","artist":"DJ Peer Pressure","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,b/5/1/4/eea3-78d4-47f8-9a48-49d5563b066a,b/5/1/4/eea3-78d4-47f8-9a48-49d5563b066a-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: CURRENT POSITION 0 Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::syncState stateService pause Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::syncState currentStatus pause Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:14 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::stPlaybackTimer Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ControllerMpd::pushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::servicePushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CorePlayQueue::getTrack 0 Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19165,"duration":5480,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DJ Peer Pressure Live! Soulfood Sunday 12/21/25","artist":"DJ Peer Pressure","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,b/5/1/4/eea3-78d4-47f8-9a48-49d5563b066a,b/5/1/4/eea3-78d4-47f8-9a48-49d5563b066a-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: CURRENT POSITION 0 Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::syncState stateService pause Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::syncState currentStatus pause Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:14 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::stPlaybackTimer Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ControllerMpd::pushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::servicePushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CorePlayQueue::getTrack 0 Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19165,"duration":5480,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DJ Peer Pressure Live! Soulfood Sunday 12/21/25","artist":"DJ Peer Pressure","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,b/5/1/4/eea3-78d4-47f8-9a48-49d5563b066a,b/5/1/4/eea3-78d4-47f8-9a48-49d5563b066a-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Dec 24 14:23:14 smsl-n100 volumio[20136]: verbose: CURRENT POSITION 0 Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::syncState stateService pause Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::syncState currentStatus pause Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:14 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:14 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreStateMachine::stPlaybackTimer Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ------------------------------ 268ms Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ------------------------------ 252ms Dec 24 14:23:14 smsl-n100 volumio[20136]: info: ------------------------------ 248ms Dec 24 14:23:14 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 14:23:14 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Dec 24 14:23:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 24 14:23:14 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:15 smsl-n100 sudo[26017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 24 14:23:15 smsl-n100 sudo[26017]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Updating RAAT Signal Path Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Updating RAAT Signal Path Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Updating RAAT Signal Path Dec 24 14:23:15 smsl-n100 sudo[26017]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 24 14:23:15 smsl-n100 sudo[26019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 24 14:23:15 smsl-n100 sudo[26019]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:15 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Dec 24 14:23:15 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:15 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Dec 24 14:23:15 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Dec 24 14:23:15 smsl-n100 systemd[1]: Started RAAT DAEMON. Dec 24 14:23:15 smsl-n100 sudo[26019]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:15 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:15 smsl-n100 sudo[26034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 24 14:23:15 smsl-n100 sudo[26034]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:15 smsl-n100 sudo[26034]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:15 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:15 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Raat Daemon started successfully Dec 24 14:23:15 smsl-n100 sudo[26037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 24 14:23:15 smsl-n100 sudo[26037]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:15 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:15 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:15 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:15 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:15 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:15 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:15 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Dec 24 14:23:15 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:15 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Dec 24 14:23:15 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Dec 24 14:23:15 smsl-n100 systemd[1]: Started RAAT DAEMON. Dec 24 14:23:15 smsl-n100 sudo[26037]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Executing endpoint restartRAATSocket Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Asound.conf file unchanged, so no further update is needed Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Output device has changed, restarting MPD Dec 24 14:23:15 smsl-n100 sudo[26049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 14:23:15 smsl-n100 sudo[26049]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:15 smsl-n100 sudo[26049]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Output device has changed, restarting Shairport Sync Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 14:23:15 smsl-n100 sudo[26054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 14:23:15 smsl-n100 sudo[26054]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:15 smsl-n100 systemd[1]: Stopping Music Player Daemon... Dec 24 14:23:15 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:15 smsl-n100 volumio[20136]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 14:23:15 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:15 smsl-n100 mpd[20468]: player: played "https://audio.mixcloud.stream/secure/hls/,b/5/1/4/eea3-78d4-47f8-9a48-49d5563b066a,b/5/1/4/eea3-78d4-47f8-9a48-49d5563b066a-192K,.m4a.urlset/index.m3u8" Dec 24 14:23:15 smsl-n100 volumio[20136]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 14:23:15 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:15 smsl-n100 systemd[1]: mpd.service: Succeeded. Dec 24 14:23:16 smsl-n100 systemd[1]: Stopped Music Player Daemon. Dec 24 14:23:16 smsl-n100 volumio[20136]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 systemd[1]: Starting Music Player Daemon... Dec 24 14:23:16 smsl-n100 sudo[26064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:16 smsl-n100 sudo[26064]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:16 smsl-n100 sudo[26067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:16 smsl-n100 sudo[26067]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:16 smsl-n100 sudo[26064]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:16 smsl-n100 sudo[26061]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 24 14:23:16 smsl-n100 sudo[26061]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:16 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:16 smsl-n100 sudo[26061]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:16 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:16 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:16 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:16 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:16 smsl-n100 sudo[26067]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:16 smsl-n100 volumio[20136]: info: Raat Daemon started successfully Dec 24 14:23:16 smsl-n100 volumio[20136]: info: MPD Permissions set Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:16 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:16 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:16 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:16 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:16 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:16 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:16 smsl-n100 volumio[20136]: info: Executing endpoint restartRAATSocket Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:16 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: Not Reporting Auto name since its the default one Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:16 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:16 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:16 smsl-n100 volumio[20136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 24 14:23:16 smsl-n100 volumio[20136]: info: Starting Shairport Sync Dec 24 14:23:16 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 24 14:23:17 smsl-n100 sudo[26085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 14:23:17 smsl-n100 sudo[26085]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:17 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 24 14:23:17 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Dec 24 14:23:17 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 24 14:23:17 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 24 14:23:17 smsl-n100 sudo[26085]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:17 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:17 smsl-n100 sudo[26093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 24 14:23:17 smsl-n100 sudo[26093]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:17 smsl-n100 sudo[26093]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:17 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:17 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:17 smsl-n100 volumio[20136]: info: Shairport-Sync Started Dec 24 14:23:17 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:17 smsl-n100 sudo[26096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 24 14:23:17 smsl-n100 sudo[26096]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:17 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Dec 24 14:23:17 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:17 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Dec 24 14:23:17 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Dec 24 14:23:17 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:17 smsl-n100 systemd[1]: Started RAAT DAEMON. Dec 24 14:23:17 smsl-n100 sudo[26096]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:17 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:17 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:17 smsl-n100 volumio[20136]: info: Raat Daemon started successfully Dec 24 14:23:17 smsl-n100 volumio[20136]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:17 smsl-n100 volumio[20136]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:17 smsl-n100 volumio[20136]: info: Executing endpoint restartRAATSocket Dec 24 14:23:17 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 24 14:23:17 smsl-n100 volumio[20136]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:17 smsl-n100 volumio[20136]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:17 smsl-n100 sudo[26125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 14:23:17 smsl-n100 sudo[26125]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:17 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Dec 24 14:23:17 smsl-n100 sudo[26125]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:17 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 24 14:23:17 smsl-n100 volumio[20136]: info: CoreStateMachine::getcurrentVolume Dec 24 14:23:17 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 14:23:17 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:17 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:18 smsl-n100 sudo[26131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 14:23:18 smsl-n100 sudo[26131]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:18 smsl-n100 sudo[26131]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:18 smsl-n100 volumio[20136]: info: RAAT: Requesting Headphone Status Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:18 smsl-n100 volumio[20136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:18 smsl-n100 volumio[20136]: info: RAAT: Requesting Headphone Status Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:18 smsl-n100 volumio[20136]: info: Executing endpoint tc_getconfig Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 24 14:23:18 smsl-n100 vtcs[26129]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 24 14:23:18 smsl-n100 vtcs[26129]: STARTED TidalConnect services. Dec 24 14:23:18 smsl-n100 volumio[20136]: info: Executing endpoint tc_connect Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 24 14:23:18 smsl-n100 volumio[20136]: info: Connecting to TidalConnect Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::servicePushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CorePlayQueue::getTrack 0 Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::servicePushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:18 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CorePlayQueue::getTrack 0 Dec 24 14:23:18 smsl-n100 volumio[20136]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:18 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:19 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:19 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:19 smsl-n100 volumio[20136]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:19 smsl-n100 volumio[20136]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:19 smsl-n100 sudo[26160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 14:23:19 smsl-n100 sudo[26160]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:19 smsl-n100 sudo[26160]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:20 smsl-n100 volumio[20136]: info: RAAT: Requesting Headphone Status Dec 24 14:23:20 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 24 14:23:20 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:20 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:20 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:20 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:20 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:20 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:20 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:20 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:20 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:20 smsl-n100 mpd[26071]: Dec 24 14:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 24 14:23:20 smsl-n100 systemd[1]: Started Music Player Daemon. Dec 24 14:23:20 smsl-n100 sudo[26054]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:20 smsl-n100 volumio[20136]: error: updateQueue error: null Dec 24 14:23:20 smsl-n100 volumio[20136]: info: TidalConnect service started! Dec 24 14:23:21 smsl-n100 volumio[20136]: info: TidalConnect service started! Dec 24 14:23:22 smsl-n100 volumio[20136]: info: TidalConnect service started! Dec 24 14:23:23 smsl-n100 sudo[26219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 24 14:23:23 smsl-n100 sudo[26219]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:23 smsl-n100 systemd[1]: Stopping MPD Monitor Service... Dec 24 14:23:23 smsl-n100 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:23 smsl-n100 systemd[1]: mpd_monitor.service: Succeeded. Dec 24 14:23:23 smsl-n100 systemd[1]: Stopped MPD Monitor Service. Dec 24 14:23:23 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:23 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:23 smsl-n100 systemd[1]: Started MPD Monitor Service. Dec 24 14:23:23 smsl-n100 sudo[26219]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:23 smsl-n100 volumio[20136]: info: Successfully started MPD Monitor Dec 24 14:23:23 smsl-n100 mpd_monitor.sh[26222]: MPD Monitor Service: Starting MPD Monitor Service Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 24 14:23:35 smsl-n100 volumio[20136]: info: Preparing to save Alsa Options, stopping services first Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioGetState Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPause Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreStateMachine::pause Dec 24 14:23:35 smsl-n100 volumio[20136]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"SPDIF"}} Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 14:23:35 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:35 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 14:23:35 smsl-n100 volumio[20136]: info: Found match in Cards Database: setting mixer for card SPDIF Dec 24 14:23:35 smsl-n100 volumio[20136]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 24 14:23:35 smsl-n100 vtcs[26129]: [2025-12-24 14:23:35.599] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 24 14:23:35 smsl-n100 vtcs[26129]: [2025-12-24 14:23:35.599] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 24 14:23:35 smsl-n100 sudo[26261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:35 smsl-n100 sudo[26261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:35 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Dec 24 14:23:35 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:35 smsl-n100 systemd[1]: vtcs.service: Succeeded. Dec 24 14:23:35 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Dec 24 14:23:35 smsl-n100 sudo[26261]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:35 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:35 smsl-n100 volumio[20136]: info: Device 1 does not have any Mixer Control Available Dec 24 14:23:35 smsl-n100 volumio[20136]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 24 14:23:35 smsl-n100 sudo[26267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:35 smsl-n100 sudo[26267]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:35 smsl-n100 sudo[26267]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:35 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 24 14:23:35 smsl-n100 volumio[20136]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 24 14:23:35 smsl-n100 volumio[20136]: info: Disabling external Volume Control Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:35 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:36 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:36 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:36 smsl-n100 volumio[20136]: info: Preparing to generate the ALSA configuration file Dec 24 14:23:36 smsl-n100 sudo[26273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:36 smsl-n100 sudo[26273]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:36 smsl-n100 sudo[26273]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:36 smsl-n100 sudo[26276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:36 smsl-n100 sudo[26276]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:36 smsl-n100 sudo[26276]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: Not Reporting Auto name since its the default one Dec 24 14:23:36 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:36 smsl-n100 volumio[20136]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 14:23:36 smsl-n100 volumio[20136]: info: Reading ALSA contributions from plugins. Dec 24 14:23:36 smsl-n100 volumio[20136]: info: Updating RAAT Signal Path Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: Not Reporting Auto name since its the default one Dec 24 14:23:36 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 14:23:36 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 24 14:23:36 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:36 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 24 14:23:36 smsl-n100 sudo[26295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 24 14:23:36 smsl-n100 sudo[26295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:36 smsl-n100 sudo[26295]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:37 smsl-n100 sudo[26298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 24 14:23:37 smsl-n100 sudo[26298]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:37 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Dec 24 14:23:37 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:37 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:37 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Dec 24 14:23:37 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Dec 24 14:23:37 smsl-n100 systemd[1]: Started RAAT DAEMON. Dec 24 14:23:37 smsl-n100 sudo[26298]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:37 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:37 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:37 smsl-n100 sudo[26307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 24 14:23:37 smsl-n100 volumio[20136]: info: Raat Daemon started successfully Dec 24 14:23:37 smsl-n100 sudo[26307]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:37 smsl-n100 volumio[20136]: info: Asound.conf file unchanged, so no further update is needed Dec 24 14:23:37 smsl-n100 volumio[20136]: info: Output device has changed, restarting MPD Dec 24 14:23:37 smsl-n100 sudo[26307]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:37 smsl-n100 sudo[26315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 24 14:23:37 smsl-n100 sudo[26315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:37 smsl-n100 sudo[26316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 14:23:37 smsl-n100 sudo[26316]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:37 smsl-n100 sudo[26316]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:37 smsl-n100 volumio[20136]: info: Output device has changed, restarting Shairport Sync Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 14:23:37 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Dec 24 14:23:37 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:37 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Dec 24 14:23:37 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Dec 24 14:23:37 smsl-n100 sudo[26320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 14:23:37 smsl-n100 sudo[26320]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:37 smsl-n100 systemd[1]: Started RAAT DAEMON. Dec 24 14:23:37 smsl-n100 sudo[26315]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:37 smsl-n100 systemd[1]: Stopping Music Player Daemon... Dec 24 14:23:37 smsl-n100 systemd[1]: mpd.service: Succeeded. Dec 24 14:23:37 smsl-n100 systemd[1]: Stopped Music Player Daemon. Dec 24 14:23:37 smsl-n100 systemd[1]: Starting Music Player Daemon... Dec 24 14:23:37 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:37 smsl-n100 volumio[20136]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 14:23:37 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:37 smsl-n100 volumio[20136]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:37 smsl-n100 volumio[20136]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:37 smsl-n100 sudo[26330]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 24 14:23:37 smsl-n100 sudo[26330]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:37 smsl-n100 sudo[26330]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:37 smsl-n100 sudo[26341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:37 smsl-n100 sudo[26341]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:37 smsl-n100 sudo[26344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 14:23:37 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:37 smsl-n100 sudo[26341]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:37 smsl-n100 sudo[26344]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:37 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:37 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:37 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:37 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:37 smsl-n100 sudo[26344]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:37 smsl-n100 volumio[20136]: info: MPD Permissions set Dec 24 14:23:37 smsl-n100 volumio[20136]: info: Raat Daemon started successfully Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:37 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:37 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:37 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:38 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:38 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:38 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:38 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:38 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:38 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:38 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:38 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:38 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:38 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:38 smsl-n100 volumio[20136]: info: Executing endpoint restartRAATSocket Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 24 14:23:38 smsl-n100 volumio[20136]: info: Executing endpoint restartRAATSocket Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 24 14:23:38 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:38 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: Not Reporting Auto name since its the default one Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 14:23:38 smsl-n100 volumio[20136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 24 14:23:38 smsl-n100 volumio[20136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 24 14:23:38 smsl-n100 volumio[20136]: info: Starting Shairport Sync Dec 24 14:23:38 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 24 14:23:38 smsl-n100 sudo[26360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 14:23:38 smsl-n100 sudo[26360]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:38 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 24 14:23:38 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Dec 24 14:23:38 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 24 14:23:38 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 24 14:23:38 smsl-n100 sudo[26360]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:38 smsl-n100 volumio[20136]: xcb_connection_has_error() returned true Dec 24 14:23:38 smsl-n100 sudo[26366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 24 14:23:38 smsl-n100 sudo[26366]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:38 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:38 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:38 smsl-n100 volumio[20136]: info: Shairport-Sync Started Dec 24 14:23:38 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:38 smsl-n100 sudo[26366]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:38 smsl-n100 sudo[26370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 24 14:23:38 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:38 smsl-n100 sudo[26370]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:38 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Dec 24 14:23:39 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:39 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Dec 24 14:23:39 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Dec 24 14:23:39 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:39 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:39 smsl-n100 systemd[1]: Started RAAT DAEMON. Dec 24 14:23:39 smsl-n100 sudo[26370]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:39 smsl-n100 volumio[20136]: info: Raat Daemon started successfully Dec 24 14:23:39 smsl-n100 volumio[20136]: info: Executing endpoint restartRAATSocket Dec 24 14:23:39 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 24 14:23:39 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:39 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:39 smsl-n100 volumio[20136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 24 14:23:39 smsl-n100 volumio[20136]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:39 smsl-n100 volumio[20136]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:39 smsl-n100 volumio[20136]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:39 smsl-n100 volumio[20136]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:39 smsl-n100 sudo[26400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 14:23:39 smsl-n100 sudo[26400]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:39 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Dec 24 14:23:39 smsl-n100 sudo[26400]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:39 smsl-n100 sudo[26404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 14:23:39 smsl-n100 sudo[26404]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:39 smsl-n100 volumio[20136]: info: RAAT: Requesting Headphone Status Dec 24 14:23:39 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 24 14:23:39 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:39 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:39 smsl-n100 sudo[26404]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:39 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:39 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:39 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:39 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:39 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:39 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreStateMachine::getcurrentVolume Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:40 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:40 smsl-n100 volumio[20136]: info: RAAT: Requesting Headphone Status Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 24 14:23:40 smsl-n100 volumio[20136]: info: Updating RAAT Signal Path Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:40 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:40 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:40 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:40 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:40 smsl-n100 volumio[20136]: info: Executing endpoint tc_getconfig Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 24 14:23:40 smsl-n100 vtcs[26403]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 24 14:23:40 smsl-n100 vtcs[26403]: STARTED TidalConnect services. Dec 24 14:23:40 smsl-n100 volumio[20136]: info: Executing endpoint tc_connect Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 24 14:23:40 smsl-n100 volumio[20136]: info: Connecting to TidalConnect Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::servicePushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CorePlayQueue::getTrack 0 Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::servicePushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:40 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CorePlayQueue::getTrack 0 Dec 24 14:23:40 smsl-n100 volumio[20136]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 24 14:23:40 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:40 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:40 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:40 smsl-n100 volumio[20136]: info: TidalConnect service stoped! Dec 24 14:23:41 smsl-n100 volumio[20136]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:41 smsl-n100 volumio[20136]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 14:23:41 smsl-n100 sudo[26434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 14:23:41 smsl-n100 sudo[26434]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:41 smsl-n100 sudo[26434]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:41 smsl-n100 volumio[20136]: info: RAAT: Requesting Headphone Status Dec 24 14:23:41 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 24 14:23:41 smsl-n100 volumio[20136]: info: CoreStateMachine::pushState Dec 24 14:23:41 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioPushState Dec 24 14:23:41 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output update for this device Dec 24 14:23:41 smsl-n100 volumio[20136]: info: MRS: Pushing multiroomSync output Dec 24 14:23:41 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:41 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 24 14:23:41 smsl-n100 volumio[20136]: error: Serial API: Failed to decode command: 100, message: undefined Dec 24 14:23:41 smsl-n100 volumio[20136]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 24 14:23:41 smsl-n100 volumio[20136]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.OnXzRwqIWFV1vfJWk8gMtHuBxJq1.f6ecf497f4f54e0646301b4ad7f856f7.state.mute' Dec 24 14:23:41 smsl-n100 mpd[26340]: Dec 24 14:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 24 14:23:42 smsl-n100 systemd[1]: Started Music Player Daemon. Dec 24 14:23:42 smsl-n100 sudo[26320]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:42 smsl-n100 volumio[20136]: error: updateQueue error: null Dec 24 14:23:42 smsl-n100 volumio[20136]: info: TidalConnect service started! Dec 24 14:23:42 smsl-n100 volumio[20136]: info: TidalConnect service started! Dec 24 14:23:44 smsl-n100 volumio[20136]: info: TidalConnect service started! Dec 24 14:23:45 smsl-n100 sudo[26443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 24 14:23:45 smsl-n100 sudo[26443]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 14:23:45 smsl-n100 systemd[1]: Stopping MPD Monitor Service... Dec 24 14:23:45 smsl-n100 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 24 14:23:45 smsl-n100 systemd[1]: mpd_monitor.service: Succeeded. Dec 24 14:23:45 smsl-n100 systemd[1]: Stopped MPD Monitor Service. Dec 24 14:23:45 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 14:23:45 smsl-n100 volumio[20136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 14:23:45 smsl-n100 systemd[1]: Started MPD Monitor Service. Dec 24 14:23:45 smsl-n100 sudo[26443]: pam_unix(sudo:session): session closed for user root Dec 24 14:23:45 smsl-n100 volumio[20136]: info: Successfully started MPD Monitor Dec 24 14:23:45 smsl-n100 mpd_monitor.sh[26449]: MPD Monitor Service: Starting MPD Monitor Service Dec 24 14:23:55 smsl-n100 volumiossh-tunnel[25146]: Connection to us1.myvolumio.org closed by remote host. Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh exited with error status 255; restarting ssh Dec 24 14:23:55 smsl-n100 autossh[25147]: starting ssh (count 2) Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh child pid is 26478 Dec 24 14:23:55 smsl-n100 volumiossh-tunnel[25146]: ssh: connect to host us1.myvolumio.org port 2222: Connection refused Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh exited with error status 255; restarting ssh Dec 24 14:23:55 smsl-n100 autossh[25147]: starting ssh (count 3) Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh child pid is 26479 Dec 24 14:23:55 smsl-n100 volumiossh-tunnel[25146]: ssh: connect to host us1.myvolumio.org port 2222: Connection refused Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh exited with error status 255; restarting ssh Dec 24 14:23:55 smsl-n100 autossh[25147]: starting ssh (count 4) Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh child pid is 26481 Dec 24 14:23:55 smsl-n100 volumiossh-tunnel[25146]: ssh: connect to host us1.myvolumio.org port 2222: Connection refused Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh exited with error status 255; restarting ssh Dec 24 14:23:55 smsl-n100 autossh[25147]: starting ssh (count 5) Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh child pid is 26482 Dec 24 14:23:55 smsl-n100 volumiossh-tunnel[25146]: ssh: connect to host us1.myvolumio.org port 2222: Connection refused Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh exited with error status 255; restarting ssh Dec 24 14:23:55 smsl-n100 autossh[25147]: starting ssh (count 6) Dec 24 14:23:55 smsl-n100 autossh[25147]: ssh child pid is 26483 Dec 24 14:23:56 smsl-n100 volumiossh-tunnel[25146]: ssh: connect to host us1.myvolumio.org port 2222: Connection refused Dec 24 14:23:56 smsl-n100 autossh[25147]: ssh exited with error status 255; restarting ssh Dec 24 14:23:56 smsl-n100 autossh[25147]: starting ssh (count 7) Dec 24 14:23:56 smsl-n100 autossh[25147]: ssh child pid is 26484 Dec 24 14:23:56 smsl-n100 volumiossh-tunnel[25146]: ssh: connect to host us1.myvolumio.org port 2222: Connection refused Dec 24 14:23:56 smsl-n100 autossh[25147]: ssh exited with error status 255; restarting ssh Dec 24 14:23:57 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:57 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Retrieving Cloud Streaming UI Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Getting Tidal Cloud Configuration Dec 24 14:23:57 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Getting Qobuz Cloud Configuration Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Asking plugin for UI Config Dec 24 14:23:57 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Getting Spotify Cloud Configuration Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Asking plugin for UI Config Dec 24 14:23:57 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Saving Spotify Acccount Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Got it Dec 24 14:23:57 smsl-n100 volumio[20136]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Got Tidal Cloud Configuration Dec 24 14:23:57 smsl-n100 volumio[20136]: info: Got it Dec 24 14:23:57 smsl-n100 volumio[20136]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioGetBrowseSources Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioGetBrowseSources Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::volumioGetBrowseSources Dec 24 14:23:58 smsl-n100 autossh[25147]: starting ssh (count 8) Dec 24 14:23:58 smsl-n100 autossh[25147]: ssh child pid is 26485 Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 14:23:58 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 24 14:23:58 smsl-n100 volumiossh-tunnel[25146]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Dec 24 14:24:02 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 14:24:06 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 14:24:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 14:24:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 24 14:24:10 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 24 14:24:14 smsl-n100 volumio[20136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 14:24:15 smsl-n100 volumio[20136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 14:24:15 smsl-n100 volumio[20136]: Error: dns service error: unknown Dec 24 14:24:15 smsl-n100 volumio[20136]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Dec 24 14:24:15 smsl-n100 volumio[20136]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 24 14:24:15 smsl-n100 volumio[20136]: errorCode: -65537 Dec 24 14:24:15 smsl-n100 volumio[20136]: } Dec 24 14:24:15 smsl-n100 volumio[20136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 14:24:16 smsl-n100 sudo[26530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-24 14:23 Dec 24 14:24:16 smsl-n100 sudo[26530]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 12:39:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="7a92d73f7a04bc7854b1812143743fd4"