-- Logs begin at Sun 2023-03-26 01:25:59 UTC, end at Sun 2025-02-23 09:40:22 UTC. -- Feb 23 09:39:39 minidsp-shd systemd[1]: Time has been changed Feb 23 09:39:40 minidsp-shd volumio[772]: info: Plugin folders cleanup Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning into folder /volumio/app/plugins/ Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning category audio_interface Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning category miscellanea Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning category music_service Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning category plugins.json Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning category system_controller Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning category user_interface Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning into folder /data/plugins/ Feb 23 09:39:40 minidsp-shd volumio[772]: info: Scanning category music_service Feb 23 09:39:40 minidsp-shd volumio[772]: info: Plugin folders cleanup completed Feb 23 09:39:40 minidsp-shd volumio[772]: info: ------------------------------------------- Feb 23 09:39:40 minidsp-shd volumio[772]: info: ----- Core plugins startup ---- Feb 23 09:39:40 minidsp-shd volumio[772]: info: ------------------------------------------- Feb 23 09:39:40 minidsp-shd volumio[772]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin bluetooth to MyMusic Plugins Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin upnp to MyMusic Plugins Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin metavolumio to MyMusic Plugins Feb 23 09:39:40 minidsp-shd nmbd[836]: [2025/02/23 09:39:40.076690, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 23 09:39:40 minidsp-shd nmbd[836]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.50.24 for name WORKGROUP<1d>. Feb 23 09:39:40 minidsp-shd nmbd[836]: This response was from IP 192.168.50.68, reporting an IP address of 192.168.50.68. Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin ms_surface_dial to MyMusic Plugins Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin cd_controller to MyMusic Plugins Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin raat to MyMusic Plugins Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 23 09:39:40 minidsp-shd volumio[772]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 09:39:40 minidsp-shd volumio[772]: info: Loading plugins from folder /data/plugins/ Feb 23 09:39:40 minidsp-shd volumio[772]: info: Loading plugin "system"... Feb 23 09:39:40 minidsp-shd volumio[772]: info: Loading plugin "appearance"... Feb 23 09:39:41 minidsp-shd volumio-remote-updater[552]: [2025-02-23 09:39:41] [connect] Successful connection Feb 23 09:39:42 minidsp-shd volumio[772]: info: Loading plugin "network"... Feb 23 09:39:42 minidsp-shd volumio[772]: info: Refreshing Cached IP Addresses Feb 23 09:39:42 minidsp-shd volumio[772]: info: Loading plugin "services"... Feb 23 09:39:42 minidsp-shd sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 09:39:42 minidsp-shd sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:39:42 minidsp-shd volumio[772]: info: Loading plugin "alsa_controller"... Feb 23 09:39:42 minidsp-shd sudo[870]: pam_unix(sudo:session): session closed for user root Feb 23 09:39:42 minidsp-shd sudo[872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 09:39:42 minidsp-shd sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:39:42 minidsp-shd sudo[872]: pam_unix(sudo:session): session closed for user root Feb 23 09:39:42 minidsp-shd volumio[772]: info: Loading plugin "wizard"... Feb 23 09:39:42 minidsp-shd volumio[772]: info: Loading plugin "volumio_command_line_client"... Feb 23 09:39:42 minidsp-shd volumio[772]: info: Loading plugin "upnp"... Feb 23 09:39:42 minidsp-shd volumio[772]: info: [1740303582823] Starting Upmpd Daemon Feb 23 09:39:42 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 09:39:42 minidsp-shd volumio[772]: info: Loading plugin "my_music"... Feb 23 09:39:42 minidsp-shd volumio[772]: info: Loading plugin "mpd"... Feb 23 09:39:43 minidsp-shd volumio[772]: info: Loading plugin "upnp_browser"... Feb 23 09:39:44 minidsp-shd volumio[772]: info: Loading plugin "networkfs"... Feb 23 09:39:44 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 09:39:44 minidsp-shd volumio[772]: info: Loading plugin "bluetooth"... Feb 23 09:39:44 minidsp-shd sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=dtao72025,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.68/Music /mnt/NAS/Asustor_Nas Feb 23 09:39:44 minidsp-shd sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:39:45 minidsp-shd kernel: FS-Cache: Netfs 'cifs' registered for caching Feb 23 09:39:45 minidsp-shd kernel: Key type cifs.spnego registered Feb 23 09:39:45 minidsp-shd kernel: Key type cifs.idmap registered Feb 23 09:39:45 minidsp-shd kernel: CIFS: Attempting to mount //192.168.50.68/Music Feb 23 09:39:45 minidsp-shd kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 23 09:39:45 minidsp-shd kernel: Status code returned 0xc000006d STATUS_LOGON_FAILURE Feb 23 09:39:45 minidsp-shd kernel: CIFS VFS: \\192.168.50.68 Send error in SessSetup = -13 Feb 23 09:39:45 minidsp-shd kernel: CIFS VFS: cifs_mount failed w/return code = -13 Feb 23 09:39:45 minidsp-shd sudo[884]: pam_unix(sudo:session): session closed for user root Feb 23 09:39:45 minidsp-shd volumio[772]: info: [1740303585398] Starting BluetoothController Feb 23 09:39:45 minidsp-shd volumio[772]: info: Loading plugin "alarm-clock"... Feb 23 09:39:45 minidsp-shd volumio[772]: info: Loading plugin "metavolumio"... Feb 23 09:39:46 minidsp-shd volumio[772]: info: Loading plugin "airplay_emulation"... Feb 23 09:39:46 minidsp-shd volumio[772]: info: Starting Shairport Sync Feb 23 09:39:46 minidsp-shd volumio[772]: info: Loading plugin "cd_controller"... Feb 23 09:39:46 minidsp-shd volumio[772]: info: Loading plugin "last_100"... Feb 23 09:39:46 minidsp-shd volumio[772]: info: Loading plugin "raat"... Feb 23 09:39:46 minidsp-shd volumio[772]: info: RAAT Plugin loaded Feb 23 09:39:46 minidsp-shd volumio[772]: info: Adding restartRAATSocket REST API Endpoint Feb 23 09:39:46 minidsp-shd volumio[772]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Feb 23 09:39:46 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Feb 23 09:39:46 minidsp-shd volumio[772]: error: Could not retrieve plugin music_service inputs Feb 23 09:39:46 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Feb 23 09:39:46 minidsp-shd volumio[772]: error: Could not retrieve plugin music_service inputs Feb 23 09:39:46 minidsp-shd volumio[772]: info: Loading plugin "streaming_services"... Feb 23 09:39:46 minidsp-shd volumio[772]: info: Loading plugin "tidalconnect"... Feb 23 09:39:47 minidsp-shd volumio[772]: info: Loading plugin "webradio"... Feb 23 09:39:47 minidsp-shd volumio[772]: info: Loading plugin "i2s_dacs"... Feb 23 09:39:47 minidsp-shd volumio[772]: info: I2S DAC not set, start Auto-detection Feb 23 09:39:47 minidsp-shd volumio[772]: info: Loading plugin "volumiodiscovery"... Feb 23 09:39:47 minidsp-shd volumio[772]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 09:39:47 minidsp-shd node[772]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 09:39:47 minidsp-shd node[772]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 09:39:47 minidsp-shd node[772]: *** WARNING *** For more information see Feb 23 09:39:47 minidsp-shd node[772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 09:39:47 minidsp-shd node[772]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 09:39:47 minidsp-shd node[772]: *** WARNING *** For more information see Feb 23 09:39:47 minidsp-shd volumio[772]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 09:39:47 minidsp-shd volumio[772]: *** WARNING *** For more information see Feb 23 09:39:47 minidsp-shd volumio[772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 09:39:47 minidsp-shd volumio[772]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 09:39:47 minidsp-shd volumio[772]: *** WARNING *** For more information see Feb 23 09:39:47 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 09:39:47 minidsp-shd volumio[772]: Discovery: StartAdv! undefined Feb 23 09:39:47 minidsp-shd volumio[772]: Discovery: Started advertising... minidsp-shd - undefined Feb 23 09:39:47 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 09:39:47 minidsp-shd volumio[772]: info: Loading plugin "outputs"... Feb 23 09:39:47 minidsp-shd volumio[772]: info: Loading plugin "albumart"... Feb 23 09:39:47 minidsp-shd volumio[772]: info: Loading plugin "ms_surface_dial"... Feb 23 09:39:48 minidsp-shd volumio[772]: Forking 3 albumart workers Feb 23 09:39:49 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin ms_surface_dial Feb 23 09:39:49 minidsp-shd volumio[772]: info: Plugin example_plugin is not enabled Feb 23 09:39:49 minidsp-shd volumio[772]: info: Loading plugin "hi_res_audio"... Feb 23 09:39:49 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin hi_res_audio Feb 23 09:39:49 minidsp-shd volumio[772]: info: Loading plugin "inputs"... Feb 23 09:39:49 minidsp-shd volumio[772]: info: Loading plugin "qobuz"... Feb 23 09:39:50 minidsp-shd volumio[772]: Starting albumart workers Feb 23 09:39:50 minidsp-shd volumio[772]: Starting albumart workers Feb 23 09:39:50 minidsp-shd volumio[772]: Starting albumart workers Feb 23 09:39:53 minidsp-shd volumio[772]: info: Loading plugin "tidal"... Feb 23 09:39:56 minidsp-shd volumio[772]: info: Loading plugin "updater_comm"... Feb 23 09:39:56 minidsp-shd volumio[772]: info: Plugin mpdemulation is not enabled Feb 23 09:39:56 minidsp-shd volumio[772]: info: Loading plugin "rest_api"... Feb 23 09:39:56 minidsp-shd volumio[772]: info: Loading plugin "websocket"... Feb 23 09:39:56 minidsp-shd volumio[772]: info: Loading plugin "RoonBridge"... Feb 23 09:39:56 minidsp-shd volumio-remote-updater[552]: [2025-02-23 09:39:56] [connect] Successful connection Feb 23 09:39:57 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin RoonBridge Feb 23 09:39:57 minidsp-shd volumio[772]: info: Loading plugin "hotelradio"... Feb 23 09:39:59 minidsp-shd volumio[772]: info: Loading plugin "personal_radio"... Feb 23 09:40:01 minidsp-shd volumio[772]: info: ControllerPersonalRadio::constructor Feb 23 09:40:01 minidsp-shd volumio[772]: info: Loading plugin "podcast"... Feb 23 09:40:05 minidsp-shd volumio[772]: info: ControllerPodcast::constructor Feb 23 09:40:05 minidsp-shd volumio[772]: info: Loading plugin "radio_paradise"... Feb 23 09:40:06 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin radio_paradise Feb 23 09:40:06 minidsp-shd volumio[772]: info: [1740303606572] [RadioParadise] API delay: 5 Feb 23 09:40:06 minidsp-shd volumio[772]: info: Loading plugin "spop"... Feb 23 09:40:08 minidsp-shd volumio[772]: info: Loading plugin "volspotconnect2"... Feb 23 09:40:09 minidsp-shd volumio[772]: info: ___________ START PLUGINS ___________ Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:40:09 minidsp-shd volumio[772]: info: [1740303609494] CoreMusicLibrary::Adding element Media Servers Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 09:40:09 minidsp-shd volumio[772]: info: Adding METAVOLUMIO REST API Endpoints Feb 23 09:40:09 minidsp-shd volumio[772]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 23 09:40:09 minidsp-shd sudo[963]: pam_unix(sudo:auth): conversation failed Feb 23 09:40:09 minidsp-shd sudo[963]: pam_unix(sudo:auth): auth could not identify password for [volumio] Feb 23 09:40:09 minidsp-shd volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 09:40:09 minidsp-shd volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 09:40:09 minidsp-shd volumio[772]: info: Preparing CD Folders Feb 23 09:40:09 minidsp-shd volumio[772]: info: Adding CD REST API Endpoints Feb 23 09:40:09 minidsp-shd volumio[772]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Feb 23 09:40:09 minidsp-shd volumio[772]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Feb 23 09:40:09 minidsp-shd volumio[772]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Feb 23 09:40:09 minidsp-shd volumio[772]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 23 09:40:09 minidsp-shd volumio[772]: info: Detecting CD presence with Flag file Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:40:09 minidsp-shd volumio[772]: info: [1740303609783] CoreMusicLibrary::Adding element Last_100 Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:40:09 minidsp-shd volumio[772]: info: Starting RAAT Plugin Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 23 09:40:09 minidsp-shd volumio[772]: info: Additional UI Settings Added for plugin music_service/raat Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:09 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:40:09 minidsp-shd volumio[772]: info: Not Reporting Auto name since its the default one Feb 23 09:40:09 minidsp-shd volumio[772]: info: Streaming services startup Feb 23 09:40:09 minidsp-shd volumio[772]: info: Starting Streaming Daemon Feb 23 09:40:09 minidsp-shd sudo[977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 09:40:09 minidsp-shd sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:09 minidsp-shd systemd[1]: Stopping Volumio Streaming Daemon... Feb 23 09:40:10 minidsp-shd systemd[1]: Starting Volumio Streaming Daemon... Feb 23 09:40:10 minidsp-shd systemd[1]: Started Volumio Streaming Daemon. Feb 23 09:40:10 minidsp-shd sudo[977]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:10 minidsp-shd volumio-streaming-daemon[983]: ############################ Feb 23 09:40:10 minidsp-shd volumio-streaming-daemon[983]: # Volumio Streaming Daemon # Feb 23 09:40:10 minidsp-shd volumio-streaming-daemon[983]: # Running on port 7777 # Feb 23 09:40:10 minidsp-shd volumio-streaming-daemon[983]: ############################ Feb 23 09:40:10 minidsp-shd volumio-streaming-daemon[983]: Checking variant in static cache Feb 23 09:40:10 minidsp-shd volumio-streaming-daemon[983]: Enabled services: Feb 23 09:40:10 minidsp-shd volumio-streaming-daemon[983]: 1) tidal Feb 23 09:40:10 minidsp-shd volumio-streaming-daemon[983]: 2) qobuz Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:40:10 minidsp-shd volumio[772]: info: [1740303610071] CoreMusicLibrary::Adding element Webradio Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 09:40:10 minidsp-shd sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 23 09:40:10 minidsp-shd sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:10 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 23 09:40:10 minidsp-shd systemd[1]: Stopped Volumio Tidal Connect Service. Feb 23 09:40:10 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 23 09:40:10 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 23 09:40:10 minidsp-shd volumio[772]: error: Hi Res Audio Failed Login: Missing Login Data Feb 23 09:40:10 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 23 09:40:10 minidsp-shd sudo[991]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:10 minidsp-shd volumio[772]: info: Refreshing TIDAL token Feb 23 09:40:10 minidsp-shd sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 23 09:40:10 minidsp-shd sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:10 minidsp-shd systemd[1]: Starting RoonBridge... Feb 23 09:40:10 minidsp-shd systemd[1]: Started RoonBridge. Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:40:10 minidsp-shd volumio[772]: info: [1740303610308] CoreMusicLibrary::Adding element Personal Radio Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:40:10 minidsp-shd sudo[995]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:40:10 minidsp-shd volumio[772]: info: [1740303610337] CoreMusicLibrary::Adding element Podcast Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:40:10 minidsp-shd volumio[772]: info: [1740303610358] CoreMusicLibrary::Adding element Radio Paradise Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:40:10 minidsp-shd volumio[772]: [SpotifyConnect] Creating VLS config file Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: [SpotifyConnect] <> or Mixer found, using softvol Feb 23 09:40:10 minidsp-shd volumio[772]: [SpotifyConnect] Starting metadata listener Feb 23 09:40:10 minidsp-shd sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Feb 23 09:40:10 minidsp-shd sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:10 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 23 09:40:10 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Feb 23 09:40:10 minidsp-shd systemd[1]: Starting Spotify Daemon... Feb 23 09:40:10 minidsp-shd systemd[1]: Started Spotify Daemon. Feb 23 09:40:10 minidsp-shd sudo[1006]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:10 minidsp-shd volumio[772]: info: Loading i18n strings for locale zh_TW Feb 23 09:40:10 minidsp-shd sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Feb 23 09:40:10 minidsp-shd sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:10 minidsp-shd volumio[772]: Updating browse sources language Feb 23 09:40:10 minidsp-shd volumio[772]: Cannot find translation for sourceMedia Servers Feb 23 09:40:10 minidsp-shd volumio[772]: Cannot find translation for sourcePersonal Radio Feb 23 09:40:10 minidsp-shd volumio[772]: Cannot find translation for sourcePodcast Feb 23 09:40:10 minidsp-shd volumio[772]: Cannot find translation for sourceRadio Paradise Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:40:10 minidsp-shd systemd[1]: Starting Volspotconnect2 Daemon... Feb 23 09:40:10 minidsp-shd systemd[1]: Started Volspotconnect2 Daemon. Feb 23 09:40:10 minidsp-shd sudo[1010]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::initPlayerControls Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 23 09:40:10 minidsp-shd volumio[1009]: 2025-02-23 09:40:10 [SPTF] 09:40:10.708 I [offline_authorizer.cpp:297] Unable to login offline: no such user Feb 23 09:40:10 minidsp-shd volumio[1009]: 2025-02-23 09:40:10 [SPTF] 09:40:10.711 I [ap:1752] Connecting to AP ap.spotify.com:4070 Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[1013]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Feb 23 09:40:10 minidsp-shd volumio[1013]: Reading Config from "volspotify.toml" Feb 23 09:40:10 minidsp-shd volumio[772]: info: BOOT COMPLETED Feb 23 09:40:10 minidsp-shd volumio[772]: [Metrics] CommandRouter: 32s 690.82ms Feb 23 09:40:10 minidsp-shd volumio[1009]: 2025-02-23 09:40:10 [SPTF] 09:40:10.756 I [ap:1226] Connected to AP: 104.199.241.202:4070 Feb 23 09:40:10 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:40:10 minidsp-shd volumio[1013]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Feb 23 09:40:10 minidsp-shd volumio[1009]: 2025-02-23 09:40:10 [SPTF] 09:40:10.888 E [ap:3953] Connection error: 410 Feb 23 09:40:10 minidsp-shd volumio[1009]: 2025-02-23 09:40:10 [WARN] Login failed: Bad username and/or password Feb 23 09:40:10 minidsp-shd volumio[1009]: 2025-02-23 09:40:10 [ERR ] Could not get the playlist container. Feb 23 09:40:10 minidsp-shd volumio[1013]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Feb 23 09:40:10 minidsp-shd systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Feb 23 09:40:10 minidsp-shd systemd[1]: Unit spop.service entered failed state. Feb 23 09:40:11 minidsp-shd volumio-remote-updater[552]: [2025-02-23 09:40:11] [connect] Successful connection Feb 23 09:40:12 minidsp-shd RoonBridge[997]: 00:00:00.025 Warn: get lock file path: /tmp/.rnbgem0- Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:00.987 Trace: [childprocess] using unix child process Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:01.136 Debug: PathForResource, filename: ../.update Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:01.218 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:01.236 Debug: PathForResource, filename: RAATServer Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:01.236 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:01.239 Debug: PathForResource, filename: RoonBridgeHelper Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:01.239 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 23 09:40:13 minidsp-shd RoonBridge[997]: Initializing Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:01.339 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 23 09:40:13 minidsp-shd RoonBridge[997]: 00:00:01.403 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 23 09:40:13 minidsp-shd RoonBridge[997]: Not Running (.o) Feb 23 09:40:14 minidsp-shd RoonBridge[997]: Running Feb 23 09:40:15 minidsp-shd RoonBridge[997]: 00:00:00.073 Warn: get lock file path: /tmp/.rnbhgem0- Feb 23 09:40:16 minidsp-shd volumio[772]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreCommandRouter::Close All Modals sent Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreCommandRouter::Close All Modals sent Feb 23 09:40:16 minidsp-shd volumio[772]: Express server listening on port 3000 Feb 23 09:40:16 minidsp-shd volumio[772]: [Metrics] WebUI: 39s 540.97ms Feb 23 09:40:16 minidsp-shd volumio[772]: Volumio Calling Home Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreStateMachine::resetVolumioState Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreStateMachine::getcurrentVolume Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 09:40:16 minidsp-shd volumio[772]: info: Stopping AccessToken refresher cron for QOBUZ Feb 23 09:40:16 minidsp-shd volumio[772]: info: AccessToken refresher cron started for QOBUZ Feb 23 09:40:16 minidsp-shd volumio[772]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"mute":false,"disableVolumeControl":true} Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreStateMachine::pushState Feb 23 09:40:16 minidsp-shd volumio[772]: info: CorePlayQueue::getTrack 0 Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioPushState Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:40:16 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:40:17 minidsp-shd sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 09:40:17 minidsp-shd sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:17 minidsp-shd sudo[1057]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:17 minidsp-shd sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 09:40:17 minidsp-shd sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:17 minidsp-shd sudo[1059]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:17 minidsp-shd sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 23 09:40:17 minidsp-shd sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:17 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 23 09:40:17 minidsp-shd systemd[1]: Starting Volumio Bluetooth Module... Feb 23 09:40:17 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 23 09:40:17 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:17 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:40:17 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Feb 23 09:40:17 minidsp-shd sudo[1065]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:17 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 23 09:40:17 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 09:40:17 minidsp-shd sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 23 09:40:17 minidsp-shd sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:17 minidsp-shd volumiobt[1067]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 23 09:40:17 minidsp-shd sudo[1068]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:17 minidsp-shd volumio[772]: xcb_connection_has_error() returned true Feb 23 09:40:17 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 23 09:40:17 minidsp-shd volumio[772]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 23 09:40:17 minidsp-shd volumio[772]: info: Serial port opened successfully Feb 23 09:40:18 minidsp-shd sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Feb 23 09:40:18 minidsp-shd sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:18 minidsp-shd volumiobt[1067]: pulseaudio: no process found Feb 23 09:40:18 minidsp-shd sudo[1076]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:18 minidsp-shd volumio[772]: info: Volumio BT Module successfully started Feb 23 09:40:18 minidsp-shd volumio[772]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Feb 23 09:40:18 minidsp-shd volumio[772]: [SpotifyConnect] Vollibrespot Daemon service started! Feb 23 09:40:18 minidsp-shd volumio[772]: [Metrics] SpotifyConnect: 8s 399.27ms Feb 23 09:40:18 minidsp-shd volumio[772]: info: SpopD Daemon Started Feb 23 09:40:18 minidsp-shd dbus[597]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Feb 23 09:40:18 minidsp-shd volumio[772]: info: TidalConnect service stoped! Feb 23 09:40:18 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Feb 23 09:40:18 minidsp-shd volumio[772]: info: RAAT Albumart path created successfully Feb 23 09:40:18 minidsp-shd volumio[772]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'minidsp-shd' Feb 23 09:40:18 minidsp-shd volumio[772]: sudo: no tty present and no askpass program specified Feb 23 09:40:18 minidsp-shd dbus[597]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Feb 23 09:40:18 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Feb 23 09:40:18 minidsp-shd rtkit-daemon[1081]: Successfully called chroot. Feb 23 09:40:18 minidsp-shd rtkit-daemon[1081]: Successfully dropped privileges. Feb 23 09:40:18 minidsp-shd rtkit-daemon[1081]: Successfully limited resources. Feb 23 09:40:18 minidsp-shd rtkit-daemon[1081]: Running. Feb 23 09:40:18 minidsp-shd rtkit-daemon[1081]: Watchdog thread running. Feb 23 09:40:18 minidsp-shd rtkit-daemon[1081]: Canary thread running. Feb 23 09:40:18 minidsp-shd volumio[772]: info: Cannot mount NAS Asustor_Nas at system boot, trial number 1 ,retrying in 5 seconds Feb 23 09:40:19 minidsp-shd volumio[772]: info: MPD running with PID592 Feb 23 09:40:19 minidsp-shd volumio[772]: ,establishing connection Feb 23 09:40:19 minidsp-shd sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Feb 23 09:40:19 minidsp-shd sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:40:19 minidsp-shd dbus[597]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30006ms) Feb 23 09:40:19 minidsp-shd sudo[1089]: pam_unix(sudo:session): session closed for user root Feb 23 09:40:19 minidsp-shd volumio[772]: info: Volumio called home Feb 23 09:40:19 minidsp-shd volumio[772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 09:40:19 minidsp-shd volumio[772]: { Error: write EPIPE Feb 23 09:40:19 minidsp-shd volumio[772]: at WriteWrap.afterWrite [as oncomplete] (net.js:868:14) errno: 'EPIPE', code: 'EPIPE', syscall: 'write' } Feb 23 09:40:19 minidsp-shd volumio[772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 09:40:19 minidsp-shd dbus[597]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' Feb 23 09:40:19 minidsp-shd systemd[1]: Starting Bluetooth service... Feb 23 09:40:19 minidsp-shd bluetoothd[1101]: Bluetooth daemon 5.23 Feb 23 09:40:19 minidsp-shd dbus[597]: [system] Successfully activated service 'org.bluez' Feb 23 09:40:19 minidsp-shd systemd[1]: Started Bluetooth service. Feb 23 09:40:19 minidsp-shd bluetoothd[1101]: Starting SDP server Feb 23 09:40:19 minidsp-shd bluetoothd[1101]: Excluding (cli) sap Feb 23 09:40:19 minidsp-shd bluetoothd[1101]: kernel lacks bnep-protocol support Feb 23 09:40:19 minidsp-shd bluetoothd[1101]: System does not support network plugin Feb 23 09:40:19 minidsp-shd dbus[597]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Feb 23 09:40:19 minidsp-shd bluetoothd[1101]: Bluetooth management interface 1.14 initialized Feb 23 09:40:19 minidsp-shd systemd[1]: Starting Hostname Service... Feb 23 09:40:19 minidsp-shd pulseaudio[1080]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 23 09:40:19 minidsp-shd pulseaudio[1080]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 23 09:40:20 minidsp-shd volumiobt[1067]: Applying permissions Feb 23 09:40:20 minidsp-shd volumiobt[1067]: Setting BT discoverable and pairable Feb 23 09:40:20 minidsp-shd pulseaudio[1080]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Feb 23 09:40:20 minidsp-shd pulseaudio[1080]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [38B blob data] Feb 23 09:40:20 minidsp-shd volumiobt[1067]: Invalid command Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [bluetooth]# agent on Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [bluetooth]# discoverable on Feb 23 09:40:20 minidsp-shd volumiobt[1067]: No default controller available Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [bluetooth]# pairable on Feb 23 09:40:20 minidsp-shd volumiobt[1067]: No default controller available Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [bluetooth]# agent NoInputNoOutput Feb 23 09:40:20 minidsp-shd volumiobt[1067]: Failed to register agent object Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [bluetooth]# default-agent Feb 23 09:40:20 minidsp-shd volumiobt[1067]: No agent is registered Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [bluetooth]# quit Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [61B blob data] Feb 23 09:40:20 minidsp-shd volumiobt[1067]: [62B blob data] Feb 23 09:40:20 minidsp-shd systemd-hostnamed[1110]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Feb 23 09:40:20 minidsp-shd dbus[597]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 23 09:40:20 minidsp-shd systemd[1]: Started Hostname Service. Feb 23 09:40:22 minidsp-shd sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 09:39 Feb 23 09:40:22 minidsp-shd sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Jul 26 13:05:05 CEST 2022" VOLUMIO_VERSION="1.125" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="b36618da2d3cf6839ff2c23420b9861f"