-- Logs begin at Mon 2023-04-03 19:11:23 UTC, end at Thu 2025-07-03 18:23:45 UTC. -- Jul 03 18:22:57 minidsp-shd systemd[1]: Time has been changed Jul 03 18:22:57 minidsp-shd wireless.js[729]: trying... Jul 03 18:22:57 minidsp-shd sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:22:57 minidsp-shd sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:22:57 minidsp-shd sudo[1025]: pam_unix(sudo:session): session closed for user root Jul 03 18:22:58 minidsp-shd ntpd[728]: Listen normally on 4 eth0 192.168.2.217 UDP 123 Jul 03 18:22:58 minidsp-shd ntpd[728]: peers refreshed Jul 03 18:22:58 minidsp-shd wireless.js[729]: trying... Jul 03 18:22:58 minidsp-shd sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:22:58 minidsp-shd sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:22:58 minidsp-shd sudo[1028]: pam_unix(sudo:session): session closed for user root Jul 03 18:22:59 minidsp-shd wireless.js[729]: trying... Jul 03 18:22:59 minidsp-shd sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:22:59 minidsp-shd sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:22:59 minidsp-shd sudo[1031]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:00 minidsp-shd wireless.js[729]: trying... Jul 03 18:23:00 minidsp-shd sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:23:00 minidsp-shd sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:00 minidsp-shd sudo[1034]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:01 minidsp-shd volumio-remote-updater[614]: [2025-07-03 18:23:01] [info] asio async_connect error: system:111 (Connection refused) Jul 03 18:23:01 minidsp-shd volumio-remote-updater[614]: [2025-07-03 18:23:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 03 18:23:01 minidsp-shd volumio-remote-updater[614]: [2025-07-03 18:23:01] [error] handle_connect error: Underlying Transport Error Jul 03 18:23:01 minidsp-shd wireless.js[729]: trying... Jul 03 18:23:01 minidsp-shd sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:23:01 minidsp-shd sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:01 minidsp-shd sudo[1038]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:02 minidsp-shd wireless.js[729]: trying... Jul 03 18:23:02 minidsp-shd sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:23:03 minidsp-shd sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:03 minidsp-shd sudo[1041]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:04 minidsp-shd wireless.js[729]: trying... Jul 03 18:23:04 minidsp-shd sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:23:04 minidsp-shd sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:04 minidsp-shd sudo[1044]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:05 minidsp-shd wireless.js[729]: Overtime, starting plan B Jul 03 18:23:05 minidsp-shd systemd[1]: Started Wireless Services. Jul 03 18:23:05 minidsp-shd systemd[1]: Starting Volumio Backend Module... Jul 03 18:23:05 minidsp-shd systemd[1]: Started Volumio Backend Module. Jul 03 18:23:05 minidsp-shd systemd[1]: Starting Volumio Streaming Daemon... Jul 03 18:23:05 minidsp-shd systemd[1]: Started Volumio Streaming Daemon. Jul 03 18:23:05 minidsp-shd volumio-streaming-daemon[1048]: ############################ Jul 03 18:23:05 minidsp-shd volumio-streaming-daemon[1048]: # Volumio Streaming Daemon # Jul 03 18:23:05 minidsp-shd volumio-streaming-daemon[1048]: # Running on port 7777 # Jul 03 18:23:05 minidsp-shd volumio-streaming-daemon[1048]: ############################ Jul 03 18:23:05 minidsp-shd volumio-streaming-daemon[1048]: Checking variant in static cache Jul 03 18:23:05 minidsp-shd volumio-streaming-daemon[1048]: Enabled services: Jul 03 18:23:05 minidsp-shd volumio-streaming-daemon[1048]: 1) tidal Jul 03 18:23:05 minidsp-shd volumio-streaming-daemon[1048]: 2) qobuz Jul 03 18:23:09 minidsp-shd volumio[1047]: info: ------------------------------------------- Jul 03 18:23:09 minidsp-shd volumio[1047]: info: ----- Volumio2 ---- Jul 03 18:23:09 minidsp-shd volumio[1047]: info: ------------------------------------------- Jul 03 18:23:09 minidsp-shd volumio[1047]: info: ----- System startup ---- Jul 03 18:23:09 minidsp-shd volumio[1047]: info: ------------------------------------------- Jul 03 18:23:11 minidsp-shd volumio-remote-updater[614]: [2025-07-03 18:23:11] [connect] Successful connection Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Plugin folders cleanup Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning into folder /volumio/app/plugins/ Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning category audio_interface Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning category miscellanea Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning category music_service Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning category plugins.json Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning category system_controller Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning category user_interface Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning into folder /data/plugins/ Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Scanning category music_service Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Plugin folders cleanup completed Jul 03 18:23:11 minidsp-shd volumio[1047]: info: ------------------------------------------- Jul 03 18:23:11 minidsp-shd volumio[1047]: info: ----- Core plugins startup ---- Jul 03 18:23:11 minidsp-shd volumio[1047]: info: ------------------------------------------- Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Loading plugins from folder /volumio/app/plugins/ Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin bluetooth to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin upnp to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin metavolumio to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin ms_surface_dial to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin cd_controller to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin raat to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Loading plugins from folder /data/plugins/ Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Loading plugin "system"... Jul 03 18:23:11 minidsp-shd volumio[1047]: info: Loading plugin "appearance"... Jul 03 18:23:13 minidsp-shd volumio[1047]: info: Loading plugin "network"... Jul 03 18:23:13 minidsp-shd volumio[1047]: info: Refreshing Cached IP Addresses Jul 03 18:23:14 minidsp-shd volumio[1047]: info: Loading plugin "services"... Jul 03 18:23:14 minidsp-shd sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 18:23:14 minidsp-shd sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:14 minidsp-shd volumio[1047]: info: Loading plugin "alsa_controller"... Jul 03 18:23:14 minidsp-shd sudo[1070]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:14 minidsp-shd sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 18:23:14 minidsp-shd sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:14 minidsp-shd sudo[1072]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:14 minidsp-shd volumio[1047]: info: Loading plugin "wizard"... Jul 03 18:23:14 minidsp-shd volumio[1047]: info: Loading plugin "volumio_command_line_client"... Jul 03 18:23:14 minidsp-shd volumio[1047]: info: Loading plugin "upnp"... Jul 03 18:23:14 minidsp-shd volumio[1047]: info: [1751566994327] Starting Upmpd Daemon Jul 03 18:23:14 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:23:14 minidsp-shd volumio[1047]: info: Loading plugin "my_music"... Jul 03 18:23:14 minidsp-shd volumio[1047]: info: Loading plugin "mpd"... Jul 03 18:23:15 minidsp-shd volumio[1047]: info: Loading plugin "upnp_browser"... Jul 03 18:23:16 minidsp-shd volumio[1047]: info: Loading plugin "networkfs"... Jul 03 18:23:16 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:23:16 minidsp-shd volumio[1047]: info: Loading plugin "bluetooth"... Jul 03 18:23:16 minidsp-shd volumio[1047]: info: [1751566996837] Starting BluetoothController Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Loading plugin "alarm-clock"... Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Loading plugin "metavolumio"... Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Loading plugin "airplay_emulation"... Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Starting Shairport Sync Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Loading plugin "cd_controller"... Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Loading plugin "last_100"... Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Loading plugin "raat"... Jul 03 18:23:17 minidsp-shd volumio[1047]: info: RAAT Plugin loaded Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Adding restartRAATSocket REST API Endpoint Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 03 18:23:17 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Jul 03 18:23:17 minidsp-shd volumio[1047]: error: Could not retrieve plugin music_service inputs Jul 03 18:23:17 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Jul 03 18:23:17 minidsp-shd volumio[1047]: error: Could not retrieve plugin music_service inputs Jul 03 18:23:17 minidsp-shd volumio[1047]: info: Loading plugin "streaming_services"... Jul 03 18:23:18 minidsp-shd volumio[1047]: info: Loading plugin "tidalconnect"... Jul 03 18:23:18 minidsp-shd volumio[1047]: info: Loading plugin "webradio"... Jul 03 18:23:18 minidsp-shd volumio[1047]: info: Loading plugin "i2s_dacs"... Jul 03 18:23:18 minidsp-shd volumio[1047]: info: I2S DAC not set, start Auto-detection Jul 03 18:23:18 minidsp-shd volumio[1047]: info: Loading plugin "volumiodiscovery"... Jul 03 18:23:18 minidsp-shd volumio[1047]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 18:23:18 minidsp-shd node[1047]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 18:23:18 minidsp-shd node[1047]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:23:18 minidsp-shd node[1047]: *** WARNING *** For more information see Jul 03 18:23:18 minidsp-shd node[1047]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 18:23:18 minidsp-shd node[1047]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:23:18 minidsp-shd node[1047]: *** WARNING *** For more information see Jul 03 18:23:18 minidsp-shd volumio[1047]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:23:18 minidsp-shd volumio[1047]: *** WARNING *** For more information see Jul 03 18:23:18 minidsp-shd volumio[1047]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 18:23:18 minidsp-shd volumio[1047]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:23:18 minidsp-shd volumio[1047]: *** WARNING *** For more information see Jul 03 18:23:18 minidsp-shd volumio[1047]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 03 18:23:18 minidsp-shd volumio[1047]: Discovery: StartAdv! undefined Jul 03 18:23:18 minidsp-shd volumio[1047]: Discovery: Started advertising... minidsp-shd - undefined Jul 03 18:23:18 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:23:18 minidsp-shd volumio[1047]: info: Loading plugin "outputs"... Jul 03 18:23:19 minidsp-shd volumio[1047]: info: Loading plugin "albumart"... Jul 03 18:23:19 minidsp-shd volumio[1047]: info: Loading plugin "ms_surface_dial"... Jul 03 18:23:19 minidsp-shd volumio[1047]: Forking 3 albumart workers Jul 03 18:23:19 minidsp-shd dhcpcd[1022]: wlan0: probing for an IPv4LL address Jul 03 18:23:20 minidsp-shd volumio[1047]: info: Applying required configuration parameters for plugin ms_surface_dial Jul 03 18:23:20 minidsp-shd volumio[1047]: info: Plugin example_plugin is not enabled Jul 03 18:23:20 minidsp-shd volumio[1047]: info: Loading plugin "hi_res_audio"... Jul 03 18:23:20 minidsp-shd volumio[1047]: info: Applying required configuration parameters for plugin hi_res_audio Jul 03 18:23:20 minidsp-shd volumio[1047]: info: Loading plugin "inputs"... Jul 03 18:23:21 minidsp-shd volumio[1047]: info: Loading plugin "qobuz"... Jul 03 18:23:21 minidsp-shd volumio[1047]: Starting albumart workers Jul 03 18:23:21 minidsp-shd volumio[1047]: Starting albumart workers Jul 03 18:23:21 minidsp-shd volumio[1047]: Starting albumart workers Jul 03 18:23:24 minidsp-shd volumio[1047]: info: Loading plugin "tidal"... Jul 03 18:23:25 minidsp-shd dhcpcd[1022]: wlan0: using IPv4LL address 169.254.245.248 Jul 03 18:23:25 minidsp-shd dhcpcd[1022]: eth0: adding route to 192.168.2.0/24 Jul 03 18:23:25 minidsp-shd dhcpcd[1022]: eth0: adding default route via 192.168.2.1 Jul 03 18:23:25 minidsp-shd dhcpcd[1022]: wlan0: adding host route to 169.254.245.248 via 127.0.0.1 Jul 03 18:23:25 minidsp-shd dhcpcd[1022]: wlan0: adding route to 169.254.0.0/16 Jul 03 18:23:25 minidsp-shd avahi-daemon[635]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.245.248. Jul 03 18:23:25 minidsp-shd avahi-daemon[635]: New relevant interface wlan0.IPv4 for mDNS. Jul 03 18:23:25 minidsp-shd avahi-daemon[635]: Registering new address record for 169.254.245.248 on wlan0.IPv4. Jul 03 18:23:25 minidsp-shd dhcpcd[1022]: eth0: removing route to 192.168.2.0/24 Jul 03 18:23:25 minidsp-shd dhcpcd[1022]: eth0: removing default route via 192.168.2.1 Jul 03 18:23:26 minidsp-shd ntpd[728]: Listen normally on 5 wlan0 169.254.245.248 UDP 123 Jul 03 18:23:26 minidsp-shd ntpd[728]: peers refreshed Jul 03 18:23:26 minidsp-shd volumio-remote-updater[614]: [2025-07-03 18:23:26] [connect] Successful connection Jul 03 18:23:27 minidsp-shd volumio[1047]: info: Loading plugin "updater_comm"... Jul 03 18:23:28 minidsp-shd volumio[1047]: info: Plugin mpdemulation is not enabled Jul 03 18:23:28 minidsp-shd volumio[1047]: info: Loading plugin "rest_api"... Jul 03 18:23:28 minidsp-shd volumio[1047]: info: Loading plugin "websocket"... Jul 03 18:23:28 minidsp-shd volumio[1047]: info: Loading plugin "spop"... Jul 03 18:23:29 minidsp-shd dhcpcd[1022]: wlan0: soliciting a DHCP lease Jul 03 18:23:29 minidsp-shd dhcpcd[1022]: wlan0: offered 192.168.2.203 from 192.168.2.1 `Speedport_Smart_4_Plus_010142.4.1.001.0' Jul 03 18:23:30 minidsp-shd volumio[1047]: info: Loading plugin "volspotconnect2"... Jul 03 18:23:32 minidsp-shd volumio[1047]: info: ___________ START PLUGINS ___________ Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:23:32 minidsp-shd volumio[1047]: info: [1751567012064] CoreMusicLibrary::Adding element Media Servers Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Adding METAVOLUMIO REST API Endpoints Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 03 18:23:32 minidsp-shd sudo[1154]: pam_unix(sudo:auth): conversation failed Jul 03 18:23:32 minidsp-shd sudo[1154]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Preparing CD Folders Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Adding CD REST API Endpoints Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Detecting CD presence with Flag file Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:23:32 minidsp-shd volumio[1047]: info: [1751567012341] CoreMusicLibrary::Adding element Last_100 Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Starting RAAT Plugin Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Additional UI Settings Added for plugin music_service/raat Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Not Reporting Auto name since its the default one Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Streaming services startup Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Starting Streaming Daemon Jul 03 18:23:32 minidsp-shd sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 18:23:32 minidsp-shd sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:32 minidsp-shd systemd[1]: Stopping Volumio Streaming Daemon... Jul 03 18:23:32 minidsp-shd systemd[1]: Starting Volumio Streaming Daemon... Jul 03 18:23:32 minidsp-shd systemd[1]: Started Volumio Streaming Daemon. Jul 03 18:23:32 minidsp-shd sudo[1168]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:23:32 minidsp-shd volumio[1047]: info: [1751567012591] CoreMusicLibrary::Adding element Webradio Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:23:32 minidsp-shd volumio-streaming-daemon[1175]: ############################ Jul 03 18:23:32 minidsp-shd volumio-streaming-daemon[1175]: # Volumio Streaming Daemon # Jul 03 18:23:32 minidsp-shd volumio-streaming-daemon[1175]: # Running on port 7777 # Jul 03 18:23:32 minidsp-shd volumio-streaming-daemon[1175]: ############################ Jul 03 18:23:32 minidsp-shd volumio-streaming-daemon[1175]: Checking variant in static cache Jul 03 18:23:32 minidsp-shd volumio-streaming-daemon[1175]: Enabled services: Jul 03 18:23:32 minidsp-shd volumio-streaming-daemon[1175]: 1) tidal Jul 03 18:23:32 minidsp-shd volumio-streaming-daemon[1175]: 2) qobuz Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 18:23:32 minidsp-shd sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 03 18:23:32 minidsp-shd sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:32 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 03 18:23:32 minidsp-shd systemd[1]: Stopped Volumio Tidal Connect Service. Jul 03 18:23:32 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 03 18:23:32 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 03 18:23:32 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 03 18:23:32 minidsp-shd volumio[1047]: error: Hi Res Audio Failed Login: Missing Login Data Jul 03 18:23:32 minidsp-shd sudo[1177]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:23:32 minidsp-shd volumio[1047]: [SpotifyConnect] Creating VLS config file Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: [SpotifyConnect] Starting metadata listener Jul 03 18:23:32 minidsp-shd sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 03 18:23:32 minidsp-shd sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:32 minidsp-shd sudo[1187]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:32 minidsp-shd volumio[1047]: [SpotifyConnect] Creating VLS config file Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:32 minidsp-shd volumio[1047]: [SpotifyConnect] <> or Mixer found, using softvol Jul 03 18:23:32 minidsp-shd volumio[1047]: [SpotifyConnect] Starting metadata listener Jul 03 18:23:32 minidsp-shd sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 03 18:23:32 minidsp-shd sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:32 minidsp-shd systemd[1]: Starting Volspotconnect2 Daemon... Jul 03 18:23:32 minidsp-shd systemd[1]: Started Volspotconnect2 Daemon. Jul 03 18:23:32 minidsp-shd sudo[1190]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:32 minidsp-shd volumio[1047]: info: Loading i18n strings for locale de Jul 03 18:23:32 minidsp-shd sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 03 18:23:32 minidsp-shd sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:32 minidsp-shd systemd[1]: Starting Volspotconnect2 Daemon... Jul 03 18:23:32 minidsp-shd systemd[1]: Started Volspotconnect2 Daemon. Jul 03 18:23:32 minidsp-shd volumio[1047]: Updating browse sources language Jul 03 18:23:32 minidsp-shd sudo[1193]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:32 minidsp-shd volumio[1047]: Cannot find translation for sourceMedia Servers Jul 03 18:23:32 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:23:33 minidsp-shd volumio[1194]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jul 03 18:23:33 minidsp-shd volumio[1194]: Reading Config from "/tmp/volspotify.toml" Jul 03 18:23:33 minidsp-shd volumio[1194]: Malformed config key: missing field `Authentication` Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::initPlayerControls Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jul 03 18:23:33 minidsp-shd volumio[1197]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Jul 03 18:23:33 minidsp-shd volumio[1197]: Reading Config from "volspotify.toml" Jul 03 18:23:33 minidsp-shd volumio[1197]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1047]: info: BOOT COMPLETED Jul 03 18:23:33 minidsp-shd volumio[1047]: [Metrics] CommandRouter: 23s 631.66ms Jul 03 18:23:33 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:23:33 minidsp-shd volumio[1197]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: wlan0: leased 192.168.2.203 for 1814400 seconds Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: eth0: adding route to 192.168.2.0/24 Jul 03 18:23:34 minidsp-shd avahi-daemon[635]: Registering new address record for 192.168.2.203 on wlan0.IPv4. Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: eth0: adding default route via 192.168.2.1 Jul 03 18:23:34 minidsp-shd avahi-daemon[635]: Withdrawing address record for 169.254.245.248 on wlan0. Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: wlan0: adding host route to 192.168.2.203 via 127.0.0.1 Jul 03 18:23:34 minidsp-shd avahi-daemon[635]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.245.248. Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: wlan0: adding route to 192.168.2.0/24 Jul 03 18:23:34 minidsp-shd avahi-daemon[635]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.203. Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: wlan0: adding default route via 192.168.2.1 Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: wlan0: deleting host route to 169.254.245.248 via 127.0.0.1 Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: wlan0: deleting route to 169.254.0.0/16 Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: eth0: removing route to 192.168.2.0/24 Jul 03 18:23:34 minidsp-shd dhcpcd[1022]: eth0: removing default route via 192.168.2.1 Jul 03 18:23:35 minidsp-shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jul 03 18:23:35 minidsp-shd systemd[1]: Stopping Volspotconnect2 Daemon... Jul 03 18:23:35 minidsp-shd systemd[1]: Starting Volspotconnect2 Daemon... Jul 03 18:23:35 minidsp-shd systemd[1]: Started Volspotconnect2 Daemon. Jul 03 18:23:35 minidsp-shd volumio[1226]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jul 03 18:23:35 minidsp-shd volumio[1226]: Reading Config from "/tmp/volspotify.toml" Jul 03 18:23:35 minidsp-shd volumio[1226]: Malformed config key: missing field `Authentication` Jul 03 18:23:36 minidsp-shd ntpd[728]: Listen normally on 6 wlan0 192.168.2.203 UDP 123 Jul 03 18:23:36 minidsp-shd ntpd[728]: Deleting interface #5 wlan0, 169.254.245.248#123, interface stats: received=0, sent=0, dropped=0, active_time=10 secs Jul 03 18:23:36 minidsp-shd ntpd[728]: peers refreshed Jul 03 18:23:37 minidsp-shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jul 03 18:23:37 minidsp-shd systemd[1]: Stopping Volspotconnect2 Daemon... Jul 03 18:23:37 minidsp-shd systemd[1]: Starting Volspotconnect2 Daemon... Jul 03 18:23:37 minidsp-shd systemd[1]: Started Volspotconnect2 Daemon. Jul 03 18:23:37 minidsp-shd volumio[1229]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jul 03 18:23:37 minidsp-shd volumio[1229]: Reading Config from "/tmp/volspotify.toml" Jul 03 18:23:37 minidsp-shd volumio[1229]: Malformed config key: missing field `Authentication` Jul 03 18:23:38 minidsp-shd volumio[1047]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreCommandRouter::Close All Modals sent Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreCommandRouter::Close All Modals sent Jul 03 18:23:38 minidsp-shd volumio[1047]: Express server listening on port 3000 Jul 03 18:23:38 minidsp-shd volumio[1047]: [Metrics] WebUI: 30s 477.29ms Jul 03 18:23:38 minidsp-shd volumio[1047]: Volumio Calling Home Jul 03 18:23:38 minidsp-shd volumio[1047]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 03 18:23:38 minidsp-shd volumio[1047]: at _handle.lookup (dgram.js:266:18) Jul 03 18:23:38 minidsp-shd volumio[1047]: at _combinedTickCallback (internal/process/next_tick.js:142:11) Jul 03 18:23:38 minidsp-shd volumio[1047]: at process._tickCallback (internal/process/next_tick.js:181:9) Jul 03 18:23:38 minidsp-shd volumio[1047]: at Function.Module.runMain (module.js:696:11) Jul 03 18:23:38 minidsp-shd volumio[1047]: at startup (bootstrap_node.js:204:16) Jul 03 18:23:38 minidsp-shd volumio[1047]: at bootstrap_node.js:625:3 Jul 03 18:23:38 minidsp-shd volumio[1047]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Jul 03 18:23:38 minidsp-shd volumio[1047]: at Socket.onListenError (dgram.js:362:22) Jul 03 18:23:38 minidsp-shd volumio[1047]: at Object.onceWrapper (events.js:315:30) Jul 03 18:23:38 minidsp-shd volumio[1047]: at emitOne (events.js:121:20) Jul 03 18:23:38 minidsp-shd volumio[1047]: at Socket.emit (events.js:211:7) Jul 03 18:23:38 minidsp-shd volumio[1047]: at _handle.lookup (dgram.js:267:14) Jul 03 18:23:38 minidsp-shd volumio[1047]: at _combinedTickCallback (internal/process/next_tick.js:142:11) Jul 03 18:23:38 minidsp-shd volumio[1047]: at process._tickCallback (internal/process/next_tick.js:181:9) Jul 03 18:23:38 minidsp-shd volumio[1047]: at Function.Module.runMain (module.js:696:11) Jul 03 18:23:38 minidsp-shd volumio[1047]: at startup (bootstrap_node.js:204:16) Jul 03 18:23:38 minidsp-shd volumio[1047]: at bootstrap_node.js:625:3 Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreStateMachine::resetVolumioState Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreStateMachine::getcurrentVolume Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 18:23:38 minidsp-shd volumio[1047]: info: Stopping AccessToken refresher cron for QOBUZ Jul 03 18:23:38 minidsp-shd volumio[1047]: info: AccessToken refresher cron started for QOBUZ Jul 03 18:23:38 minidsp-shd volumio[1047]: info: Stopping AccessToken refresher cron Jul 03 18:23:38 minidsp-shd volumio[1047]: info: AccessToken refresher cron started Jul 03 18:23:38 minidsp-shd volumio[1047]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"mute":false,"disableVolumeControl":true} Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreStateMachine::pushState Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CorePlayQueue::getTrack 0 Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioPushState Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:23:38 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:23:38 minidsp-shd sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 18:23:38 minidsp-shd sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:39 minidsp-shd sudo[1253]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:39 minidsp-shd sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 18:23:39 minidsp-shd sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:39 minidsp-shd sudo[1255]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:39 minidsp-shd sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 03 18:23:39 minidsp-shd sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:39 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 03 18:23:39 minidsp-shd systemd[1]: Starting Volumio Bluetooth Module... Jul 03 18:23:39 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 03 18:23:39 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Jul 03 18:23:39 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:39 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:39 minidsp-shd sudo[1259]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:39 minidsp-shd volumio[1047]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 03 18:23:39 minidsp-shd sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 03 18:23:39 minidsp-shd sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:39 minidsp-shd volumiobt[1261]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 03 18:23:39 minidsp-shd sudo[1262]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:39 minidsp-shd volumio[1047]: info: Serial port opened successfully Jul 03 18:23:39 minidsp-shd volumio[1047]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Jul 03 18:23:39 minidsp-shd volumio[1047]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 03 18:23:39 minidsp-shd volumio[1047]: [Metrics] SpotifyConnect: 6s 554.19ms Jul 03 18:23:39 minidsp-shd volumio[1047]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 03 18:23:39 minidsp-shd volumio[1047]: [Metrics] SpotifyConnect: 6s 557.89ms Jul 03 18:23:39 minidsp-shd volumio[1047]: info: TidalConnect service stoped! Jul 03 18:23:39 minidsp-shd volumio[1047]: info: RAAT Albumart path created successfully Jul 03 18:23:39 minidsp-shd volumio[1047]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'minidsp-shd' Jul 03 18:23:39 minidsp-shd volumio[1047]: sudo: no tty present and no askpass program specified Jul 03 18:23:39 minidsp-shd volumio[1047]: info: MPD running with PID636 Jul 03 18:23:39 minidsp-shd volumio[1047]: ,establishing connection Jul 03 18:23:39 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 03 18:23:39 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 18:23:39 minidsp-shd sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jul 03 18:23:39 minidsp-shd sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:39 minidsp-shd sudo[1270]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:39 minidsp-shd volumio[1047]: xcb_connection_has_error() returned true Jul 03 18:23:39 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 03 18:23:39 minidsp-shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jul 03 18:23:39 minidsp-shd systemd[1]: Stopping Volspotconnect2 Daemon... Jul 03 18:23:39 minidsp-shd dbus[646]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' Jul 03 18:23:39 minidsp-shd systemd[1]: Starting Volspotconnect2 Daemon... Jul 03 18:23:39 minidsp-shd systemd[1]: Started Volspotconnect2 Daemon. Jul 03 18:23:39 minidsp-shd systemd[1]: Starting Bluetooth service... Jul 03 18:23:39 minidsp-shd volumio[1274]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jul 03 18:23:39 minidsp-shd volumio[1274]: Reading Config from "/tmp/volspotify.toml" Jul 03 18:23:39 minidsp-shd volumio[1274]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Jul 03 18:23:39 minidsp-shd bluetoothd[1275]: Bluetooth daemon 5.23 Jul 03 18:23:39 minidsp-shd dbus[646]: [system] Successfully activated service 'org.bluez' Jul 03 18:23:39 minidsp-shd systemd[1]: Started Bluetooth service. Jul 03 18:23:39 minidsp-shd bluetoothd[1275]: Starting SDP server Jul 03 18:23:39 minidsp-shd bluetoothd[1275]: Excluding (cli) sap Jul 03 18:23:39 minidsp-shd bluetoothd[1275]: kernel lacks bnep-protocol support Jul 03 18:23:39 minidsp-shd bluetoothd[1275]: System does not support network plugin Jul 03 18:23:39 minidsp-shd dbus[646]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Jul 03 18:23:39 minidsp-shd bluetoothd[1275]: Bluetooth management interface 1.14 initialized Jul 03 18:23:39 minidsp-shd volumio[1274]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Jul 03 18:23:39 minidsp-shd systemd[1]: Starting Hostname Service... Jul 03 18:23:39 minidsp-shd systemd-hostnamed[1282]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Jul 03 18:23:39 minidsp-shd dbus[646]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 03 18:23:39 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:23:39 minidsp-shd volumio[1047]: info: Volumio BT Module successfully started Jul 03 18:23:40 minidsp-shd volumio[1047]: info: Volumio called home Jul 03 18:23:40 minidsp-shd systemd[1]: Started Hostname Service. Jul 03 18:23:40 minidsp-shd volumio[1047]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 03 18:23:40 minidsp-shd sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 03 18:23:40 minidsp-shd sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:40 minidsp-shd volumiobt[1261]: pulseaudio: no process found Jul 03 18:23:40 minidsp-shd sudo[1287]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:40 minidsp-shd dbus[646]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Jul 03 18:23:40 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 03 18:23:40 minidsp-shd dbus[646]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 03 18:23:40 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 03 18:23:40 minidsp-shd rtkit-daemon[1292]: Successfully called chroot. Jul 03 18:23:40 minidsp-shd rtkit-daemon[1292]: Successfully dropped privileges. Jul 03 18:23:40 minidsp-shd rtkit-daemon[1292]: Successfully limited resources. Jul 03 18:23:40 minidsp-shd rtkit-daemon[1292]: Running. Jul 03 18:23:40 minidsp-shd rtkit-daemon[1292]: Watchdog thread running. Jul 03 18:23:40 minidsp-shd rtkit-daemon[1292]: Canary thread running. Jul 03 18:23:40 minidsp-shd pulseaudio[1291]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 03 18:23:40 minidsp-shd pulseaudio[1291]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 03 18:23:40 minidsp-shd volumiobt[1261]: Applying permissions Jul 03 18:23:40 minidsp-shd volumiobt[1261]: Setting BT discoverable and pairable Jul 03 18:23:40 minidsp-shd pulseaudio[1291]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [38B blob data] Jul 03 18:23:41 minidsp-shd volumiobt[1261]: Invalid command Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [bluetooth]# agent on Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [bluetooth]# discoverable on Jul 03 18:23:41 minidsp-shd volumiobt[1261]: No default controller available Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [bluetooth]# pairable on Jul 03 18:23:41 minidsp-shd volumiobt[1261]: No default controller available Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [bluetooth]# agent NoInputNoOutput Jul 03 18:23:41 minidsp-shd volumiobt[1261]: Failed to register agent object Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [bluetooth]# default-agent Jul 03 18:23:41 minidsp-shd volumiobt[1261]: No agent is registered Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [bluetooth]# quit Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [61B blob data] Jul 03 18:23:41 minidsp-shd volumiobt[1261]: [62B blob data] Jul 03 18:23:41 minidsp-shd volumio[1047]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 03 18:23:41 minidsp-shd volumio[1047]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 03 18:23:41 minidsp-shd volumio-remote-updater[614]: [2025-07-03 18:23:41] [connect] Successful connection Jul 03 18:23:41 minidsp-shd sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 03 18:23:41 minidsp-shd sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 03 18:23:41 minidsp-shd systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 03 18:23:41 minidsp-shd systemd[1]: Starting Volumio Tidal Connect Service... Jul 03 18:23:41 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Jul 03 18:23:41 minidsp-shd sudo[1300]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:41 minidsp-shd volumio[1047]: xcb_connection_has_error() returned true Jul 03 18:23:41 minidsp-shd volumio[1047]: info: Reloading queue from file Jul 03 18:23:41 minidsp-shd volumio[1047]: info: Serial Data: Jul 03 18:23:41 minidsp-shd sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Jul 03 18:23:41 minidsp-shd volumio[1047]: info: Reconfiguring and Restarting RAAT Plugin Jul 03 18:23:41 minidsp-shd sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:41 minidsp-shd systemd[1]: Starting RAAT DAEMON... Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:41 minidsp-shd systemd[1]: Started RAAT DAEMON. Jul 03 18:23:41 minidsp-shd sudo[1308]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:23:41 minidsp-shd volumio[1047]: info: Not Reporting Auto name since its the default one Jul 03 18:23:41 minidsp-shd volumio[1047]: info: RAAT Overriding default device vendor model Jul 03 18:23:41 minidsp-shd volumio[1047]: info: Adding MINIDSP Inputs Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:23:41 minidsp-shd volumio[1047]: info: [1751567021791] CoreMusicLibrary::Adding element Inputs Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:23:41 minidsp-shd volumio[1047]: info: [1751567021819] CoreMusicLibrary::Adding element Presets Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:23:41 minidsp-shd volumio[1047]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{},"org.bluez.Alert1":{},"org.bluez.HealthManager1":{}}} Jul 03 18:23:41 minidsp-shd volumio[1047]: info: [msSurfaceDial init()] check /org/bluez Jul 03 18:23:41 minidsp-shd volumio[1047]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Jul 03 18:23:41 minidsp-shd volumio[1047]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Jul 03 18:23:41 minidsp-shd volumio[1047]: info: CoreCommandRouter::Reload Ui Jul 03 18:23:41 minidsp-shd volumio[1047]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 03 18:23:42 minidsp-shd volumio[1047]: error: updateQueue error: null Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Failed LSINFO: Error: [50@0] {} No such directory Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::setRepeat null single undefined Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::pushState Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CorePlayQueue::getTrack 0 Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioPushState Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Help! Some callbacks for volumioPushState are crashing! Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Cannot read property 'sendVolumeMute' of undefined Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::setRandom null Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::pushState Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CorePlayQueue::getTrack 0 Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioPushState Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Help! Some callbacks for volumioPushState are crashing! Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Cannot read property 'sendVolumeMute' of undefined Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Serial Data: Jul 03 18:23:42 minidsp-shd volumio[1047]: info: msSurfaceDial volumioupdatevolume callback: {"mute":false,"disableVolumeControl":false} Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::pushState Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CorePlayQueue::getTrack 0 Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioPushState Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Help! Some callbacks for volumioPushState are crashing! Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Cannot read property 'sendVolumeMute' of undefined Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Updating RAAT Signal Path Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 03 18:23:42 minidsp-shd volumio-remote-updater[614]: [2025-07-03 18:23:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1751567021 101 Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Executing endpoint restartRAATSocket Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Raat Daemon started successfully Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Serial Data: Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CorePlayQueue::getTrack 0 Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioStop Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::stop Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 18:23:42 minidsp-shd volumio[1047]: verbose: UNSET VOLATILE Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"TOSLINK","disableUiControls":true,"albumart":"/albumart"} Jul 03 18:23:42 minidsp-shd volumio[1047]: info: msSurfaceDial volumioupdatevolume callback: {"vol":69,"dbVolume":-40,"mute":false,"disableVolumeControl":false} Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::pushState Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CorePlayQueue::getTrack 0 Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioPushState Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Help! Some callbacks for volumioPushState are crashing! Jul 03 18:23:42 minidsp-shd volumio[1047]: error: Cannot read property 'sendVolumeMute' of undefined Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Updating RAAT Signal Path Jul 03 18:23:42 minidsp-shd volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 18:23:42 minidsp-shd volumio-streaming-daemon[1175]: Checking variant in static cache Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Starting Shairport Sync Jul 03 18:23:42 minidsp-shd volumio[1047]: info: Starting Shairport Sync Jul 03 18:23:42 minidsp-shd sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:23:42 minidsp-shd sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:42 minidsp-shd systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jul 03 18:23:42 minidsp-shd sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:23:42 minidsp-shd sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:42 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 18:23:42 minidsp-shd sudo[1330]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:42 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 18:23:42 minidsp-shd sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 03 18:23:42 minidsp-shd sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:42 minidsp-shd systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jul 03 18:23:42 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 18:23:42 minidsp-shd sudo[1332]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:43 minidsp-shd systemd[1]: Starting UPnP Renderer front-end to MPD... Jul 03 18:23:43 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Jul 03 18:23:43 minidsp-shd volumio[1047]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 03 18:23:43 minidsp-shd sudo[1335]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:43 minidsp-shd volumio[1047]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 03 18:23:43 minidsp-shd volumio[1047]: info: Executing endpoint tc_getconfig Jul 03 18:23:43 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 03 18:23:43 minidsp-shd vtcs[1303]: STARTING TidalConnect services, version: 1.1.7.5 Jul 03 18:23:43 minidsp-shd vtcs[1303]: STARTED TidalConnect services. Jul 03 18:23:43 minidsp-shd volumio[1047]: info: CoreCommandRouter::servicePushState Jul 03 18:23:43 minidsp-shd volumio[1047]: info: CoreStateMachine::pushState Jul 03 18:23:43 minidsp-shd volumio[1047]: info: CoreCommandRouter::volumioPushState Jul 03 18:23:43 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:23:43 minidsp-shd volumio[1047]: error: Help! Some callbacks for volumioPushState are crashing! Jul 03 18:23:43 minidsp-shd volumio[1047]: error: Cannot read property 'sendVolumeMute' of undefined Jul 03 18:23:43 minidsp-shd volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 03 18:23:43 minidsp-shd volumio[1047]: xcb_connection_has_error() returned true Jul 03 18:23:43 minidsp-shd sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 03 18:23:43 minidsp-shd sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:23:43 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Jul 03 18:23:43 minidsp-shd systemd[1]: Starting RAAT DAEMON... Jul 03 18:23:43 minidsp-shd systemd[1]: Started RAAT DAEMON. Jul 03 18:23:43 minidsp-shd sudo[1359]: pam_unix(sudo:session): session closed for user root Jul 03 18:23:43 minidsp-shd volumio[1047]: info: Upmpdcli Daemon Started Jul 03 18:23:43 minidsp-shd volumio[1047]: info: Shairport-Sync Started Jul 03 18:23:43 minidsp-shd volumio[1047]: Error adding Membership: Error: addMembership EINVAL Jul 03 18:23:43 minidsp-shd volumio[1047]: info: Shairport-Sync Started Jul 03 18:23:43 minidsp-shd volumio[1047]: info: Checking Spotify Web API Jul 03 18:23:43 minidsp-shd volumio[1047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 18:23:43 minidsp-shd volumio[1047]: { Error: addMembership ENODEV Jul 03 18:23:43 minidsp-shd volumio[1047]: at Socket.addMembership (dgram.js:615:11) Jul 03 18:23:43 minidsp-shd volumio[1047]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Jul 03 18:23:43 minidsp-shd volumio[1047]: at ontimeout (timers.js:498:11) Jul 03 18:23:43 minidsp-shd volumio[1047]: at tryOnTimeout (timers.js:323:5) Jul 03 18:23:43 minidsp-shd volumio[1047]: at Timer.listOnTimeout (timers.js:290:5) errno: 'ENODEV', code: 'ENODEV', syscall: 'addMembership' } Jul 03 18:23:43 minidsp-shd volumio[1047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 18:23:45 minidsp-shd sudo[1382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 18:22 Jul 03 18:23:45 minidsp-shd sudo[1382]: 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"