-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Mon 2025-08-25 18:56:26 PDT. -- Aug 25 18:55:00 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Fetching time from Volumio... Aug 25 18:55:02 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 25 18:55:04 smsl-n100 sudo[14163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 18:55:04 smsl-n100 sudo[14163]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:04 smsl-n100 sudo[14163]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:04 smsl-n100 sudo[14166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 18:55:04 smsl-n100 sudo[14166]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:04 smsl-n100 sudo[14166]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:04 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.162 UA: Mozilla/5.0 (Linux; Android 16; SM-S938W Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Received Get System Info Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Discovery: Getting this device information Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 18:55:04 smsl-n100 volumio-remote-updater[13805]: No test mode Aug 25 18:55:04 smsl-n100 volumio-remote-updater[13805]: No alpha test mode Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Retrieving Cloud Streaming UI Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Getting Tidal Cloud Configuration Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Getting Qobuz Cloud Configuration Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Asking plugin for UI Config Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Getting Spotify Cloud Configuration Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Asking plugin for UI Config Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Saving Spotify Acccount Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Got it Aug 25 18:55:04 smsl-n100 volumio[12907]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Got Tidal Cloud Configuration Aug 25 18:55:04 smsl-n100 volumio[12907]: info: Got it Aug 25 18:55:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:05 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 25 18:55:05 smsl-n100 volumio[12907]: info: Loading i18n strings for locale en Aug 25 18:55:05 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 25 18:55:05 smsl-n100 volumio[12907]: info: Setting timezone to America/Vancouver Aug 25 18:55:05 smsl-n100 sudo[14170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 25 18:55:05 smsl-n100 sudo[14170]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:05 smsl-n100 sudo[14170]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:05 smsl-n100 sudo[14173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Vancouver /etc/localtime Aug 25 18:55:05 smsl-n100 sudo[14173]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:05 smsl-n100 sudo[14173]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:05 smsl-n100 sudo[14176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 25 18:55:05 smsl-n100 sudo[14176]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:05 smsl-n100 sudo[14176]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:06 smsl-n100 sudo[14179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Vancouver Aug 25 18:55:06 smsl-n100 sudo[14179]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:06 smsl-n100 dbus-daemon[533]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.107' (uid=0 pid=14180 comm="/usr/bin/timedatectl set-timezone America/Vancouve") Aug 25 18:55:06 smsl-n100 systemd[1]: Starting Time & Date Service... Aug 25 18:55:06 smsl-n100 dbus-daemon[533]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 25 18:55:06 smsl-n100 systemd[1]: Started Time & Date Service. Aug 25 18:55:06 smsl-n100 sudo[14179]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:06 smsl-n100 volumio[12907]: info: Fetching Streaming Services browse cache Aug 25 18:55:06 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:06 smsl-n100 volumio[12907]: Updating browse sources language Aug 25 18:55:06 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 18:55:06 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 18:55:08 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 25 18:55:08 smsl-n100 volumio[12907]: info: Loading i18n strings for locale en Aug 25 18:55:08 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 25 18:55:08 smsl-n100 volumio[12907]: info: Setting timezone to America/Vancouver Aug 25 18:55:08 smsl-n100 sudo[14183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 25 18:55:08 smsl-n100 sudo[14183]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:08 smsl-n100 sudo[14183]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:08 smsl-n100 sudo[14186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Vancouver /etc/localtime Aug 25 18:55:08 smsl-n100 sudo[14186]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:08 smsl-n100 sudo[14186]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:08 smsl-n100 sudo[14189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 25 18:55:08 smsl-n100 sudo[14189]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:08 smsl-n100 sudo[14189]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:08 smsl-n100 sudo[14192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Vancouver Aug 25 18:55:08 smsl-n100 sudo[14192]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:08 smsl-n100 sudo[14192]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:08 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 25 18:55:08 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 25 18:55:08 smsl-n100 sudo[14195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 25 18:55:08 smsl-n100 sudo[14195]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:08 smsl-n100 volumio[12907]: Updating browse sources language Aug 25 18:55:08 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 18:55:08 smsl-n100 volumio[12907]: info: Fetching Streaming Services browse cache Aug 25 18:55:09 smsl-n100 sudo[14195]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:09 smsl-n100 ntpd[751]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 25 18:55:11 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Aug 25 18:55:11 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Aug 25 18:55:12 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:12 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 25 18:55:12 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:12 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 18:55:16 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 25 18:55:16 smsl-n100 volumio[12907]: info: Preparing to save Alsa Options, stopping services first Aug 25 18:55:16 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:55:16 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:16 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPause Aug 25 18:55:16 smsl-n100 volumio[12907]: info: CoreStateMachine::pause Aug 25 18:55:16 smsl-n100 volumio[12907]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"SPDIF"},"disallowPush":true} Aug 25 18:55:16 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 18:55:16 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:16 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:16 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 18:55:16 smsl-n100 volumio[12907]: info: Found match in Cards Database: setting mixer for card SPDIF Aug 25 18:55:16 smsl-n100 volumio[12907]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Aug 25 18:55:17 smsl-n100 vtcs[13963]: [2025-08-25 18:55:16.999] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Aug 25 18:55:17 smsl-n100 vtcs[13963]: [2025-08-25 18:55:17.000] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Aug 25 18:55:17 smsl-n100 sudo[14221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:17 smsl-n100 sudo[14221]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:17 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Aug 25 18:55:17 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:17 smsl-n100 systemd[1]: vtcs.service: Succeeded. Aug 25 18:55:17 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Aug 25 18:55:17 smsl-n100 sudo[14221]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:17 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:17 smsl-n100 volumio[12907]: info: Device 1 does not have any Mixer Control Available Aug 25 18:55:17 smsl-n100 volumio[12907]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Aug 25 18:55:17 smsl-n100 sudo[14227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:17 smsl-n100 sudo[14227]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:17 smsl-n100 sudo[14227]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:17 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 25 18:55:17 smsl-n100 volumio[12907]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 25 18:55:17 smsl-n100 volumio[12907]: info: Disabling external Volume Control Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:17 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:17 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:17 smsl-n100 volumio[12907]: info: Preparing to generate the ALSA configuration file Aug 25 18:55:17 smsl-n100 sudo[14234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:17 smsl-n100 sudo[14234]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:17 smsl-n100 sudo[14237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:17 smsl-n100 sudo[14237]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:17 smsl-n100 sudo[14234]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: Not Reporting Auto name since its the default one Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 25 18:55:17 smsl-n100 sudo[14237]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 25 18:55:17 smsl-n100 sudo[14246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 25 18:55:17 smsl-n100 sudo[14246]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:17 smsl-n100 volumio[12907]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 25 18:55:17 smsl-n100 volumio[12907]: info: Reading ALSA contributions from plugins. Aug 25 18:55:17 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:17 smsl-n100 sudo[14249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 25 18:55:17 smsl-n100 sudo[14249]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:17 smsl-n100 volumio[12907]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:17 smsl-n100 volumio[12907]: info: Not Reporting Auto name since its the default one Aug 25 18:55:18 smsl-n100 sudo[14246]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:18 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 18:55:18 smsl-n100 sudo[14249]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:18 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 25 18:55:18 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 25 18:55:18 smsl-n100 sudo[14262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 25 18:55:18 smsl-n100 sudo[14262]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:18 smsl-n100 sudo[14262]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:18 smsl-n100 sudo[14266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 25 18:55:18 smsl-n100 sudo[14266]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:18 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:18 smsl-n100 systemd[1]: Started RAAT DAEMON. Aug 25 18:55:18 smsl-n100 sudo[14266]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:18 smsl-n100 sudo[14270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 25 18:55:18 smsl-n100 sudo[14270]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:18 smsl-n100 volumio[12907]: info: Raat Daemon started successfully Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: Asound.conf file written Aug 25 18:55:18 smsl-n100 sudo[14270]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:18 smsl-n100 sudo[14276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 25 18:55:18 smsl-n100 sudo[14276]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:18 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Aug 25 18:55:18 smsl-n100 sudo[14282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 25 18:55:18 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:18 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Aug 25 18:55:18 smsl-n100 sudo[14282]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:18 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Aug 25 18:55:18 smsl-n100 sudo[14282]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:18 smsl-n100 systemd[1]: Started RAAT DAEMON. Aug 25 18:55:18 smsl-n100 sudo[14276]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:18 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:18 smsl-n100 volumio[12907]: info: Output device has changed, restarting MPD Aug 25 18:55:18 smsl-n100 sudo[14298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 18:55:18 smsl-n100 sudo[14298]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:18 smsl-n100 volumio[12907]: info: Output device has changed, restarting Shairport Sync Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:18 smsl-n100 sudo[14298]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:18 smsl-n100 sudo[14302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 18:55:18 smsl-n100 sudo[14302]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:18 smsl-n100 systemd[1]: Stopping Music Player Daemon... Aug 25 18:55:18 smsl-n100 systemd[1]: mpd.service: Succeeded. Aug 25 18:55:18 smsl-n100 systemd[1]: Stopped Music Player Daemon. Aug 25 18:55:18 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:18 smsl-n100 volumio[12907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 18:55:18 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:18 smsl-n100 volumio[12907]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 25 18:55:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:18 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 25 18:55:19 smsl-n100 volumio[12907]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: Output device has changed, restarting MPD Aug 25 18:55:19 smsl-n100 sudo[14311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:19 smsl-n100 sudo[14311]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:19 smsl-n100 sudo[14307]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 18:55:19 smsl-n100 sudo[14307]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:19 smsl-n100 sudo[14314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 18:55:19 smsl-n100 sudo[14314]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:19 smsl-n100 sudo[14314]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:19 smsl-n100 sudo[14307]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:19 smsl-n100 volumio[12907]: info: Output device has changed, restarting Shairport Sync Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:19 smsl-n100 sudo[14311]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:19 smsl-n100 sudo[14318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 18:55:19 smsl-n100 sudo[14318]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:19 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:19 smsl-n100 systemd[1]: mpd.service: Succeeded. Aug 25 18:55:19 smsl-n100 systemd[1]: Stopped Music Player Daemon. Aug 25 18:55:19 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 25 18:55:19 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:19 smsl-n100 volumio[12907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 18:55:19 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 sudo[14329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:19 smsl-n100 sudo[14329]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:19 smsl-n100 sudo[14325]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 18:55:19 smsl-n100 sudo[14325]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:19 smsl-n100 sudo[14332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:19 smsl-n100 sudo[14332]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:19 smsl-n100 sudo[14325]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:19 smsl-n100 sudo[14329]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:19 smsl-n100 sudo[14335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:19 smsl-n100 sudo[14335]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 sudo[14332]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:19 smsl-n100 volumio[12907]: info: Not Reporting Auto name since its the default one Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:19 smsl-n100 sudo[14335]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:19 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MPD Permissions set Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MPD Permissions set Aug 25 18:55:19 smsl-n100 volumio[12907]: info: Raat Daemon started successfully Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:19 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:19 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:19 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:19 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:19 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:19 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:19 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:19 smsl-n100 volumio[12907]: info: Executing endpoint restartRAATSocket Aug 25 18:55:19 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: Not Reporting Auto name since its the default one Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 25 18:55:20 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:20 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:20 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 25 18:55:20 smsl-n100 sudo[14368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 25 18:55:20 smsl-n100 sudo[14368]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:20 smsl-n100 sudo[14368]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:20 smsl-n100 sudo[14384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 25 18:55:20 smsl-n100 sudo[14384]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:20 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:20 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Aug 25 18:55:20 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:20 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Aug 25 18:55:20 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Aug 25 18:55:20 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Date not found in response Aug 25 18:55:20 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Retrying in 5 seconds... Aug 25 18:55:20 smsl-n100 systemd[1]: Started RAAT DAEMON. Aug 25 18:55:20 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:20 smsl-n100 sudo[14384]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:20 smsl-n100 sudo[14391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 25 18:55:20 smsl-n100 sudo[14391]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:20 smsl-n100 volumio[12907]: info: Starting Shairport Sync Aug 25 18:55:20 smsl-n100 sudo[14391]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Starting Shairport Sync Aug 25 18:55:21 smsl-n100 sudo[14404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 25 18:55:21 smsl-n100 sudo[14404]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:21 smsl-n100 sudo[14407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 18:55:21 smsl-n100 sudo[14407]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:21 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:21 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:21 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Aug 25 18:55:21 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:21 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Aug 25 18:55:21 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Raat Daemon started successfully Aug 25 18:55:21 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:21 smsl-n100 sudo[14412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 18:55:21 smsl-n100 sudo[14412]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:21 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 18:55:21 smsl-n100 systemd[1]: Started RAAT DAEMON. Aug 25 18:55:21 smsl-n100 sudo[14404]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Preparing to generate the ALSA configuration file Aug 25 18:55:21 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 25 18:55:21 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 25 18:55:21 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 18:55:21 smsl-n100 sudo[14407]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:21 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:21 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:21 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Raat Daemon started successfully Aug 25 18:55:21 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 18:55:21 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:21 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 25 18:55:21 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 25 18:55:21 smsl-n100 volumio[12907]: info: RAAT: Requesting Headphone Status Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 25 18:55:21 smsl-n100 volumio[12907]: info: RAAT: Requesting Headphone Status Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreStateMachine::getcurrentVolume Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:21 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:21 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:21 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 18:55:21 smsl-n100 sudo[14412]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:21 smsl-n100 volumio[12907]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Reading ALSA contributions from plugins. Aug 25 18:55:21 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:21 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:21 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Executing endpoint restartRAATSocket Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Shairport-Sync Started Aug 25 18:55:21 smsl-n100 sudo[14437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 25 18:55:21 smsl-n100 sudo[14437]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Shairport-Sync Started Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:21 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:21 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:21 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:21 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Aug 25 18:55:21 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:21 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:21 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:21 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:21 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:21 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:21 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:21 smsl-n100 sudo[14437]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:21 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:21 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:21 smsl-n100 volumio[12907]: info: Executing endpoint restartRAATSocket Aug 25 18:55:21 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 25 18:55:22 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:22 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:22 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Aug 25 18:55:22 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Aug 25 18:55:22 smsl-n100 sudo[14302]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:22 smsl-n100 sudo[14318]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Aug 25 18:55:22 smsl-n100 volumio[12907]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Aug 25 18:55:22 smsl-n100 volumio[12907]: See "systemctl status mpd.service" and "journalctl -xe" for details. Aug 25 18:55:22 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 25 18:55:22 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Aug 25 18:55:22 smsl-n100 volumio[12907]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Aug 25 18:55:22 smsl-n100 volumio[12907]: See "systemctl status mpd.service" and "journalctl -xe" for details. Aug 25 18:55:22 smsl-n100 sudo[14450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 25 18:55:22 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:22 smsl-n100 sudo[14450]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Asound.conf file written Aug 25 18:55:22 smsl-n100 sudo[14450]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:22 smsl-n100 sudo[14454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 25 18:55:22 smsl-n100 sudo[14454]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:22 smsl-n100 sudo[14454]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:22 smsl-n100 sudo[14451]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 18:55:22 smsl-n100 sudo[14451]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:22 smsl-n100 sudo[14451]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:22 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Output device has changed, restarting MPD Aug 25 18:55:22 smsl-n100 sudo[14462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 18:55:22 smsl-n100 sudo[14462]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Output device has changed, restarting Shairport Sync Aug 25 18:55:22 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:22 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:22 smsl-n100 sudo[14462]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:22 smsl-n100 sudo[14466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 18:55:22 smsl-n100 sudo[14466]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:22 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:22 smsl-n100 systemd[1]: mpd.service: Succeeded. Aug 25 18:55:22 smsl-n100 systemd[1]: Stopped Music Player Daemon. Aug 25 18:55:22 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 25 18:55:22 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 18:55:22 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:22 smsl-n100 volumio[12907]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 25 18:55:22 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 25 18:55:22 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Preparing to generate the ALSA configuration file Aug 25 18:55:22 smsl-n100 sudo[14475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:22 smsl-n100 sudo[14475]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:22 smsl-n100 sudo[14471]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 18:55:22 smsl-n100 sudo[14471]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:22 smsl-n100 sudo[14471]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:22 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Aug 25 18:55:22 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:22 smsl-n100 systemd[1]: vtcs.service: Succeeded. Aug 25 18:55:22 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Aug 25 18:55:22 smsl-n100 volumio[12907]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 25 18:55:22 smsl-n100 volumio[12907]: info: Reading ALSA contributions from plugins. Aug 25 18:55:22 smsl-n100 sudo[14475]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:23 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:23 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:23 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:23 smsl-n100 sudo[14480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:23 smsl-n100 sudo[14480]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:23 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:23 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:23 smsl-n100 volumio[12907]: info: MPD Permissions set Aug 25 18:55:23 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:23 smsl-n100 sudo[14480]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:23 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:23 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:23 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:23 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:23 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:23 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:23 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:23 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:23 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:23 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:23 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:23 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:23 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:23 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:23 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:23 smsl-n100 volumio[12907]: info: Executing endpoint tc_getconfig Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: Not Reporting Auto name since its the default one Aug 25 18:55:23 smsl-n100 volumio[12907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:23 smsl-n100 volumio[12907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:23 smsl-n100 sudo[14501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 25 18:55:23 smsl-n100 sudo[14501]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Aug 25 18:55:23 smsl-n100 sudo[14501]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:23 smsl-n100 volumio[12907]: info: RAAT: Requesting Headphone Status Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 25 18:55:23 smsl-n100 volumio[12907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:23 smsl-n100 volumio[12907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 25 18:55:23 smsl-n100 sudo[14517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 25 18:55:23 smsl-n100 sudo[14517]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:24 smsl-n100 sudo[14517]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:24 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:24 smsl-n100 volumio[12907]: info: RAAT: Requesting Headphone Status Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 25 18:55:24 smsl-n100 volumio[12907]: info: Starting Shairport Sync Aug 25 18:55:24 smsl-n100 sudo[14523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 25 18:55:24 smsl-n100 sudo[14523]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:24 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:24 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:24 smsl-n100 sudo[14523]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:24 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:24 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:24 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:24 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:24 smsl-n100 sudo[14527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:24 smsl-n100 sudo[14527]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:24 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:24 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:24 smsl-n100 sudo[14528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 25 18:55:24 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:24 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:24 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:24 smsl-n100 sudo[14528]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:24 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:24 smsl-n100 volumio[12907]: info: Asound.conf file unchanged, so no further update is needed Aug 25 18:55:24 smsl-n100 volumio[12907]: info: Output device has changed, restarting MPD Aug 25 18:55:24 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 18:55:24 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 25 18:55:24 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 25 18:55:24 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Aug 25 18:55:24 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 18:55:24 smsl-n100 sudo[14527]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:24 smsl-n100 sudo[14533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 18:55:24 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:24 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Aug 25 18:55:24 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Aug 25 18:55:24 smsl-n100 sudo[14533]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:24 smsl-n100 volumio[12907]: info: Output device has changed, restarting Shairport Sync Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:24 smsl-n100 sudo[14533]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:24 smsl-n100 systemd[1]: Started RAAT DAEMON. Aug 25 18:55:24 smsl-n100 sudo[14528]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:24 smsl-n100 sudo[14537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 18:55:24 smsl-n100 sudo[14537]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:24 smsl-n100 volumio-remote-updater[13805]: curl_easy_perform() failed: Couldn't resolve host name Aug 25 18:55:24 smsl-n100 volumio-remote-updater[13805]: terminate called after throwing an instance of 'std::invalid_argument' Aug 25 18:55:24 smsl-n100 volumio-remote-updater[13805]: what(): parse error - unexpected end of input Aug 25 18:55:24 smsl-n100 systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Aug 25 18:55:24 smsl-n100 systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Aug 25 18:55:24 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:24 smsl-n100 systemd[1]: mpd.service: Succeeded. Aug 25 18:55:24 smsl-n100 systemd[1]: Stopped Music Player Daemon. Aug 25 18:55:24 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:24 smsl-n100 volumio[12907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 18:55:24 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:24 smsl-n100 volumio[12907]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:24 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 25 18:55:24 smsl-n100 systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Aug 25 18:55:24 smsl-n100 systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 2. Aug 25 18:55:24 smsl-n100 systemd[1]: Stopped volumio-remote-updater.service. Aug 25 18:55:24 smsl-n100 systemd[1]: Started volumio-remote-updater.service. Aug 25 18:55:24 smsl-n100 volumio[12907]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 25 18:55:24 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio-remote-updater[14565]: Error: No active session Aug 25 18:55:25 smsl-n100 volumio-remote-updater[14565]: [2025-08-25 18:55:25] [connect] Successful connection Aug 25 18:55:25 smsl-n100 sudo[14574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:25 smsl-n100 sudo[14574]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:25 smsl-n100 sudo[14560]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 18:55:25 smsl-n100 sudo[14560]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:25 smsl-n100 sudo[14582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 25 18:55:25 smsl-n100 sudo[14582]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:25 smsl-n100 sudo[14560]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:25 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Aug 25 18:55:25 smsl-n100 volumio[12907]: info: Executing endpoint tc_getconfig Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 25 18:55:25 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:25 smsl-n100 systemd[1]: vtcs.service: Succeeded. Aug 25 18:55:25 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:25 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:25 smsl-n100 sudo[14574]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:25 smsl-n100 sudo[14582]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:25 smsl-n100 volumio[12907]: info: MPD Permissions set Aug 25 18:55:25 smsl-n100 volumio[12907]: info: TidalConnect service started! Aug 25 18:55:25 smsl-n100 volumio[12907]: info: Raat Daemon started successfully Aug 25 18:55:25 smsl-n100 volumio[12907]: info: Shairport-Sync Started Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:25 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:25 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:25 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:25 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:25 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:25 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:25 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:25 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:25 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:25 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:25 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:25 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:25 smsl-n100 volumio[12907]: info: Executing endpoint restartRAATSocket Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 25 18:55:25 smsl-n100 volumio-remote-updater[14565]: [2025-08-25 18:55:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756173325 101 Aug 25 18:55:25 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Aug 25 18:55:25 smsl-n100 volumio[12907]: info: TidalConnect service started! Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: Not Reporting Auto name since its the default one Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 25 18:55:25 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Fetching time from Volumio... Aug 25 18:55:25 smsl-n100 volumio[12907]: xcb_connection_has_error() returned true Aug 25 18:55:25 smsl-n100 volumio[12907]: info: Starting Shairport Sync Aug 25 18:55:25 smsl-n100 sudo[14625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 25 18:55:25 smsl-n100 sudo[14625]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:26 smsl-n100 sudo[14625]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:26 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:26 smsl-n100 sudo[14628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 18:55:26 smsl-n100 sudo[14628]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:26 smsl-n100 sudo[14629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 25 18:55:26 smsl-n100 sudo[14629]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:26 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:26 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 18:55:26 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 25 18:55:26 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 25 18:55:26 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:26 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:26 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Aug 25 18:55:26 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 18:55:26 smsl-n100 sudo[14628]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:26 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:26 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Aug 25 18:55:26 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Aug 25 18:55:26 smsl-n100 systemd[1]: Started RAAT DAEMON. Aug 25 18:55:26 smsl-n100 sudo[14629]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:26 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:26 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:26 smsl-n100 volumio[12907]: info: Shairport-Sync Started Aug 25 18:55:26 smsl-n100 volumio[12907]: info: Raat Daemon started successfully Aug 25 18:55:26 smsl-n100 volumio[12907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:26 smsl-n100 volumio[12907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:26 smsl-n100 volumio[12907]: info: Executing endpoint restartRAATSocket Aug 25 18:55:26 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 25 18:55:26 smsl-n100 sudo[14654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 25 18:55:26 smsl-n100 sudo[14654]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:26 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:26 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Aug 25 18:55:26 smsl-n100 sudo[14654]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:26 smsl-n100 volumio[12907]: info: TidalConnect service started! Aug 25 18:55:27 smsl-n100 volumio[12907]: info: TidalConnect service started! Aug 25 18:55:27 smsl-n100 volumio[12907]: info: RAAT: Requesting Headphone Status Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:27 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:27 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:27 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:27 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:27 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:27 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:27 smsl-n100 volumio[12907]: info: Executing endpoint tc_getconfig Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 25 18:55:27 smsl-n100 vtcs[14657]: STARTING TidalConnect services, version: 1.4.0.34 Aug 25 18:55:27 smsl-n100 vtcs[14657]: STARTED TidalConnect services. Aug 25 18:55:27 smsl-n100 volumio[12907]: info: Executing endpoint tc_connect Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 25 18:55:27 smsl-n100 volumio[12907]: info: Connecting to TidalConnect Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreCommandRouter::servicePushState Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:27 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:27 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:27 smsl-n100 volumio[12907]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreCommandRouter::servicePushState Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:27 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:27 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:27 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:27 smsl-n100 volumio[12907]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Aug 25 18:55:28 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:28 smsl-n100 volumio[12907]: info: TidalConnect service stoped! Aug 25 18:55:28 smsl-n100 volumio[12907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:28 smsl-n100 volumio[12907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 25 18:55:28 smsl-n100 sudo[14685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 25 18:55:28 smsl-n100 sudo[14685]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:28 smsl-n100 sudo[14685]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:28 smsl-n100 volumio[12907]: info: RAAT: Requesting Headphone Status Aug 25 18:55:28 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 25 18:55:28 smsl-n100 volumio[12907]: info: CoreStateMachine::pushState Aug 25 18:55:28 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:28 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:28 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output update for this device Aug 25 18:55:28 smsl-n100 volumio[12907]: info: MRS: Pushing multiroomSync output Aug 25 18:55:28 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:28 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 25 18:55:28 smsl-n100 volumio[12907]: error: Serial API: Failed to decode command: 100, message: undefined Aug 25 18:55:28 smsl-n100 volumio[12907]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 25 18:55:29 smsl-n100 mpd[14591]: Aug 25 18:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 25 18:55:29 smsl-n100 systemd[1]: Started Music Player Daemon. Aug 25 18:55:29 smsl-n100 sudo[14537]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:29 smsl-n100 mpd_monitor.sh[548]: MPD restarted due to no mpc output. Aug 25 18:55:29 smsl-n100 volumio[12907]: error: MPD error: The expression evaluated to a falsy value: Aug 25 18:55:29 smsl-n100 volumio[12907]: assert.ok(self.idling) Aug 25 18:55:29 smsl-n100 volumio[12907]: error: The expression evaluated to a falsy value: Aug 25 18:55:29 smsl-n100 volumio[12907]: assert.ok(self.idling) Aug 25 18:55:29 smsl-n100 sudo[14466]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:29 smsl-n100 volumio[12907]: error: updateQueue error: null Aug 25 18:55:29 smsl-n100 volumio[12907]: error: MPD error: The expression evaluated to a falsy value: Aug 25 18:55:29 smsl-n100 volumio[12907]: assert.ok(self.idling) Aug 25 18:55:29 smsl-n100 volumio[12907]: error: The expression evaluated to a falsy value: Aug 25 18:55:29 smsl-n100 volumio[12907]: assert.ok(self.idling) Aug 25 18:55:29 smsl-n100 volumio[12907]: error: updateQueue error: null Aug 25 18:55:29 smsl-n100 ntpd[751]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 25 18:55:29 smsl-n100 volumio[12907]: info: TidalConnect service started! Aug 25 18:55:31 smsl-n100 volumio[12907]: info: TidalConnect service started! Aug 25 18:55:36 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 25 18:55:36 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 18:55:38 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Aug 25 18:55:38 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Aug 25 18:55:38 smsl-n100 sudo[14706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 18:55:38 smsl-n100 sudo[14706]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:38 smsl-n100 sudo[14706]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:38 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 25 18:55:38 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 18:55:38 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded. Aug 25 18:55:42 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 25 18:55:45 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Date not found in response Aug 25 18:55:45 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Retrying in 5 seconds... Aug 25 18:55:48 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:48 smsl-n100 volumio[12907]: info: Retrieving Cloud Streaming UI Aug 25 18:55:48 smsl-n100 volumio[12907]: info: Getting Tidal Cloud Configuration Aug 25 18:55:48 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:49 smsl-n100 volumio[12907]: info: Getting Qobuz Cloud Configuration Aug 25 18:55:49 smsl-n100 volumio[12907]: info: Asking plugin for UI Config Aug 25 18:55:49 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:49 smsl-n100 volumio[12907]: info: Getting Spotify Cloud Configuration Aug 25 18:55:49 smsl-n100 volumio[12907]: info: Asking plugin for UI Config Aug 25 18:55:49 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:49 smsl-n100 volumio[12907]: info: Saving Spotify Acccount Aug 25 18:55:49 smsl-n100 volumio[12907]: info: Got it Aug 25 18:55:49 smsl-n100 volumio[12907]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 25 18:55:49 smsl-n100 volumio[12907]: info: Got Tidal Cloud Configuration Aug 25 18:55:49 smsl-n100 volumio[12907]: info: Got it Aug 25 18:55:49 smsl-n100 volumio[12907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:49 smsl-n100 ntpd[751]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 25 18:55:50 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Fetching time from Volumio... Aug 25 18:55:51 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Aug 25 18:55:51 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 25 18:55:51 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Aug 25 18:55:51 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Aug 25 18:55:51 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 25 18:56:03 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 25 18:56:03 smsl-n100 volumio[12907]: info: CoreCommandRouter::Reload Ui Aug 25 18:56:03 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Aug 25 18:56:03 smsl-n100 volumio[12907]: info: Wizard terminated Successfully Aug 25 18:56:03 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumiosetStartupVolume Aug 25 18:56:03 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 18:56:03 smsl-n100 volumio[12907]: info: Executing Deferred Wifi Connection Aug 25 18:56:03 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Aug 25 18:56:03 smsl-n100 volumio[12907]: info: Saving new wireless network Aug 25 18:56:03 smsl-n100 sudo[14767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Aug 25 18:56:03 smsl-n100 sudo[14767]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:03 smsl-n100 sudo[14767]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:03 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Aug 25 18:56:03 smsl-n100 volumio[12907]: info: Discovery: Restarting Advertising due to device name change Aug 25 18:56:03 smsl-n100 sudo[14770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 25 18:56:03 smsl-n100 sudo[14770]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:03 smsl-n100 systemd[1]: Starting Wireless Services... Aug 25 18:56:03 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:56:03 smsl-n100 sudo[14780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 18:56:03 smsl-n100 sudo[14780]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:03 smsl-n100 sudo[14780]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:03 smsl-n100 sudo[14783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 18:56:03 smsl-n100 sudo[14783]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:03 smsl-n100 sudo[14783]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:03 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.162 UA: Mozilla/5.0 (Linux; Android 16; SM-S938W Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 25 18:56:04 smsl-n100 sudo[14786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 18:56:04 smsl-n100 sudo[14786]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:04 smsl-n100 sudo[14786]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:04 smsl-n100 sudo[14788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 18:56:04 smsl-n100 sudo[14788]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:04 smsl-n100 sudo[14788]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:04 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.162 UA: Mozilla/5.0 (Linux; Android 16; SM-S938W Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 18:56:04 smsl-n100 volumio[12907]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 25 18:56:04 smsl-n100 volumio[12907]: info: Received Get System Info Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 18:56:04 smsl-n100 volumio[12907]: info: Discovery: Getting this device information Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:04 smsl-n100 volumio[12907]: info: Listing playlists Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 18:56:04 smsl-n100 volumio[12907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 18:56:04 smsl-n100 volumio[12907]: info: Discovery: A device disappeared from network Aug 25 18:56:04 smsl-n100 wireless.js[14772]: Cleaning previous... Aug 25 18:56:04 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Aug 25 18:56:04 smsl-n100 dnsmasq[9065]: exiting on receipt of SIGTERM Aug 25 18:56:04 smsl-n100 systemd[1]: dnsmasq.service: Succeeded. Aug 25 18:56:04 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Aug 25 18:56:04 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:56:04 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Aug 25 18:56:04 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:56:04 smsl-n100 dhcpcd[580]: wlan0: carrier lost Aug 25 18:56:04 smsl-n100 systemd[1]: hostapd.service: Succeeded. Aug 25 18:56:04 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Aug 25 18:56:04 smsl-n100 dhcpcd[580]: wlan0: deleting route to 169.254.0.0/16 Aug 25 18:56:04 smsl-n100 dhcpcd[580]: wlan0: deleting default route Aug 25 18:56:04 smsl-n100 avahi-daemon[552]: Withdrawing address record for 169.254.124.160 on wlan0. Aug 25 18:56:05 smsl-n100 sudo[14815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 25 18:56:05 smsl-n100 sudo[14815]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:05 smsl-n100 avahi-daemon[552]: Withdrawing address record for 192.168.211.1 on wlan0. Aug 25 18:56:05 smsl-n100 avahi-daemon[552]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Aug 25 18:56:05 smsl-n100 avahi-daemon[552]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 25 18:56:05 smsl-n100 sudo[14815]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:05 smsl-n100 sudo[14825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 25 18:56:05 smsl-n100 sudo[14825]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:05 smsl-n100 sudo[14825]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:05 smsl-n100 wireless.js[14772]: Stopped aP Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 25 18:56:05 smsl-n100 sudo[14832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 25 18:56:05 smsl-n100 sudo[14832]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:05 smsl-n100 sudo[14832]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:05 smsl-n100 sudo[14834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 25 18:56:05 smsl-n100 sudo[14834]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 25 18:56:05 smsl-n100 sudo[14834]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Aug 25 18:56:05 smsl-n100 sudo[14841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 25 18:56:05 smsl-n100 sudo[14841]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Aug 25 18:56:05 smsl-n100 sudo[14841]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Aug 25 18:56:05 smsl-n100 sudo[14843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 25 18:56:05 smsl-n100 sudo[14843]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 25 18:56:05 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Aug 25 18:56:05 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:05 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:05 smsl-n100 dhcpcd[580]: wlan0: carrier acquired Aug 25 18:56:05 smsl-n100 dhcpcd[580]: wlan0: IAID 2b:ee:c9:49 Aug 25 18:56:05 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Date not found in response Aug 25 18:56:05 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Retrying in 5 seconds... Aug 25 18:56:06 smsl-n100 dhcpcd[580]: wlan0: soliciting an IPv6 router Aug 25 18:56:06 smsl-n100 dhcpcd[580]: wlan0: soliciting a DHCP lease Aug 25 18:56:06 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Aug 25 18:56:06 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:06 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:07 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Aug 25 18:56:07 smsl-n100 ntpd[751]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=955 secs Aug 25 18:56:07 smsl-n100 ntpd[751]: Deleting interface #4 wlan0, 169.254.124.160#123, interface stats: received=0, sent=0, dropped=0, active_time=944 secs Aug 25 18:56:07 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:07 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:08 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Aug 25 18:56:08 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:08 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:08 smsl-n100 volumio[12907]: info: Discovery: Started advertising with name: SMSL N100 Aug 25 18:56:08 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Aug 25 18:56:08 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:08 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 25 18:56:09 smsl-n100 ntpd[751]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 25 18:56:10 smsl-n100 sudo[14843]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:10 smsl-n100 wireless.js[14772]: Start wireless flow Aug 25 18:56:10 smsl-n100 wireless.js[14772]: Stopped hotspot (if there).. Aug 25 18:56:10 smsl-n100 sudo[14854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 25 18:56:10 smsl-n100 sudo[14854]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:10 smsl-n100 sudo[14854]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:10 smsl-n100 sudo[14856]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 25 18:56:10 smsl-n100 sudo[14856]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:10 smsl-n100 dhcpcd[580]: wlan0: carrier lost Aug 25 18:56:10 smsl-n100 sudo[14856]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:10 smsl-n100 wireless.js[14772]: DHCP IP Aug 25 18:56:10 smsl-n100 wireless.js[14772]: Start ap Aug 25 18:56:10 smsl-n100 wpa_supplicant[14861]: Successfully initialized wpa_supplicant Aug 25 18:56:10 smsl-n100 sudo[14865]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 25 18:56:10 smsl-n100 sudo[14865]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:10 smsl-n100 dhcpcd[580]: wlan0: carrier acquired Aug 25 18:56:10 smsl-n100 dhcpcd[14874]: sending commands to master dhcpcd process Aug 25 18:56:10 smsl-n100 sudo[14865]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:10 smsl-n100 dhcpcd[580]: wlan0: IAID 2b:ee:c9:49 Aug 25 18:56:10 smsl-n100 dhcpcd[580]: wlan0: carrier lost Aug 25 18:56:10 smsl-n100 dhcpcd[580]: control command: /sbin/dhcpcd Aug 25 18:56:10 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Fetching time from Volumio... Aug 25 18:56:11 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Date not found in response Aug 25 18:56:11 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Retrying in 5 seconds... Aug 25 18:56:11 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:11 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Aug 25 18:56:11 smsl-n100 sudo[14894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:11 smsl-n100 sudo[14894]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:11 smsl-n100 sudo[14894]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:11 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:11 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:12 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:12 smsl-n100 sudo[14907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:12 smsl-n100 sudo[14907]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:12 smsl-n100 sudo[14907]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:12 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Aug 25 18:56:12 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:12 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:13 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 25 18:56:13 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:13 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:13 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:13 smsl-n100 sudo[14910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:13 smsl-n100 sudo[14910]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:13 smsl-n100 sudo[14910]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:14 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:14 smsl-n100 sudo[14913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:14 smsl-n100 sudo[14913]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:14 smsl-n100 sudo[14913]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:14 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 25 18:56:14 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:14 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:15 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:15 smsl-n100 sudo[14916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:15 smsl-n100 sudo[14916]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:15 smsl-n100 sudo[14916]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:15 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 25 18:56:15 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:15 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:15 smsl-n100 wpa_supplicant[14877]: wlan0: Trying to associate with cc:d4:2e:c7:cf:d0 (SSID='MyHomeWifi-5G2' freq=5805 MHz) Aug 25 18:56:16 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Fetching time from Volumio... Aug 25 18:56:16 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Date not found in response Aug 25 18:56:16 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Retrying in 5 seconds... Aug 25 18:56:16 smsl-n100 wpa_supplicant[14877]: wlan0: Associated with cc:d4:2e:c7:cf:d0 Aug 25 18:56:16 smsl-n100 wpa_supplicant[14877]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 25 18:56:16 smsl-n100 wpa_supplicant[14877]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CA Aug 25 18:56:16 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:16 smsl-n100 wpa_supplicant[14877]: wlan0: WPA: Key negotiation completed with cc:d4:2e:c7:cf:d0 [PTK=CCMP GTK=CCMP] Aug 25 18:56:16 smsl-n100 wpa_supplicant[14877]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:d4:2e:c7:cf:d0 completed [id=0 id_str=] Aug 25 18:56:16 smsl-n100 dhcpcd[580]: wlan0: carrier acquired Aug 25 18:56:16 smsl-n100 dhcpcd[580]: wlan0: IAID 2b:ee:c9:49 Aug 25 18:56:16 smsl-n100 sudo[14927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:16 smsl-n100 sudo[14927]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:16 smsl-n100 sudo[14927]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:16 smsl-n100 dhcpcd[580]: wlan0: soliciting an IPv6 router Aug 25 18:56:17 smsl-n100 dhcpcd[580]: wlan0: soliciting a DHCP lease Aug 25 18:56:17 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:17 smsl-n100 sudo[14930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:17 smsl-n100 sudo[14930]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:17 smsl-n100 sudo[14930]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:17 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 25 18:56:17 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:17 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:18 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:18 smsl-n100 sudo[14933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:18 smsl-n100 sudo[14933]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:18 smsl-n100 sudo[14933]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:18 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 25 18:56:18 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:18 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:19 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:19 smsl-n100 sudo[14936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:19 smsl-n100 sudo[14936]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:19 smsl-n100 sudo[14936]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:19 smsl-n100 dhcpcd[580]: wlan0: offered 192.168.1.86 from 192.168.1.254 Aug 25 18:56:19 smsl-n100 dhcpcd[580]: wlan0: probing address 192.168.1.86/24 Aug 25 18:56:20 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 25 18:56:20 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:20 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:20 smsl-n100 sudo[14942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:20 smsl-n100 sudo[14942]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:20 smsl-n100 sudo[14942]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:20 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 25 18:56:20 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:20 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:21 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Fetching time from Volumio... Aug 25 18:56:21 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Date not found in response Aug 25 18:56:21 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Retrying in 5 seconds... Aug 25 18:56:21 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:21 smsl-n100 sudo[14950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:21 smsl-n100 sudo[14950]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:21 smsl-n100 sudo[14950]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:22 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:22 smsl-n100 sudo[14967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:22 smsl-n100 sudo[14967]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:22 smsl-n100 sudo[14967]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:22 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Aug 25 18:56:22 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:22 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:23 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:23 smsl-n100 sudo[14970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:23 smsl-n100 sudo[14970]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:23 smsl-n100 sudo[14970]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:23 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Aug 25 18:56:23 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:23 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:24 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:24 smsl-n100 sudo[14973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:24 smsl-n100 sudo[14973]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:24 smsl-n100 sudo[14973]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:24 smsl-n100 dhcpcd[580]: wlan0: leased 192.168.1.86 for 86400 seconds Aug 25 18:56:24 smsl-n100 dhcpcd[580]: wlan0: adding route to 192.168.1.0/24 Aug 25 18:56:24 smsl-n100 avahi-daemon[552]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.86. Aug 25 18:56:24 smsl-n100 dhcpcd[580]: wlan0: adding default route via 192.168.1.254 Aug 25 18:56:24 smsl-n100 avahi-daemon[552]: New relevant interface wlan0.IPv4 for mDNS. Aug 25 18:56:24 smsl-n100 avahi-daemon[552]: Registering new address record for 192.168.1.86 on wlan0.IPv4. Aug 25 18:56:24 smsl-n100 ntpd[751]: ntpd exiting on signal 15 (Terminated) Aug 25 18:56:24 smsl-n100 systemd[1]: Stopping Network Time Service... Aug 25 18:56:24 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:56:24 smsl-n100 systemd[1]: ntp.service: Succeeded. Aug 25 18:56:24 smsl-n100 systemd[1]: Stopped Network Time Service. Aug 25 18:56:24 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:56:24 smsl-n100 systemd[1]: Starting Network Time Service... Aug 25 18:56:24 smsl-n100 volumio[12907]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:56:24 smsl-n100 ntpd[15009]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 25 18:56:24 smsl-n100 ntpd[15009]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 25 18:56:24 smsl-n100 systemd[1]: Started Network Time Service. Aug 25 18:56:24 smsl-n100 ntpd[15015]: proto: precision = 1.166 usec (-20) Aug 25 18:56:24 smsl-n100 ntpd[15015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 25 18:56:24 smsl-n100 ntpd[15015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 25 18:56:24 smsl-n100 ntpd[15015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 973 days ago Aug 25 18:56:24 smsl-n100 ntpd[15015]: Listen and drop on 0 v6wildcard [::]:123 Aug 25 18:56:24 smsl-n100 ntpd[15015]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 25 18:56:24 smsl-n100 ntpd[15015]: Listen normally on 2 lo 127.0.0.1:123 Aug 25 18:56:24 smsl-n100 ntpd[15015]: Listen normally on 3 wlan0 192.168.1.86:123 Aug 25 18:56:24 smsl-n100 ntpd[15015]: Listening on routing socket on fd #20 for interface updates Aug 25 18:56:24 smsl-n100 ntpd[15015]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Aug 25 18:56:24 smsl-n100 ntpd[15015]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Aug 25 18:56:25 smsl-n100 wireless.js[14772]: trying... Aug 25 18:56:25 smsl-n100 volumio[12907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Aug 25 18:56:25 smsl-n100 sudo[15018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 18:56:25 smsl-n100 sudo[15018]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:25 smsl-n100 sudo[15018]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:25 smsl-n100 wireless.js[14772]: Connected to: ----MyHomeWifi-5G2 Aug 25 18:56:25 smsl-n100 wireless.js[14772]: ---- Aug 25 18:56:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:25 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:25 smsl-n100 sudo[15021]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 18:56:25 smsl-n100 sudo[15021]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:25 smsl-n100 sudo[15021]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:25 smsl-n100 wireless.js[14772]: ... joined AP, wlan0 IPv4 is 192.168.1.86, ipV6 is undefined Aug 25 18:56:25 smsl-n100 wireless.js[14772]: It's done! AP Aug 25 18:56:25 smsl-n100 iwconfig[15040]: Error for wireless request "Set Power Management" (8B2C) : Aug 25 18:56:25 smsl-n100 iwconfig[15040]: SET failed on device wlan0 ; Operation not permitted. Aug 25 18:56:25 smsl-n100 systemd[1]: Started Wireless Services. Aug 25 18:56:25 smsl-n100 sudo[14770]: pam_unix(sudo:session): session closed for user root Aug 25 18:56:25 smsl-n100 volumio[12907]: info: MRS: Found cast device: Pixel-Tablet-e1f399cfa589efa925f3aff15e362dd5 Aug 25 18:56:25 smsl-n100 volumio[12907]: info: Adding audio output: Aug 25 18:56:25 smsl-n100 volumio[12907]: info: Discovery: adding f87496b2-8a1e-4562-9b65-37c0143e8ff1 Aug 25 18:56:25 smsl-n100 volumio[12907]: info: Discovery: Found device SMSL N100 Aug 25 18:56:25 smsl-n100 volumio[12907]: info: CoreCommandRouter::volumioGetState Aug 25 18:56:25 smsl-n100 volumio[12907]: info: CorePlayQueue::getTrack 0 Aug 25 18:56:25 smsl-n100 volumio[12907]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 18:56:25 smsl-n100 volumio[12907]: Error: The value NaN is not a number Aug 25 18:56:25 smsl-n100 volumio[12907]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Aug 25 18:56:25 smsl-n100 volumio[12907]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Aug 25 18:56:25 smsl-n100 volumio[12907]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Aug 25 18:56:25 smsl-n100 volumio[12907]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Aug 25 18:56:25 smsl-n100 volumio[12907]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Aug 25 18:56:25 smsl-n100 volumio[12907]: at Browser.emit (events.js:400:28) Aug 25 18:56:25 smsl-n100 volumio[12907]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Aug 25 18:56:25 smsl-n100 volumio[12907]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Aug 25 18:56:25 smsl-n100 volumio[12907]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Aug 25 18:56:25 smsl-n100 volumio[12907]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Aug 25 18:56:25 smsl-n100 volumio[12907]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Aug 25 18:56:25 smsl-n100 volumio[12907]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Aug 25 18:56:25 smsl-n100 volumio[12907]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Aug 25 18:56:25 smsl-n100 volumio[12907]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 18:56:25 smsl-n100 ntpd[15015]: Soliciting pool server 198.181.199.82 Aug 25 18:56:26 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Fetching time from Volumio... Aug 25 18:56:26 smsl-n100 volumio-time-update[526]: volumio-time-update-util: Setting system time to: 2026-02-21 16:03:14 Aug 25 18:56:26 smsl-n100 sudo[15078]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-02-21 16:03:14 Aug 25 18:56:26 smsl-n100 sudo[15078]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:26 smsl-n100 dbus-daemon[533]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.117' (uid=0 pid=15079 comm="timedatectl set-time 2026-02-21 16:03:14 ") Aug 25 18:56:26 smsl-n100 sudo[15081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-25 18:55 Aug 25 18:56:26 smsl-n100 sudo[15081]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:56:26 smsl-n100 systemd[1]: Starting Time & Date Service... 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="8205c9d25502d90d2bb411bb156e1d5a8ef7404e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 20 Jan 2025 07:23:13 PM CET" VOLUMIO_VERSION="3.788" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="b3195a7f5841a4bc08e3e196c1a91b34"