-- Logs begin at Fri 2025-02-14 22:46:15 JST, end at Tue 2025-05-20 07:01:26 JST. -- May 20 07:00:06 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded. May 20 07:00:06 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 20 07:00:06 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 20 07:00:07 smsl-n100 volumio-remote-updater[575]: No test mode May 20 07:00:07 smsl-n100 volumio-remote-updater[575]: No alpha test mode May 20 07:00:07 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 20 07:00:07 smsl-n100 volumio[1000]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 20 07:00:07 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 20 07:00:10 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 07:00:10 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 07:00:10 smsl-n100 volumio[1000]: info: Discovery: Getting this device information May 20 07:00:10 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetState May 20 07:00:10 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:10 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 07:00:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 20 07:00:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 20 07:00:22 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 20 07:00:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 07:00:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 20 07:00:28 smsl-n100 volumio[1000]: info: Preparing to save Alsa Options, stopping services first May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetState May 20 07:00:28 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPause May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreStateMachine::pause May 20 07:00:28 smsl-n100 volumio[1000]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"SPDIF"},"disallowPush":true} May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 07:00:28 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:28 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 07:00:28 smsl-n100 volumio[1000]: info: Found match in Cards Database: setting mixer for card SPDIF May 20 07:00:28 smsl-n100 volumio[1000]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes May 20 07:00:28 smsl-n100 vtcs[1525]: [2025-05-20 07:00:28.233] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 20 07:00:28 smsl-n100 vtcs[1525]: [2025-05-20 07:00:28.235] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer May 20 07:00:28 smsl-n100 vtcs[1525]: [2025-05-20 07:00:28.236] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 20 07:00:28 smsl-n100 sudo[2991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 20 07:00:28 smsl-n100 sudo[2991]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:28 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... May 20 07:00:28 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 20 07:00:28 smsl-n100 systemd[1]: vtcs.service: Succeeded. May 20 07:00:28 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. May 20 07:00:28 smsl-n100 sudo[2991]: pam_unix(sudo:session): session closed for user root May 20 07:00:28 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:28 smsl-n100 volumio[1000]: info: Device 1 does not have any Mixer Control Available May 20 07:00:28 smsl-n100 volumio[1000]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes May 20 07:00:28 smsl-n100 sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 20 07:00:28 smsl-n100 sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:28 smsl-n100 sudo[2997]: pam_unix(sudo:session): session closed for user root May 20 07:00:28 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 20 07:00:28 smsl-n100 volumio[1000]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 20 07:00:28 smsl-n100 volumio[1000]: info: Disabling external Volume Control May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:28 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:28 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:28 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:28 smsl-n100 volumio[1000]: info: Preparing to generate the ALSA configuration file May 20 07:00:28 smsl-n100 sudo[3003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 20 07:00:28 smsl-n100 sudo[3003]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:28 smsl-n100 sudo[3006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 20 07:00:28 smsl-n100 sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:28 smsl-n100 sudo[3003]: pam_unix(sudo:session): session closed for user root May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:28 smsl-n100 sudo[3006]: pam_unix(sudo:session): session closed for user root May 20 07:00:28 smsl-n100 volumio[1000]: info: Not Reporting Auto name since its the default one May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 20 07:00:28 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 20 07:00:29 smsl-n100 sudo[3015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 07:00:29 smsl-n100 sudo[3015]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:29 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:00:29 smsl-n100 sudo[3018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 07:00:29 smsl-n100 sudo[3018]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:29 smsl-n100 volumio[1000]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 20 07:00:29 smsl-n100 volumio[1000]: info: Reading ALSA contributions from plugins. May 20 07:00:29 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: Not Reporting Auto name since its the default one May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 20 07:00:29 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:29 smsl-n100 sudo[3027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 20 07:00:29 smsl-n100 sudo[3027]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:29 smsl-n100 sudo[3027]: pam_unix(sudo:session): session closed for user root May 20 07:00:29 smsl-n100 sudo[3031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 20 07:00:29 smsl-n100 sudo[3031]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:29 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 07:00:29 smsl-n100 systemd[1]: Stopping RAAT DAEMON... May 20 07:00:29 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 20 07:00:29 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. May 20 07:00:29 smsl-n100 systemd[1]: Stopped RAAT DAEMON. May 20 07:00:29 smsl-n100 systemd[1]: Started RAAT DAEMON. May 20 07:00:29 smsl-n100 sudo[3031]: pam_unix(sudo:session): session closed for user root May 20 07:00:29 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 20 07:00:29 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:29 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:00:29 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 07:00:29 smsl-n100 sudo[3050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 20 07:00:29 smsl-n100 sudo[3050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:29 smsl-n100 volumio[1000]: info: Raat Daemon started successfully May 20 07:00:29 smsl-n100 sudo[3050]: pam_unix(sudo:session): session closed for user root May 20 07:00:29 smsl-n100 volumio[1000]: info: Executing endpoint restartRAATSocket May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 20 07:00:29 smsl-n100 sudo[3052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 20 07:00:29 smsl-n100 sudo[3052]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:30 smsl-n100 systemd[1]: Stopping RAAT DAEMON... May 20 07:00:30 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 20 07:00:30 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. May 20 07:00:30 smsl-n100 systemd[1]: Stopped RAAT DAEMON. May 20 07:00:30 smsl-n100 systemd[1]: Started RAAT DAEMON. May 20 07:00:30 smsl-n100 sudo[3052]: pam_unix(sudo:session): session closed for user root May 20 07:00:30 smsl-n100 volumio[1000]: info: Asound.conf file unchanged, so no further update is needed May 20 07:00:30 smsl-n100 volumio[1000]: info: Output device has changed, restarting MPD May 20 07:00:30 smsl-n100 sudo[3061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 07:00:30 smsl-n100 sudo[3061]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:30 smsl-n100 volumio[1000]: info: Output device has changed, restarting Shairport Sync May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 07:00:30 smsl-n100 sudo[3061]: pam_unix(sudo:session): session closed for user root May 20 07:00:30 smsl-n100 sudo[3068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 07:00:30 smsl-n100 sudo[3068]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:30 smsl-n100 systemd[1]: Stopping Music Player Daemon... May 20 07:00:30 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:30 smsl-n100 volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 20 07:00:30 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:30 smsl-n100 systemd[1]: mpd.service: Succeeded. May 20 07:00:30 smsl-n100 volumio[1000]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 20 07:00:30 smsl-n100 systemd[1]: Stopped Music Player Daemon. May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:30 smsl-n100 systemd[1]: Starting Music Player Daemon... May 20 07:00:30 smsl-n100 volumio[1000]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:30 smsl-n100 sudo[3079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 20 07:00:30 smsl-n100 sudo[3079]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:30 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:30 smsl-n100 sudo[3082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 20 07:00:30 smsl-n100 sudo[3074]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 07:00:30 smsl-n100 sudo[3074]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:30 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:00:30 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 07:00:30 smsl-n100 sudo[3082]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:30 smsl-n100 sudo[3074]: pam_unix(sudo:session): session closed for user root May 20 07:00:30 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:00:30 smsl-n100 sudo[3079]: pam_unix(sudo:session): session closed for user root May 20 07:00:30 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 07:00:30 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:00:30 smsl-n100 volumio[1000]: info: Raat Daemon started successfully May 20 07:00:30 smsl-n100 volumio[1000]: info: MPD Permissions set May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:30 smsl-n100 sudo[3082]: pam_unix(sudo:session): session closed for user root May 20 07:00:30 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:30 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:30 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:30 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:30 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: MAXVOL, message: May 20 07:00:30 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:30 smsl-n100 volumio[1000]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:30 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:30 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:30 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:30 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:30 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: MAXVOL, message: May 20 07:00:30 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:30 smsl-n100 volumio[1000]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 20 07:00:30 smsl-n100 volumio[1000]: info: Executing endpoint restartRAATSocket May 20 07:00:30 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 20 07:00:30 smsl-n100 volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: Not Reporting Auto name since its the default one May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 07:00:31 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:31 smsl-n100 volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 20 07:00:31 smsl-n100 volumio[1000]: info: Starting Shairport Sync May 20 07:00:31 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 20 07:00:31 smsl-n100 sudo[3114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 20 07:00:31 smsl-n100 sudo[3114]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:31 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 20 07:00:31 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. May 20 07:00:31 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 20 07:00:31 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 20 07:00:31 smsl-n100 sudo[3114]: pam_unix(sudo:session): session closed for user root May 20 07:00:31 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true May 20 07:00:31 smsl-n100 sudo[3121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 20 07:00:31 smsl-n100 sudo[3121]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:31 smsl-n100 sudo[3121]: pam_unix(sudo:session): session closed for user root May 20 07:00:31 smsl-n100 volumio[1000]: info: TidalConnect service stoped! May 20 07:00:31 smsl-n100 sudo[3125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 20 07:00:31 smsl-n100 sudo[3125]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:31 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:00:31 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 07:00:31 smsl-n100 volumio[1000]: info: Shairport-Sync Started May 20 07:00:31 smsl-n100 volumio[1000]: info: TidalConnect service stoped! May 20 07:00:31 smsl-n100 systemd[1]: Stopping RAAT DAEMON... May 20 07:00:31 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 20 07:00:31 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. May 20 07:00:31 smsl-n100 volumio[1000]: info: TidalConnect service stoped! May 20 07:00:31 smsl-n100 systemd[1]: Stopped RAAT DAEMON. May 20 07:00:31 smsl-n100 systemd[1]: Started RAAT DAEMON. May 20 07:00:31 smsl-n100 sudo[3125]: pam_unix(sudo:session): session closed for user root May 20 07:00:32 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:00:32 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 07:00:32 smsl-n100 volumio[1000]: info: Raat Daemon started successfully May 20 07:00:32 smsl-n100 volumio[1000]: info: TidalConnect service stoped! May 20 07:00:32 smsl-n100 volumio[1000]: info: Executing endpoint restartRAATSocket May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 20 07:00:32 smsl-n100 volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 20 07:00:32 smsl-n100 volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 20 07:00:32 smsl-n100 volumio[1000]: info: RAAT: Requesting Headphone Status May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 20 07:00:32 smsl-n100 volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 20 07:00:32 smsl-n100 volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 20 07:00:32 smsl-n100 sudo[3155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 20 07:00:32 smsl-n100 sudo[3155]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:32 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:32 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:32 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:32 smsl-n100 sudo[3155]: pam_unix(sudo:session): session closed for user root May 20 07:00:32 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:32 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:32 smsl-n100 sudo[3158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 20 07:00:32 smsl-n100 sudo[3158]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:32 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:32 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: MAXVOL, message: May 20 07:00:32 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:32 smsl-n100 volumio[1000]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 20 07:00:32 smsl-n100 sudo[3158]: pam_unix(sudo:session): session closed for user root May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreStateMachine::getcurrentVolume May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:32 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:32 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:32 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:32 smsl-n100 volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 20 07:00:32 smsl-n100 volumio[1000]: info: RAAT: Requesting Headphone Status May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:32 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 07:00:32 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:32 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:32 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:32 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:32 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: MAXVOL, message: May 20 07:00:32 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:32 smsl-n100 volumio[1000]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 20 07:00:33 smsl-n100 volumio[1000]: info: Executing endpoint tc_getconfig May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 20 07:00:33 smsl-n100 vtcs[3159]: STARTING TidalConnect services, version: 1.4.0.34 May 20 07:00:33 smsl-n100 vtcs[3159]: STARTED TidalConnect services. May 20 07:00:33 smsl-n100 volumio[1000]: info: Executing endpoint tc_connect May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 20 07:00:33 smsl-n100 volumio[1000]: info: Connecting to TidalConnect May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:33 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:33 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:33 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 20 07:00:33 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:33 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:33 smsl-n100 volumio[1000]: info: No code May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:33 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:33 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:33 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:33 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 20 07:00:33 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:33 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:33 smsl-n100 volumio[1000]: info: No code May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:33 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:33 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:33 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:33 smsl-n100 volumio[1000]: info: TidalConnect service stoped! May 20 07:00:33 smsl-n100 volumio[1000]: info: TidalConnect service stoped! May 20 07:00:33 smsl-n100 volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 20 07:00:33 smsl-n100 volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 20 07:00:33 smsl-n100 sudo[3189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 20 07:00:33 smsl-n100 sudo[3189]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:00:33 smsl-n100 sudo[3189]: pam_unix(sudo:session): session closed for user root May 20 07:00:34 smsl-n100 sudo[3018]: pam_unix(sudo:session): session closed for user root May 20 07:00:34 smsl-n100 sudo[3015]: pam_unix(sudo:session): session closed for user root May 20 07:00:34 smsl-n100 volumio[1000]: info: RAAT: Requesting Headphone Status May 20 07:00:34 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 20 07:00:34 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState May 20 07:00:34 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:34 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState May 20 07:00:34 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device May 20 07:00:34 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output May 20 07:00:34 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:34 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: MAXVOL, message: May 20 07:00:34 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined May 20 07:00:34 smsl-n100 volumio[1000]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 20 07:00:35 smsl-n100 mpd[3086]: May 20 07:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 07:00:35 smsl-n100 systemd[1]: Started Music Player Daemon. May 20 07:00:35 smsl-n100 sudo[3068]: pam_unix(sudo:session): session closed for user root May 20 07:00:35 smsl-n100 volumio[1000]: error: updateQueue error: null May 20 07:00:35 smsl-n100 volumio[1000]: info: TidalConnect service started! May 20 07:00:35 smsl-n100 volumio[1000]: info: TidalConnect service started! May 20 07:00:37 smsl-n100 volumio[1000]: info: TidalConnect service started! May 20 07:00:40 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 07:00:40 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 07:00:40 smsl-n100 volumio[1000]: info: Discovery: Getting this device information May 20 07:00:40 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetState May 20 07:00:40 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:00:40 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 07:01:10 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 07:01:10 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 07:01:10 smsl-n100 volumio[1000]: info: Discovery: Getting this device information May 20 07:01:10 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetState May 20 07:01:10 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:01:10 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 07:01:11 smsl-n100 volumio[1000]: verbose: New Socket.io Connection to 192.168.11.2 from 192.168.11.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetVisibleSources May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetVisibleSources May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetVisibleSources May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin May 20 07:01:11 smsl-n100 volumio[1000]: info: Retrieving Cloud Streaming UI May 20 07:01:11 smsl-n100 volumio[1000]: info: Getting Tidal Cloud Configuration May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin May 20 07:01:11 smsl-n100 volumio[1000]: info: Getting Qobuz Cloud Configuration May 20 07:01:11 smsl-n100 volumio[1000]: info: Asking plugin for UI Config May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin May 20 07:01:11 smsl-n100 volumio[1000]: info: Getting Spotify Cloud Configuration May 20 07:01:11 smsl-n100 volumio[1000]: info: Asking plugin for UI Config May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin May 20 07:01:11 smsl-n100 volumio[1000]: info: Saving Spotify Acccount May 20 07:01:11 smsl-n100 volumio[1000]: info: Got it May 20 07:01:11 smsl-n100 volumio[1000]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 20 07:01:11 smsl-n100 volumio[1000]: info: Got Tidal Cloud Configuration May 20 07:01:11 smsl-n100 volumio[1000]: info: Got it May 20 07:01:11 smsl-n100 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin May 20 07:01:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork May 20 07:01:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo May 20 07:01:18 smsl-n100 sudo[3306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 07:01:18 smsl-n100 sudo[3306]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:18 smsl-n100 sudo[3306]: pam_unix(sudo:session): session closed for user root May 20 07:01:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 20 07:01:18 smsl-n100 volumio[1000]: info: Saving new wireless network May 20 07:01:18 smsl-n100 sudo[3309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 20 07:01:18 smsl-n100 sudo[3309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:18 smsl-n100 sudo[3309]: pam_unix(sudo:session): session closed for user root May 20 07:01:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 20 07:01:18 smsl-n100 volumio[1000]: info: Discovery: Restarting Advertising due to device name change May 20 07:01:18 smsl-n100 sudo[3312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 20 07:01:18 smsl-n100 sudo[3312]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:18 smsl-n100 systemd[1]: Starting Wireless Services... May 20 07:01:18 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:01:19 smsl-n100 wireless.js[3314]: Cleaning previous... May 20 07:01:19 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... May 20 07:01:19 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:01:19 smsl-n100 volumio[1000]: info: Discovery: A device disappeared from network May 20 07:01:19 smsl-n100 volumio[1000]: info: Discovery: A device disappeared from network May 20 07:01:19 smsl-n100 dnsmasq[1015]: exiting on receipt of SIGTERM May 20 07:01:19 smsl-n100 systemd[1]: dnsmasq.service: Succeeded. May 20 07:01:19 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. May 20 07:01:19 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... May 20 07:01:19 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 07:01:19 smsl-n100 systemd[1]: hostapd.service: Succeeded. May 20 07:01:19 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. May 20 07:01:19 smsl-n100 sudo[3332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 20 07:01:19 smsl-n100 sudo[3332]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:19 smsl-n100 avahi-daemon[576]: Withdrawing address record for 192.168.211.1 on wlan0. May 20 07:01:19 smsl-n100 avahi-daemon[576]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 20 07:01:19 smsl-n100 avahi-daemon[576]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.33.207. May 20 07:01:19 smsl-n100 avahi-daemon[576]: Withdrawing address record for 169.254.33.207 on wlan0. May 20 07:01:19 smsl-n100 avahi-daemon[576]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.33.207. May 20 07:01:19 smsl-n100 avahi-daemon[576]: Interface wlan0.IPv4 no longer relevant for mDNS. May 20 07:01:19 smsl-n100 sudo[3332]: pam_unix(sudo:session): session closed for user root May 20 07:01:19 smsl-n100 sudo[3334]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 20 07:01:19 smsl-n100 sudo[3334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:19 smsl-n100 dhcpcd[946]: wlan0: carrier lost May 20 07:01:19 smsl-n100 sudo[3334]: pam_unix(sudo:session): session closed for user root May 20 07:01:19 smsl-n100 dhcpcd[946]: wlan0: deleting route to 169.254.0.0/16 May 20 07:01:20 smsl-n100 wireless.js[3314]: Stopped aP May 20 07:01:20 smsl-n100 sudo[3369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 20 07:01:20 smsl-n100 sudo[3369]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:20 smsl-n100 sudo[3369]: pam_unix(sudo:session): session closed for user root May 20 07:01:20 smsl-n100 sudo[3381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 20 07:01:20 smsl-n100 sudo[3381]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:20 smsl-n100 sudo[3381]: pam_unix(sudo:session): session closed for user root May 20 07:01:20 smsl-n100 sudo[3392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 20 07:01:20 smsl-n100 sudo[3392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:20 smsl-n100 sudo[3392]: pam_unix(sudo:session): session closed for user root May 20 07:01:20 smsl-n100 sudo[3394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 20 07:01:20 smsl-n100 sudo[3394]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:20 smsl-n100 dhcpcd[946]: wlan0: carrier acquired May 20 07:01:20 smsl-n100 dhcpcd[946]: wlan0: IAID 2b:ee:c8:63 May 20 07:01:21 smsl-n100 dhcpcd[946]: wlan0: soliciting a DHCP lease May 20 07:01:21 smsl-n100 dhcpcd[946]: wlan0: soliciting an IPv6 router May 20 07:01:22 smsl-n100 ntpd[941]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=615 secs May 20 07:01:22 smsl-n100 ntpd[941]: Deleting interface #5 wlan0, 169.254.33.207#123, interface stats: received=0, sent=0, dropped=0, active_time=605 secs May 20 07:01:23 smsl-n100 volumio[1000]: info: Discovery: Started advertising with name: SMSL N100 May 20 07:01:24 smsl-n100 volumio[1000]: info: Discovery: adding 7442d965-c8bc-45fa-a84f-3d0bfb06d69b May 20 07:01:24 smsl-n100 volumio[1000]: info: Discovery: Found device SMSL N100 May 20 07:01:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetState May 20 07:01:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 May 20 07:01:24 smsl-n100 volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 07:01:24 smsl-n100 volumio[1000]: Error: The value NaN is not a number May 20 07:01:24 smsl-n100 volumio[1000]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 20 07:01:24 smsl-n100 volumio[1000]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 20 07:01:24 smsl-n100 volumio[1000]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 20 07:01:24 smsl-n100 volumio[1000]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 20 07:01:24 smsl-n100 volumio[1000]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 20 07:01:24 smsl-n100 volumio[1000]: at Browser.emit (events.js:400:28) May 20 07:01:24 smsl-n100 volumio[1000]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 20 07:01:24 smsl-n100 volumio[1000]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 20 07:01:24 smsl-n100 volumio[1000]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 20 07:01:24 smsl-n100 volumio[1000]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 20 07:01:24 smsl-n100 volumio[1000]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 20 07:01:24 smsl-n100 volumio[1000]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 20 07:01:24 smsl-n100 volumio[1000]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 20 07:01:24 smsl-n100 volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 07:01:24 smsl-n100 sudo[3394]: pam_unix(sudo:session): session closed for user root May 20 07:01:24 smsl-n100 wireless.js[3314]: Start wireless flow May 20 07:01:24 smsl-n100 wireless.js[3314]: Stopped hotspot (if there).. May 20 07:01:24 smsl-n100 sudo[3424]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 20 07:01:24 smsl-n100 sudo[3424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:24 smsl-n100 sudo[3424]: pam_unix(sudo:session): session closed for user root May 20 07:01:24 smsl-n100 sudo[3426]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 20 07:01:24 smsl-n100 sudo[3426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:24 smsl-n100 dhcpcd[946]: wlan0: carrier lost May 20 07:01:24 smsl-n100 sudo[3426]: pam_unix(sudo:session): session closed for user root May 20 07:01:24 smsl-n100 wireless.js[3314]: DHCP IP May 20 07:01:24 smsl-n100 wireless.js[3314]: Start ap May 20 07:01:24 smsl-n100 wpa_supplicant[3432]: Successfully initialized wpa_supplicant May 20 07:01:25 smsl-n100 sudo[3435]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 20 07:01:25 smsl-n100 sudo[3435]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:25 smsl-n100 dhcpcd[3446]: sending commands to master dhcpcd process May 20 07:01:25 smsl-n100 sudo[3435]: pam_unix(sudo:session): session closed for user root May 20 07:01:25 smsl-n100 dhcpcd[946]: wlan0: carrier acquired May 20 07:01:25 smsl-n100 dhcpcd[946]: wlan0: IAID 2b:ee:c8:63 May 20 07:01:25 smsl-n100 dhcpcd[946]: control command: /sbin/dhcpcd May 20 07:01:25 smsl-n100 dhcpcd[946]: wlan0: carrier lost May 20 07:01:25 smsl-n100 wireless.js[3314]: trying... May 20 07:01:26 smsl-n100 sudo[3481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 20 07:01:26 smsl-n100 sudo[3479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 07:00 May 20 07:01:26 smsl-n100 sudo[3481]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:26 smsl-n100 sudo[3479]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 07:01:26 smsl-n100 sudo[3481]: pam_unix(sudo:session): session closed for user root 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"