-- Logs begin at Tue 2022-07-19 18:58:21 UTC, end at Sat 2025-02-15 13:34:18 UTC. -- Feb 15 13:33:57 shd systemd[1]: Time has been changed Feb 15 13:33:58 shd volumio[941]: info: Applying required configuration parameters for plugin ms_surface_dial Feb 15 13:33:58 shd volumio[941]: info: Plugin example_plugin is not enabled Feb 15 13:33:58 shd volumio[941]: info: Loading plugin "hi_res_audio"... Feb 15 13:33:58 shd volumio[941]: info: Applying required configuration parameters for plugin hi_res_audio Feb 15 13:33:58 shd volumio[941]: info: Loading plugin "inputs"... Feb 15 13:33:58 shd volumio[941]: info: Loading plugin "qobuz"... Feb 15 13:33:58 shd sudo[977]: pam_unix(sudo:session): session closed for user root Feb 15 13:33:58 shd volumio[941]: Starting albumart workers Feb 15 13:33:58 shd volumio[941]: Starting albumart workers Feb 15 13:33:58 shd volumio[941]: Starting albumart workers Feb 15 13:34:00 shd volumio[941]: info: Loading plugin "tidal"... Feb 15 13:34:03 shd volumio[941]: info: Loading plugin "updater_comm"... Feb 15 13:34:03 shd volumio[941]: info: Plugin mpdemulation is not enabled Feb 15 13:34:03 shd volumio[941]: info: Loading plugin "rest_api"... Feb 15 13:34:03 shd volumio[941]: info: Loading plugin "websocket"... Feb 15 13:34:03 shd volumio[941]: info: Loading plugin "RoonBridge"... Feb 15 13:34:04 shd volumio[941]: info: Applying required configuration parameters for plugin RoonBridge Feb 15 13:34:04 shd volumio[941]: info: Loading plugin "spop"... Feb 15 13:34:04 shd volumio-remote-updater[644]: [2025-02-15 13:34:04] [connect] Successful connection Feb 15 13:34:04 shd ifup[516]: dhcpcd[550]: timed out Feb 15 13:34:04 shd dhcpcd[550]: timed out Feb 15 13:34:04 shd dhcpcd[550]: exited Feb 15 13:34:04 shd ifup[516]: dhcpcd[550]: exited Feb 15 13:34:04 shd ifup[516]: Failed to bring up eth0. Feb 15 13:34:05 shd volumio[941]: info: Loading plugin "volspotconnect2"... Feb 15 13:34:06 shd volumio[941]: info: ___________ START PLUGINS ___________ Feb 15 13:34:06 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 13:34:06 shd volumio[941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 13:34:06 shd volumio[941]: info: [1739626446857] CoreMusicLibrary::Adding element Media Servers Feb 15 13:34:06 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 13:34:06 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 13:34:06 shd volumio[941]: info: Adding METAVOLUMIO REST API Endpoints Feb 15 13:34:06 shd volumio[941]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 15 13:34:06 shd sudo[1038]: pam_unix(sudo:auth): conversation failed Feb 15 13:34:06 shd sudo[1038]: pam_unix(sudo:auth): auth could not identify password for [volumio] Feb 15 13:34:06 shd volumio[941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 15 13:34:07 shd volumio[941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 15 13:34:07 shd volumio[941]: info: Preparing CD Folders Feb 15 13:34:07 shd volumio[941]: info: Adding CD REST API Endpoints Feb 15 13:34:07 shd volumio[941]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Feb 15 13:34:07 shd volumio[941]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Feb 15 13:34:07 shd volumio[941]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Feb 15 13:34:07 shd volumio[941]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 15 13:34:07 shd volumio[941]: info: Detecting CD presence with Flag file Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 13:34:07 shd volumio[941]: info: [1739626447044] CoreMusicLibrary::Adding element Last_100 Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 13:34:07 shd volumio[941]: info: Starting RAAT Plugin Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 15 13:34:07 shd volumio[941]: info: Additional UI Settings Added for plugin music_service/raat Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: Not Reporting Auto name since its the default one Feb 15 13:34:07 shd volumio[941]: info: Streaming services startup Feb 15 13:34:07 shd volumio[941]: info: Starting Streaming Daemon Feb 15 13:34:07 shd sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 15 13:34:07 shd sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:07 shd systemd[1]: Stopping Volumio Streaming Daemon... Feb 15 13:34:07 shd systemd[1]: Starting Volumio Streaming Daemon... Feb 15 13:34:07 shd systemd[1]: Started Volumio Streaming Daemon. Feb 15 13:34:07 shd sudo[1052]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:07 shd volumio-streaming-daemon[1057]: ############################ Feb 15 13:34:07 shd volumio-streaming-daemon[1057]: # Volumio Streaming Daemon # Feb 15 13:34:07 shd volumio-streaming-daemon[1057]: # Running on port 7777 # Feb 15 13:34:07 shd volumio-streaming-daemon[1057]: ############################ Feb 15 13:34:07 shd volumio-streaming-daemon[1057]: Checking variant in static cache Feb 15 13:34:07 shd volumio-streaming-daemon[1057]: Enabled services: Feb 15 13:34:07 shd volumio-streaming-daemon[1057]: 1) tidal Feb 15 13:34:07 shd volumio-streaming-daemon[1057]: 2) qobuz Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 13:34:07 shd volumio[941]: info: [1739626447229] CoreMusicLibrary::Adding element Webradio Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 13:34:07 shd volumio[941]: error: Hi Res Audio Failed Login: Missing Login Data Feb 15 13:34:07 shd volumio[941]: info: Refreshing QOBUZ token Feb 15 13:34:07 shd sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 15 13:34:07 shd sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:07 shd systemd[1]: Starting RoonBridge... Feb 15 13:34:07 shd systemd[1]: Started RoonBridge. Feb 15 13:34:07 shd sudo[1066]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 13:34:07 shd volumio[941]: [SpotifyConnect] Creating VLS config file Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Feb 15 13:34:07 shd sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:07 shd sudo[1074]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:07 shd volumio[941]: [SpotifyConnect] Starting metadata listener Feb 15 13:34:07 shd volumio[941]: [SpotifyConnect] Creating VLS config file Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: [SpotifyConnect] <> or Mixer found, using softvol Feb 15 13:34:07 shd sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Feb 15 13:34:07 shd volumio[941]: [SpotifyConnect] Starting metadata listener Feb 15 13:34:07 shd sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:07 shd systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 13:34:07 shd systemd[1]: Started Volspotconnect2 Daemon. Feb 15 13:34:07 shd sudo[1080]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:07 shd sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Feb 15 13:34:07 shd sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:07 shd systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 13:34:07 shd systemd[1]: Started Volspotconnect2 Daemon. Feb 15 13:34:07 shd volumio[941]: info: Loading i18n strings for locale en Feb 15 13:34:07 shd sudo[1084]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:07 shd volumio[941]: Updating browse sources language Feb 15 13:34:07 shd volumio[941]: Cannot find translation for sourceMedia Servers Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::initPlayerControls Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 13:34:07 shd volumio[941]: info: BOOT COMPLETED Feb 15 13:34:07 shd volumio[941]: [Metrics] CommandRouter: 17s 644.87ms Feb 15 13:34:07 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 13:34:07 shd volumio[1083]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Feb 15 13:34:07 shd volumio[1083]: Reading Config from "/tmp/volspotify.toml" Feb 15 13:34:07 shd volumio[1083]: Malformed config key: missing field `Authentication` Feb 15 13:34:07 shd volumio[1087]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Feb 15 13:34:07 shd volumio[1087]: Reading Config from "volspotify.toml" Feb 15 13:34:07 shd volumio[1087]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Feb 15 13:34:08 shd volumio[1087]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Feb 15 13:34:09 shd RoonBridge[1068]: 00:00:00.018 Warn: get lock file path: /tmp/.rnbgem0- Feb 15 13:34:09 shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Feb 15 13:34:09 shd systemd[1]: Stopping Volspotconnect2 Daemon... Feb 15 13:34:09 shd systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 13:34:09 shd systemd[1]: Started Volspotconnect2 Daemon. Feb 15 13:34:09 shd volumio[1096]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Feb 15 13:34:09 shd volumio[1096]: Reading Config from "/tmp/volspotify.toml" Feb 15 13:34:09 shd volumio[1096]: Malformed config key: missing field `Authentication` Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:00.799 Trace: [childprocess] using unix child process Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:00.908 Debug: PathForResource, filename: ../.update Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:01.017 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:01.029 Debug: PathForResource, filename: RAATServer Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:01.030 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:01.031 Debug: PathForResource, filename: RoonBridgeHelper Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:01.031 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 15 13:34:10 shd RoonBridge[1068]: Initializing Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:01.099 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 15 13:34:10 shd RoonBridge[1068]: 00:00:01.164 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 15 13:34:10 shd RoonBridge[1068]: Not Running (.o) Feb 15 13:34:11 shd RoonBridge[1068]: Running Feb 15 13:34:11 shd RoonBridge[1068]: 00:00:00.055 Warn: get lock file path: /tmp/.rnbhgem0- Feb 15 13:34:12 shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Feb 15 13:34:12 shd systemd[1]: Stopping Volspotconnect2 Daemon... Feb 15 13:34:12 shd systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 13:34:12 shd systemd[1]: Started Volspotconnect2 Daemon. Feb 15 13:34:12 shd volumio[1123]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Feb 15 13:34:12 shd volumio[1123]: Reading Config from "/tmp/volspotify.toml" Feb 15 13:34:12 shd volumio[1123]: Malformed config key: missing field `Authentication` Feb 15 13:34:13 shd volumio[941]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::Close All Modals sent Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::Close All Modals sent Feb 15 13:34:13 shd volumio[941]: Express server listening on port 3000 Feb 15 13:34:13 shd volumio[941]: [Metrics] WebUI: 24s 357.32ms Feb 15 13:34:13 shd volumio[941]: Volumio Calling Home Feb 15 13:34:13 shd volumio[941]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Feb 15 13:34:13 shd volumio[941]: at _handle.lookup (dgram.js:266:18) Feb 15 13:34:13 shd volumio[941]: at _combinedTickCallback (internal/process/next_tick.js:142:11) Feb 15 13:34:13 shd volumio[941]: at process._tickCallback (internal/process/next_tick.js:181:9) Feb 15 13:34:13 shd volumio[941]: at Function.Module.runMain (module.js:696:11) Feb 15 13:34:13 shd volumio[941]: at startup (bootstrap_node.js:204:16) Feb 15 13:34:13 shd volumio[941]: at bootstrap_node.js:625:3 Feb 15 13:34:13 shd volumio[941]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Feb 15 13:34:13 shd volumio[941]: at Socket.onListenError (dgram.js:362:22) Feb 15 13:34:13 shd volumio[941]: at Object.onceWrapper (events.js:315:30) Feb 15 13:34:13 shd volumio[941]: at emitOne (events.js:121:20) Feb 15 13:34:13 shd volumio[941]: at Socket.emit (events.js:211:7) Feb 15 13:34:13 shd volumio[941]: at _handle.lookup (dgram.js:267:14) Feb 15 13:34:13 shd volumio[941]: at _combinedTickCallback (internal/process/next_tick.js:142:11) Feb 15 13:34:13 shd volumio[941]: at process._tickCallback (internal/process/next_tick.js:181:9) Feb 15 13:34:13 shd volumio[941]: at Function.Module.runMain (module.js:696:11) Feb 15 13:34:13 shd volumio[941]: at startup (bootstrap_node.js:204:16) Feb 15 13:34:13 shd volumio[941]: at bootstrap_node.js:625:3 Feb 15 13:34:13 shd volumio[941]: info: CoreStateMachine::resetVolumioState Feb 15 13:34:13 shd volumio[941]: info: CoreStateMachine::getcurrentVolume Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 13:34:13 shd volumio[941]: info: Stopping AccessToken refresher cron Feb 15 13:34:13 shd volumio[941]: info: AccessToken refresher cron started Feb 15 13:34:13 shd volumio[941]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"mute":false,"disableVolumeControl":true} Feb 15 13:34:13 shd volumio[941]: info: CoreStateMachine::pushState Feb 15 13:34:13 shd volumio[941]: info: CorePlayQueue::getTrack 0 Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::volumioPushState Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 13:34:13 shd sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 13:34:13 shd sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:13 shd sudo[1139]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:13 shd sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 13:34:13 shd sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:13 shd sudo[1141]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:13 shd sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 15 13:34:13 shd sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:13 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:13 shd systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 15 13:34:13 shd systemd[1]: Starting Volumio Bluetooth Module... Feb 15 13:34:13 shd systemd[1]: Started Volumio Bluetooth Module. Feb 15 13:34:13 shd sudo[1143]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:13 shd volumio[941]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 15 13:34:13 shd volumio[941]: info: Serial port opened successfully Feb 15 13:34:13 shd sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 15 13:34:13 shd sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:13 shd volumiobt[1146]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 15 13:34:13 shd sudo[1147]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:13 shd volumio[941]: info: Setting Device type: NanoPi NEO2 Feb 15 13:34:13 shd volumio[941]: [SpotifyConnect] Vollibrespot Daemon service started! Feb 15 13:34:13 shd volumio[941]: [Metrics] SpotifyConnect: 6s 526.92ms Feb 15 13:34:13 shd volumio[941]: [SpotifyConnect] Vollibrespot Daemon service started! Feb 15 13:34:13 shd volumio[941]: [Metrics] SpotifyConnect: 6s 530.10ms Feb 15 13:34:13 shd volumio[941]: info: RAAT Albumart path created successfully Feb 15 13:34:13 shd volumio[941]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'shd' Feb 15 13:34:13 shd volumio[941]: sudo: no tty present and no askpass program specified Feb 15 13:34:14 shd volumio[941]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Feb 15 13:34:14 shd volumio[941]: info: MPD running with PID667 Feb 15 13:34:14 shd volumio[941]: ,establishing connection Feb 15 13:34:14 shd sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Feb 15 13:34:14 shd sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:14 shd sudo[1152]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:14 shd dbus[670]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' Feb 15 13:34:14 shd systemd[1]: Starting Bluetooth service... Feb 15 13:34:14 shd bluetoothd[1154]: Bluetooth daemon 5.23 Feb 15 13:34:14 shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Feb 15 13:34:14 shd systemd[1]: Stopping Volspotconnect2 Daemon... Feb 15 13:34:14 shd systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 13:34:14 shd dbus[670]: [system] Successfully activated service 'org.bluez' Feb 15 13:34:14 shd systemd[1]: Started Volspotconnect2 Daemon. Feb 15 13:34:14 shd systemd[1]: Started Bluetooth service. Feb 15 13:34:14 shd volumio[1155]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Feb 15 13:34:14 shd volumio[1155]: Reading Config from "/tmp/volspotify.toml" Feb 15 13:34:14 shd kernel: Bluetooth: Core ver 2.22 Feb 15 13:34:14 shd kernel: NET: Registered protocol family 31 Feb 15 13:34:14 shd kernel: Bluetooth: HCI device and connection manager initialized Feb 15 13:34:14 shd kernel: Bluetooth: HCI socket layer initialized Feb 15 13:34:14 shd kernel: Bluetooth: L2CAP socket layer initialized Feb 15 13:34:14 shd kernel: Bluetooth: SCO socket layer initialized Feb 15 13:34:14 shd volumio[941]: info: Volumio BT Module successfully started Feb 15 13:34:14 shd volumio[941]: info: Volumio called home Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 15 13:34:14 shd bluetoothd[1154]: Starting SDP server Feb 15 13:34:14 shd bluetoothd[1154]: Excluding (cli) sap Feb 15 13:34:14 shd volumio[1155]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Feb 15 13:34:14 shd kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 15 13:34:14 shd kernel: Bluetooth: BNEP filters: protocol multicast Feb 15 13:34:14 shd kernel: Bluetooth: BNEP socket layer initialized Feb 15 13:34:14 shd bluetoothd[1154]: Bluetooth management interface 1.14 initialized Feb 15 13:34:14 shd dbus[670]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Feb 15 13:34:14 shd systemd[1]: Starting Hostname Service... Feb 15 13:34:14 shd volumio[941]: xcb_connection_has_error() returned true Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 15 13:34:14 shd systemd-hostnamed[1167]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Feb 15 13:34:14 shd dbus[670]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 15 13:34:14 shd volumio[1155]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Feb 15 13:34:14 shd systemd[1]: Started Hostname Service. Feb 15 13:34:14 shd volumio[941]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Feb 15 13:34:14 shd volumio[941]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 15 13:34:14 shd volumio[941]: xcb_connection_has_error() returned true Feb 15 13:34:14 shd volumio[941]: info: Reloading queue from file Feb 15 13:34:14 shd volumio[941]: info: Serial Data: Feb 15 13:34:14 shd sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Feb 15 13:34:14 shd volumio[941]: info: Reconfiguring and Restarting RAAT Plugin Feb 15 13:34:14 shd sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Feb 15 13:34:14 shd sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:14 shd sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:14 shd volumiobt[1146]: pulseaudio: no process found Feb 15 13:34:14 shd sudo[1175]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:14 shd systemd[1]: Starting RAAT DAEMON... Feb 15 13:34:14 shd systemd[1]: Started RAAT DAEMON. Feb 15 13:34:14 shd sudo[1176]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 13:34:14 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 13:34:14 shd volumio[941]: info: Not Reporting Auto name since its the default one Feb 15 13:34:14 shd volumio[941]: info: RAAT Overriding default device vendor model Feb 15 13:34:15 shd volumio[941]: info: Adding MINIDSP Inputs Feb 15 13:34:15 shd volumio[941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 13:34:15 shd volumio[941]: info: [1739626455011] CoreMusicLibrary::Adding element Inputs Feb 15 13:34:15 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 13:34:15 shd volumio[941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 13:34:15 shd volumio[941]: info: [1739626455057] CoreMusicLibrary::Adding element Presets Feb 15 13:34:15 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 13:34:15 shd volumio[941]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{},"org.bluez.Alert1":{},"org.bluez.HealthManager1":{}}} Feb 15 13:34:15 shd volumio[941]: info: [msSurfaceDial init()] check /org/bluez Feb 15 13:34:15 shd volumio[941]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Feb 15 13:34:15 shd dbus[670]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Feb 15 13:34:15 shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Feb 15 13:34:15 shd volumio[941]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Feb 15 13:34:15 shd volumio[941]: info: CoreCommandRouter::Reload Ui Feb 15 13:34:15 shd volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 15 13:34:15 shd dbus[670]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Feb 15 13:34:15 shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Feb 15 13:34:15 shd rtkit-daemon[1200]: Successfully called chroot. Feb 15 13:34:15 shd rtkit-daemon[1200]: Successfully dropped privileges. Feb 15 13:34:15 shd rtkit-daemon[1200]: Successfully limited resources. Feb 15 13:34:15 shd rtkit-daemon[1200]: Running. Feb 15 13:34:15 shd rtkit-daemon[1200]: Watchdog thread running. Feb 15 13:34:15 shd rtkit-daemon[1200]: Canary thread running. Feb 15 13:34:15 shd volumio[941]: error: updateQueue error: null Feb 15 13:34:15 shd volumio[941]: error: Failed LSINFO: Error: [50@0] {} No such directory Feb 15 13:34:15 shd pulseaudio[1199]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 15 13:34:15 shd pulseaudio[1199]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 15 13:34:15 shd pulseaudio[1199]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Feb 15 13:34:15 shd volumiobt[1146]: Applying permissions Feb 15 13:34:15 shd volumiobt[1146]: Setting BT discoverable and pairable Feb 15 13:34:15 shd volumiobt[1146]: [38B blob data] Feb 15 13:34:15 shd volumiobt[1146]: Invalid command Feb 15 13:34:15 shd volumiobt[1146]: [bluetooth]# agent on Feb 15 13:34:15 shd volumiobt[1146]: [bluetooth]# discoverable on Feb 15 13:34:15 shd volumiobt[1146]: No default controller available Feb 15 13:34:15 shd volumiobt[1146]: [bluetooth]# pairable on Feb 15 13:34:15 shd volumiobt[1146]: No default controller available Feb 15 13:34:15 shd volumiobt[1146]: [bluetooth]# agent NoInputNoOutput Feb 15 13:34:15 shd volumiobt[1146]: Failed to register agent object Feb 15 13:34:15 shd volumiobt[1146]: [bluetooth]# default-agent Feb 15 13:34:15 shd volumiobt[1146]: No agent is registered Feb 15 13:34:15 shd volumiobt[1146]: [bluetooth]# quit Feb 15 13:34:15 shd volumiobt[1146]: [61B blob data] Feb 15 13:34:15 shd volumiobt[1146]: [62B blob data] Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::setRepeat undefined single undefined Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::pushState Feb 15 13:34:16 shd volumio[941]: info: CorePlayQueue::getTrack 0 Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::volumioPushState Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::setRandom true Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::pushState Feb 15 13:34:16 shd volumio[941]: info: CorePlayQueue::getTrack 0 Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::volumioPushState Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 13:34:16 shd volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 15 13:34:16 shd volumio[941]: info: Raat Daemon started successfully Feb 15 13:34:16 shd volumio[941]: info: mDNS: A device disapperared from network Feb 15 13:34:16 shd volumio[941]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Feb 15 13:34:16 shd volumio[941]: info: Executing endpoint restartRAATSocket Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 15 13:34:16 shd volumio[941]: info: Starting Shairport Sync Feb 15 13:34:16 shd volumio[941]: info: Starting Shairport Sync Feb 15 13:34:16 shd sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 13:34:16 shd volumio[941]: info: Serial Data: Feb 15 13:34:16 shd sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:16 shd volumio[941]: info: msSurfaceDial volumioupdatevolume callback: {"mute":false,"disableVolumeControl":false} Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::pushState Feb 15 13:34:16 shd volumio[941]: info: CorePlayQueue::getTrack 0 Feb 15 13:34:16 shd sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 13:34:16 shd sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::volumioPushState Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 13:34:16 shd sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 15 13:34:16 shd sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:16 shd systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 15 13:34:16 shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Feb 15 13:34:16 shd volumio[941]: info: Updating RAAT Signal Path Feb 15 13:34:16 shd volumio[941]: info: CorePlayQueue::getTrack 0 Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::volumiosetSourceActiveno-source Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::volumioStop Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::stop Feb 15 13:34:16 shd sudo[1225]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 13:34:16 shd volumio[941]: verbose: UNSET VOLATILE Feb 15 13:34:16 shd volumio[941]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"TOSLINK","disableUiControls":true,"albumart":"/albumart"} Feb 15 13:34:16 shd volumio[941]: info: msSurfaceDial volumioupdatevolume callback: {"vol":null,"dbVolume":null,"mute":false,"disableVolumeControl":false} Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::pushState Feb 15 13:34:16 shd volumio[941]: info: CorePlayQueue::getTrack 0 Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::volumioPushState Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 13:34:16 shd systemd[1]: Starting UPnP Renderer front-end to MPD... Feb 15 13:34:16 shd systemd[1]: Started UPnP Renderer front-end to MPD. Feb 15 13:34:16 shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 13:34:16 shd sudo[1230]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:16 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Feb 15 13:34:16 shd volumio[941]: info: Updating RAAT Signal Path Feb 15 13:34:16 shd volumio[941]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 13:34:16 shd systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 15 13:34:16 shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 13:34:16 shd sudo[1226]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:17 shd volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 15 13:34:17 shd volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 15 13:34:17 shd volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 15 13:34:17 shd volumio[941]: xcb_connection_has_error() returned true Feb 15 13:34:17 shd volumio[941]: Discovery: adding c96094bd-a322-465c-98e6-a51c19ab2527 Feb 15 13:34:17 shd volumio[941]: info: mDNS: Found device shd Feb 15 13:34:17 shd sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 15 13:34:17 shd volumio[941]: info: CoreCommandRouter::volumioGetState Feb 15 13:34:17 shd sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 13:34:17 shd volumio[941]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 13:34:17 shd volumio[941]: Error: The value NaN is not a number Feb 15 13:34:17 shd volumio[941]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Feb 15 13:34:17 shd volumio[941]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Feb 15 13:34:17 shd volumio[941]: at ControllerVolumioDiscovery.pushMultiRoomStatusUpdate (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:287:25) Feb 15 13:34:17 shd volumio[941]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:276:10) Feb 15 13:34:17 shd volumio[941]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:202:12) Feb 15 13:34:17 shd volumio[941]: at emitTwo (events.js:126:13) Feb 15 13:34:17 shd volumio[941]: at Browser.emit (events.js:214:7) Feb 15 13:34:17 shd volumio[941]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Feb 15 13:34:17 shd volumio[941]: at next (/volumio/node_modules/mdns/lib/browser.js:104:7) Feb 15 13:34:17 shd volumio[941]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Feb 15 13:34:17 shd volumio[941]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Feb 15 13:34:17 shd volumio[941]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Feb 15 13:34:17 shd sudo[1251]: pam_unix(sudo:session): session closed for user root Feb 15 13:34:17 shd systemd[1]: Stopping RAAT DAEMON... Feb 15 13:34:17 shd systemd[1]: Starting RAAT DAEMON... Feb 15 13:34:17 shd systemd[1]: Started RAAT DAEMON. Feb 15 13:34:17 shd volumio[941]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 13:34:18 shd sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 13:33 Feb 15 13:34:18 shd sudo[1272]: 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="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_BE_VERSION="0cf482c24e7a7ab87af1081ea7d6d953621249a4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Mar 23 10:08:04 CET 2022" VOLUMIO_VERSION="1.121" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="c49a059c2bcfb892c670d4f5a84421ae"