-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Tue 2025-04-29 20:41:43 BST. -- Apr 29 20:40:14 volumio-snug volumio[981]: info: Tunnel connection is inactive, restarting it Apr 29 20:40:14 volumio-snug volumio[981]: info: Starting Tunnel 1 Apr 29 20:40:14 volumio-snug volumio[981]: info: Starting Tunnel Connection Checker Apr 29 20:40:14 volumio-snug sudo[1750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 29 20:40:14 volumio-snug sudo[1750]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:14 volumio-snug autossh[1363]: received signal to exit (15) Apr 29 20:40:14 volumio-snug systemd[1]: Stopping MyVolumio SSH Tunnel... Apr 29 20:40:14 volumio-snug systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Apr 29 20:40:14 volumio-snug systemd[1]: sshtunnel.service: Succeeded. Apr 29 20:40:14 volumio-snug systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 29 20:40:14 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:14 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 20:40:14 volumio-snug systemd[1]: Started MyVolumio SSH Tunnel. Apr 29 20:40:14 volumio-snug sudo[1750]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:14 volumio-snug autossh[1753]: port set to 0, monitoring disabled Apr 29 20:40:14 volumio-snug autossh[1753]: starting ssh (count 1) Apr 29 20:40:14 volumio-snug autossh[1753]: ssh child pid is 1756 Apr 29 20:40:14 volumio-snug volumio[981]: info: Remote SSH Started Apr 29 20:40:14 volumio-snug volumio[981]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 29 20:40:15 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:40:15 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:15 volumio-snug volumiossh-tunnel[1752]: Warning: Permanently added '[eu8.myvolumio.org]:2222,[178.62.246.67]:2222' (RSA) to the list of known hosts. Apr 29 20:40:16 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 20:40:16 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 20:40:16 volumio-snug volumio[981]: info: Discovery: Getting this device information Apr 29 20:40:16 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:40:16 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:16 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 29 20:40:35 volumio-snug volumio[981]: info: Loading i18n strings for locale en Apr 29 20:40:35 volumio-snug volumio[981]: Updating browse sources language Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 29 20:40:35 volumio-snug volumio[981]: info: Setting timezone to Europe/London Apr 29 20:40:35 volumio-snug sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 29 20:40:35 volumio-snug sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:35 volumio-snug sudo[1790]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:35 volumio-snug sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Apr 29 20:40:35 volumio-snug sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:35 volumio-snug sudo[1793]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:35 volumio-snug sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 29 20:40:35 volumio-snug sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:35 volumio-snug sudo[1796]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:35 volumio-snug sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Apr 29 20:40:35 volumio-snug sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:35 volumio-snug dbus-daemon[585]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.49' (uid=0 pid=1800 comm="/usr/bin/timedatectl set-timezone Europe/London ") Apr 29 20:40:35 volumio-snug systemd[1]: Starting Time & Date Service... Apr 29 20:40:35 volumio-snug dbus-daemon[585]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 29 20:40:35 volumio-snug systemd[1]: Started Time & Date Service. Apr 29 20:40:35 volumio-snug sudo[1799]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 29 20:40:35 volumio-snug sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 29 20:40:35 volumio-snug sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:35 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:35 volumio-snug volumio[981]: info: Fetching Streaming Services browse cache Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 20:40:35 volumio-snug volumio[981]: info: Discovery: Getting this device information Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:40:35 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:35 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 20:40:36 volumio-snug sudo[1803]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:36 volumio-snug volumiologrotate[595]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 29 20:40:36 volumio-snug volumiologrotate[595]: ls: cannot access 'SNUG': No such file or directory Apr 29 20:40:38 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 29 20:40:38 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Apr 29 20:40:39 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:39 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 29 20:40:39 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:39 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 20:40:46 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 20:40:46 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 20:40:46 volumio-snug volumio[981]: info: Discovery: Getting this device information Apr 29 20:40:46 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:40:46 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:46 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 29 20:40:57 volumio-snug volumio[981]: info: Preparing to save Alsa Options, stopping services first Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:40:57 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPause Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreStateMachine::pause Apr 29 20:40:57 volumio-snug volumio[981]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"justboom-digi","label":"JustBoom Digi Boards"},"output_device":{"value":1,"label":"JustBoom Digi Boards"},"disallowPush":true} Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:57 volumio-snug volumio[981]: info: Enabling I2S DAC: JustBoom Digi Boards Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:57 volumio-snug sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 29 20:40:57 volumio-snug sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:57 volumio-snug sudo[1892]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:57 volumio-snug volumio[981]: info: No Overlays Loaded Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:57 volumio-snug sudo[1895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay justboom-digi Apr 29 20:40:57 volumio-snug sudo[1895]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/#sound-dai-cells Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/compatible Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/reg Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/PVDD-supply Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/DVDD-supply Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/status Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Apr 29 20:40:57 volumio-snug kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Apr 29 20:40:57 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:57 volumio-snug sudo[1895]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:57 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:57 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:57 volumio-snug volumio[981]: info: Device 2 does not have any Mixer Control Available Apr 29 20:40:57 volumio-snug vtcs[1428]: [2025-04-29 20:40:57.374] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 29 20:40:57 volumio-snug vtcs[1428]: [2025-04-29 20:40:57.375] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Apr 29 20:40:57 volumio-snug sudo[1910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 20:40:57 volumio-snug sudo[1910]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:57 volumio-snug systemd[1]: Stopping Volumio Tidal Connect Service... Apr 29 20:40:57 volumio-snug systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 29 20:40:57 volumio-snug systemd[1]: vtcs.service: Succeeded. Apr 29 20:40:57 volumio-snug systemd[1]: Stopped Volumio Tidal Connect Service. Apr 29 20:40:57 volumio-snug sudo[1910]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:57 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 29 20:40:57 volumio-snug volumio[981]: info: Updating Volume Controller Parameters: Device: 2 Name: JustBoom Digi Boards Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 29 20:40:57 volumio-snug volumio[981]: info: Disabling external Volume Control Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:40:57 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:40:57 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:40:57 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:40:57 volumio-snug volumio[981]: info: Preparing to generate the ALSA configuration file Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:40:57 volumio-snug volumio[981]: info: Retrieving Cloud Streaming UI Apr 29 20:40:57 volumio-snug volumio[981]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 29 20:40:57 volumio-snug volumio[981]: info: Reading ALSA contributions from plugins. Apr 29 20:40:57 volumio-snug sudo[1917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 20:40:57 volumio-snug sudo[1917]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:57 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:57 volumio-snug volumio[981]: info: Apr 29 20:40:57 volumio-snug volumio[981]: ---------------------------- MPD announces state update: mixer Apr 29 20:40:57 volumio-snug volumio[981]: info: ControllerMpd::getState Apr 29 20:40:57 volumio-snug volumio[981]: verbose: ControllerMpd::sendMpdCommand status Apr 29 20:40:57 volumio-snug sudo[1917]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:57 volumio-snug volumio[981]: info: I2S Param [object Object] successfully enabled Apr 29 20:40:57 volumio-snug volumio[981]: info: sendMpdCommand status took 29 milliseconds Apr 29 20:40:57 volumio-snug volumio[981]: verbose: ControllerMpd::parseState Apr 29 20:40:57 volumio-snug volumio[981]: info: ControllerMpd::pushState Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::servicePushState Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:40:57 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:40:57 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:40:57 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:40:57 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:57 volumio-snug volumio[981]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 20:40:57 volumio-snug volumio[981]: verbose: CURRENT POSITION 0 Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreStateMachine::syncState stateService stop Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreStateMachine::syncState currentStatus stop Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:40:57 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:40:57 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:40:57 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:40:57 volumio-snug volumio[981]: info: No code Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:40:57 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:40:57 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:40:57 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:40:57 volumio-snug volumio[981]: info: ------------------------------ 132ms Apr 29 20:40:57 volumio-snug volumio[981]: info: Getting Tidal Cloud Configuration Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:40:57 volumio-snug volumio[981]: info: Getting Qobuz Cloud Configuration Apr 29 20:40:57 volumio-snug volumio[981]: info: Asking plugin for UI Config Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:40:57 volumio-snug volumio[981]: info: Getting Spotify Cloud Configuration Apr 29 20:40:57 volumio-snug volumio[981]: info: Asking plugin for UI Config Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:40:57 volumio-snug volumio[981]: info: Saving Spotify Acccount Apr 29 20:40:57 volumio-snug volumio[981]: info: Got it Apr 29 20:40:57 volumio-snug volumio[981]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 29 20:40:57 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 20:40:57 volumio-snug volumio[981]: info: Getting Alsa Cards List without I2S DAC Apr 29 20:40:57 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 29 20:40:57 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:58 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 29 20:40:58 volumio-snug volumio[981]: info: Got Tidal Cloud Configuration Apr 29 20:40:58 volumio-snug volumio[981]: info: Got it Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:40:58 volumio-snug volumio[981]: info: Asound.conf file written Apr 29 20:40:58 volumio-snug sudo[1929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 29 20:40:58 volumio-snug sudo[1929]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug sudo[1929]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:58 volumio-snug volumio[981]: info: Output device has changed, restarting MPD Apr 29 20:40:58 volumio-snug sudo[1934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 29 20:40:58 volumio-snug volumio[981]: info: Output device has changed, restarting Shairport Sync Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug sudo[1934]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:58 volumio-snug sudo[1934]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 29 20:40:58 volumio-snug sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug systemd[1]: Stopping Music Player Daemon... Apr 29 20:40:58 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:58 volumio-snug volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 29 20:40:58 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:58 volumio-snug volumio[981]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug systemd[1]: mpd.service: Succeeded. Apr 29 20:40:58 volumio-snug systemd[1]: Stopped Music Player Daemon. Apr 29 20:40:58 volumio-snug volumio[981]: info: Output device has changed, restarting MPD Apr 29 20:40:58 volumio-snug sudo[1946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 20:40:58 volumio-snug systemd[1]: Starting Music Player Daemon... Apr 29 20:40:58 volumio-snug volumio[981]: info: Output device has changed, restarting Shairport Sync Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug sudo[1946]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 29 20:40:58 volumio-snug sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:58 volumio-snug sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 29 20:40:58 volumio-snug sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug sudo[1946]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug sudo[1949]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 29 20:40:58 volumio-snug systemd[1]: mpd.service: Succeeded. Apr 29 20:40:58 volumio-snug systemd[1]: Stopped Music Player Daemon. Apr 29 20:40:58 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:58 volumio-snug volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 29 20:40:58 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:40:58 volumio-snug volumio[981]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug systemd[1]: Starting Music Player Daemon... Apr 29 20:40:58 volumio-snug sudo[1962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 20:40:58 volumio-snug sudo[1962]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug sudo[1964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 20:40:58 volumio-snug sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug sudo[1962]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 20:40:58 volumio-snug sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug volumio[981]: info: Apr 29 20:40:58 volumio-snug volumio[981]: ---------------------------- MPD announces state update: mixer Apr 29 20:40:58 volumio-snug volumio[981]: info: ControllerMpd::getState Apr 29 20:40:58 volumio-snug volumio[981]: verbose: ControllerMpd::sendMpdCommand status Apr 29 20:40:58 volumio-snug volumio[981]: error: MPD error: Error: write EPIPE Apr 29 20:40:58 volumio-snug volumio[981]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 29 20:40:58 volumio-snug sudo[1964]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 20:40:58 volumio-snug sudo[1968]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:58 volumio-snug sudo[1958]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 29 20:40:58 volumio-snug sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug sudo[1958]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug volumio[981]: info: MPD Permissions set Apr 29 20:40:58 volumio-snug volumio[981]: info: MPD Permissions set Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:40:58 volumio-snug volumio[981]: info: Starting Shairport Sync Apr 29 20:40:58 volumio-snug volumio[981]: info: Starting Shairport Sync Apr 29 20:40:58 volumio-snug sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 29 20:40:58 volumio-snug sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 29 20:40:58 volumio-snug sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:40:58 volumio-snug systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 29 20:40:58 volumio-snug systemd[1]: shairport-sync.service: Succeeded. Apr 29 20:40:58 volumio-snug systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 20:40:58 volumio-snug systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 29 20:40:58 volumio-snug sudo[1984]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 29 20:40:58 volumio-snug volumio[981]: info: Shairport-Sync Started Apr 29 20:40:58 volumio-snug systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 29 20:40:58 volumio-snug systemd[1]: shairport-sync.service: Succeeded. Apr 29 20:40:58 volumio-snug systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 20:40:58 volumio-snug volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 20:40:58 volumio-snug systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 29 20:40:58 volumio-snug sudo[1987]: pam_unix(sudo:session): session closed for user root Apr 29 20:40:58 volumio-snug volumio[981]: info: Shairport-Sync Started Apr 29 20:41:00 volumio-snug mpd[1974]: Apr 29 20:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 29 20:41:00 volumio-snug systemd[1]: Started Music Player Daemon. Apr 29 20:41:00 volumio-snug sudo[1951]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:00 volumio-snug sudo[1937]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:00 volumio-snug volumio[981]: error: MPD error: The expression evaluated to a falsy value: Apr 29 20:41:00 volumio-snug volumio[981]: assert.ok(self.idling) Apr 29 20:41:00 volumio-snug volumio[981]: error: The expression evaluated to a falsy value: Apr 29 20:41:00 volumio-snug volumio[981]: assert.ok(self.idling) Apr 29 20:41:00 volumio-snug volumio[981]: error: updateQueue error: null Apr 29 20:41:00 volumio-snug volumio[981]: info: TidalConnect service stoped! Apr 29 20:41:00 volumio-snug volumio[981]: info: TidalConnect service stoped! Apr 29 20:41:00 volumio-snug volumio[981]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 29 20:41:00 volumio-snug volumio[981]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 29 20:41:00 volumio-snug sudo[2004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 29 20:41:00 volumio-snug sudo[2004]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:41:00 volumio-snug systemd[1]: Started Volumio Tidal Connect Service. Apr 29 20:41:00 volumio-snug sudo[2004]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:01 volumio-snug volumio[981]: info: Executing endpoint tc_getconfig Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 29 20:41:01 volumio-snug vtcs[2006]: STARTING TidalConnect services, version: 1.4.0.34 Apr 29 20:41:01 volumio-snug vtcs[2006]: STARTED TidalConnect services. Apr 29 20:41:01 volumio-snug volumio[981]: info: Executing endpoint tc_connect Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 29 20:41:01 volumio-snug volumio[981]: info: Connecting to TidalConnect Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::servicePushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 29 20:41:01 volumio-snug volumio[981]: verbose: CURRENT POSITION 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::syncState stateService stop Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::syncState currentStatus stop Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:41:01 volumio-snug volumio[981]: info: No code Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::servicePushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 29 20:41:01 volumio-snug volumio[981]: verbose: CURRENT POSITION 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::syncState stateService stop Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::syncState currentStatus stop Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:41:01 volumio-snug volumio[981]: info: No code Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:41:01 volumio-snug volumio[981]: info: TidalConnect service stoped! Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::getcurrentVolume Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioRetrievevolume Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreStateMachine::pushState Apr 29 20:41:01 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:01 volumio-snug volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 20:41:01 volumio-snug volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 20:41:01 volumio-snug volumio[981]: info: TidalConnect service stoped! Apr 29 20:41:01 volumio-snug volumio[981]: info: TidalConnect service stoped! Apr 29 20:41:01 volumio-snug volumio[981]: info: TidalConnect service stoped! Apr 29 20:41:01 volumio-snug volumio[981]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 29 20:41:01 volumio-snug volumio[981]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 29 20:41:01 volumio-snug sudo[2038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 29 20:41:01 volumio-snug sudo[2038]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:41:01 volumio-snug volumio[981]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 29 20:41:01 volumio-snug volumio[981]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 29 20:41:01 volumio-snug sudo[2038]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:01 volumio-snug sudo[2045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 29 20:41:01 volumio-snug sudo[2045]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:41:01 volumio-snug sudo[2045]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:03 volumio-snug volumio[981]: info: TidalConnect service started! Apr 29 20:41:04 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Apr 29 20:41:04 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 29 20:41:04 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:41:04 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Apr 29 20:41:04 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Apr 29 20:41:04 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 29 20:41:04 volumio-snug volumio[981]: xcb_connection_has_error() returned true Apr 29 20:41:04 volumio-snug volumio[981]: info: TidalConnect service started! Apr 29 20:41:04 volumio-snug volumio[981]: info: TidalConnect service started! Apr 29 20:41:05 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 29 20:41:05 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 29 20:41:05 volumio-snug systemd[1]: systemd-timedated.service: Succeeded. Apr 29 20:41:05 volumio-snug volumio-remote-updater[578]: No test mode Apr 29 20:41:05 volumio-snug volumio-remote-updater[578]: No alpha test mode Apr 29 20:41:05 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 29 20:41:05 volumio-snug volumio[981]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 29 20:41:05 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 29 20:41:07 volumio-snug volumio[981]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Apr 29 20:41:07 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Apr 29 20:41:07 volumio-snug volumio[981]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Apr 29 20:41:08 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 29 20:41:08 volumio-snug volumio[981]: info: CoreCommandRouter::Reload Ui Apr 29 20:41:08 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Apr 29 20:41:08 volumio-snug volumio[981]: info: Wizard terminated Successfully Apr 29 20:41:08 volumio-snug volumio[981]: info: CoreCommandRouter::volumiosetStartupVolume Apr 29 20:41:08 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 20:41:08 volumio-snug volumio[981]: info: Player Reboot required after I2S DAC has been enabled in wizard Apr 29 20:41:09 volumio-snug sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 29 20:41:09 volumio-snug sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:41:09 volumio-snug sudo[2077]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:09 volumio-snug sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 29 20:41:09 volumio-snug sudo[2079]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:41:09 volumio-snug sudo[2079]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:09 volumio-snug volumio[981]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.232 UA: Mozilla/5.0 (Linux; Android 13; M2012K11AG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 29 20:41:09 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 29 20:41:10 volumio-snug sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 29 20:41:10 volumio-snug sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:41:10 volumio-snug sudo[2083]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:10 volumio-snug sudo[2085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 29 20:41:10 volumio-snug sudo[2085]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 20:41:10 volumio-snug sudo[2085]: pam_unix(sudo:session): session closed for user root Apr 29 20:41:10 volumio-snug volumio[981]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.232 UA: Mozilla/5.0 (Linux; Android 13; M2012K11AG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:41:10 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 29 20:41:10 volumio-snug volumio[981]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 29 20:41:10 volumio-snug volumio[981]: info: Received Get System Info Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 20:41:10 volumio-snug volumio[981]: info: Discovery: Getting this device information Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:41:10 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:41:10 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:10 volumio-snug volumio[981]: info: Listing playlists Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 29 20:41:10 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 29 20:41:11 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 29 20:41:12 volumio-snug volumio[981]: info: Received Get System Info Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 20:41:12 volumio-snug volumio[981]: info: Discovery: Getting this device information Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:41:12 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 29 20:41:12 volumio-snug volumio[981]: info: Received Get System Info Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 20:41:12 volumio-snug volumio[981]: info: Discovery: Getting this device information Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:41:12 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:12 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 20:41:16 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 20:41:16 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 20:41:16 volumio-snug volumio[981]: info: Discovery: Getting this device information Apr 29 20:41:16 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:41:16 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:16 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 20:41:20 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 29 20:41:27 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Apr 29 20:41:29 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 29 20:41:29 volumio-snug volumio[981]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 29 20:41:29 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 29 20:41:29 volumio-snug volumio[981]: info: Received Get System Version Apr 29 20:41:29 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 29 20:41:29 volumio-snug volumio[981]: info: Received Get System Info Apr 29 20:41:29 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 20:41:29 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 20:41:29 volumio-snug volumio[981]: info: Discovery: Getting this device information Apr 29 20:41:29 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 20:41:29 volumio-snug volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 20:41:29 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:41:32 volumio-snug volumio[981]: info: Retrieving Cloud Streaming UI Apr 29 20:41:32 volumio-snug volumio[981]: info: Getting Tidal Cloud Configuration Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:41:32 volumio-snug volumio[981]: info: Getting Qobuz Cloud Configuration Apr 29 20:41:32 volumio-snug volumio[981]: info: Asking plugin for UI Config Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:41:32 volumio-snug volumio[981]: info: Getting Spotify Cloud Configuration Apr 29 20:41:32 volumio-snug volumio[981]: info: Asking plugin for UI Config Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:41:32 volumio-snug volumio[981]: info: Saving Spotify Acccount Apr 29 20:41:32 volumio-snug volumio[981]: info: Got it Apr 29 20:41:32 volumio-snug volumio[981]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 29 20:41:32 volumio-snug volumio[981]: info: Got Tidal Cloud Configuration Apr 29 20:41:32 volumio-snug volumio[981]: info: Got it Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetBrowseSources Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetBrowseSources Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::volumioGetBrowseSources Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 29 20:41:32 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 29 20:41:36 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 29 20:41:36 volumio-snug volumiologrotate[595]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 29 20:41:36 volumio-snug volumiologrotate[595]: ls: cannot access 'SNUG': No such file or directory Apr 29 20:41:37 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 29 20:41:37 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 29 20:41:38 volumio-snug volumio[981]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L raspberrypi.local. Apr 29 20:41:38 volumio-snug volumio[981]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L rpi-server.local. Apr 29 20:41:40 volumio-snug volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 29 20:41:42 volumio-snug volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 20:41:42 volumio-snug volumio[981]: Error: dns service error: unknown Apr 29 20:41:42 volumio-snug volumio[981]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Apr 29 20:41:42 volumio-snug volumio[981]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Apr 29 20:41:42 volumio-snug volumio[981]: errorCode: -65537 Apr 29 20:41:42 volumio-snug volumio[981]: } Apr 29 20:41:42 volumio-snug volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 20:41:43 volumio-snug sudo[2195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-29 20:40 Apr 29 20:41:43 volumio-snug sudo[2195]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"