-- Logs begin at Wed 2022-07-13 14:55:24 UTC, end at Mon 2025-10-27 07:27:20 UTC. -- Oct 27 07:26:35 musical-fidelity volumio[8344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:26:35 musical-fidelity volumio[8344]: { Error: connect ETIMEDOUT 174.37.243.85:443 Oct 27 07:26:35 musical-fidelity volumio[8344]: at Object._errnoException (util.js:1022:11) Oct 27 07:26:35 musical-fidelity volumio[8344]: at _exceptionWithHostPort (util.js:1044:20) Oct 27 07:26:35 musical-fidelity volumio[8344]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 27 07:26:35 musical-fidelity volumio[8344]: code: 'ETIMEDOUT', Oct 27 07:26:35 musical-fidelity volumio[8344]: errno: 'ETIMEDOUT', Oct 27 07:26:35 musical-fidelity volumio[8344]: syscall: 'connect', Oct 27 07:26:35 musical-fidelity volumio[8344]: address: '174.37.243.85', Oct 27 07:26:35 musical-fidelity volumio[8344]: port: 443 } Oct 27 07:26:35 musical-fidelity volumio[8344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:26:36 musical-fidelity sudo[9841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-27 07:25 Oct 27 07:26:36 musical-fidelity sudo[9841]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:36 musical-fidelity sudo[9841]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:37 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:26:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 27 07:26:37 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:26:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 27 07:26:37 musical-fidelity systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Oct 27 07:26:37 musical-fidelity bluetoothd[712]: Endpoint unregistered: sender=:1.78 path=/MediaEndpoint/A2DPSource Oct 27 07:26:37 musical-fidelity bluetoothd[712]: Endpoint unregistered: sender=:1.78 path=/MediaEndpoint/A2DPSink Oct 27 07:26:37 musical-fidelity systemd[1]: Unit volumio.service entered failed state. Oct 27 07:26:37 musical-fidelity systemd[1]: Starting dynamicswap service... Oct 27 07:26:37 musical-fidelity systemd[1]: Started dynamicswap service. Oct 27 07:26:37 musical-fidelity systemd[1]: volumio.service holdoff time over, scheduling restart. Oct 27 07:26:37 musical-fidelity systemd[1]: Starting dynamicswap service... Oct 27 07:26:37 musical-fidelity systemd[1]: Started dynamicswap service. Oct 27 07:26:37 musical-fidelity systemd[1]: Stopping Volumio Backend Module... Oct 27 07:26:37 musical-fidelity systemd[1]: Starting Volumio Backend Module... Oct 27 07:26:37 musical-fidelity systemd[1]: Started Volumio Backend Module. Oct 27 07:26:39 musical-fidelity volumio[9873]: info: ------------------------------------------- Oct 27 07:26:39 musical-fidelity volumio[9873]: info: ----- Volumio2 ---- Oct 27 07:26:39 musical-fidelity volumio[9873]: info: ------------------------------------------- Oct 27 07:26:39 musical-fidelity volumio[9873]: info: ----- System startup ---- Oct 27 07:26:39 musical-fidelity volumio[9873]: info: ------------------------------------------- Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Plugin folders cleanup Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Scanning into folder /volumio/app/plugins/ Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Scanning category audio_interface Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Scanning category miscellanea Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Scanning category music_service Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Scanning category plugins.json Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Scanning category system_controller Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Scanning category user_interface Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Scanning into folder /data/plugins/ Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Plugin folders cleanup completed Oct 27 07:26:40 musical-fidelity volumio[9873]: info: ------------------------------------------- Oct 27 07:26:40 musical-fidelity volumio[9873]: info: ----- Core plugins startup ---- Oct 27 07:26:40 musical-fidelity volumio[9873]: info: ------------------------------------------- Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Loading plugins from folder /volumio/app/plugins/ Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Adding plugin upnp to MyMusic Plugins Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Adding plugin metavolumio to MyMusic Plugins Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Adding plugin cd_controller to MyMusic Plugins Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Adding plugin raat to MyMusic Plugins Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Loading plugins from folder /data/plugins/ Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Loading plugin "system"... Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Applying required configuration parameters for plugin system Oct 27 07:26:40 musical-fidelity volumio[9873]: info: Loading plugin "appearance"... Oct 27 07:26:41 musical-fidelity volumio[9873]: info: Loading plugin "network"... Oct 27 07:26:41 musical-fidelity volumio[9873]: info: Refreshing Cached IP Addresses Oct 27 07:26:41 musical-fidelity sudo[9916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 07:26:41 musical-fidelity volumio[9873]: info: Loading plugin "services"... Oct 27 07:26:41 musical-fidelity sudo[9916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:41 musical-fidelity volumio[9873]: info: Loading plugin "alsa_controller"... Oct 27 07:26:41 musical-fidelity sudo[9916]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:41 musical-fidelity volumio[9873]: info: Applying required configuration parameters for plugin alsa_controller Oct 27 07:26:41 musical-fidelity sudo[9920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 07:26:41 musical-fidelity sudo[9920]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:41 musical-fidelity sudo[9920]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:42 musical-fidelity volumio[9873]: info: Loading plugin "wizard"... Oct 27 07:26:42 musical-fidelity volumio[9873]: info: Loading plugin "volumio_command_line_client"... Oct 27 07:26:42 musical-fidelity volumio[9873]: info: Loading plugin "upnp"... Oct 27 07:26:42 musical-fidelity volumio[9873]: info: [1761550002098] Starting Upmpd Daemon Oct 27 07:26:42 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:26:42 musical-fidelity volumio[9873]: info: Loading plugin "my_music"... Oct 27 07:26:42 musical-fidelity volumio[9873]: info: Loading plugin "mpd"... Oct 27 07:26:42 musical-fidelity volumio[9873]: info: Loading plugin "upnp_browser"... Oct 27 07:26:42 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:26:42] [connect] Successful connection Oct 27 07:26:42 musical-fidelity volumio[9873]: info: Loading plugin "networkfs"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Cannot mount NAS shared at system boot, trial number 1 ,retrying in 5 seconds Oct 27 07:26:43 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "bluetooth"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: [1761550003169] Starting BluetoothController Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "multiroom"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "alarm-clock"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "metavolumio"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "airplay_emulation"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Starting Shairport Sync Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "cd_controller"... Oct 27 07:26:43 musical-fidelity sudo[9948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 27 07:26:43 musical-fidelity sudo[9948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Plugin last_100 is not enabled Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "raat"... Oct 27 07:26:43 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 27 07:26:43 musical-fidelity sudo[9948]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:43 musical-fidelity volumio[9873]: info: RAAT Plugin loaded Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Adding restartRAATSocket REST API Endpoint Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "streaming_services"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "webradio"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "i2s_dacs"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: I2S DAC not set, start Auto-detection Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "volumiodiscovery"... Oct 27 07:26:43 musical-fidelity volumio[9873]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 27 07:26:43 musical-fidelity node[9873]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 27 07:26:43 musical-fidelity node[9873]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 07:26:43 musical-fidelity node[9873]: *** WARNING *** For more information see Oct 27 07:26:43 musical-fidelity node[9873]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 27 07:26:43 musical-fidelity node[9873]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 07:26:43 musical-fidelity node[9873]: *** WARNING *** For more information see Oct 27 07:26:43 musical-fidelity volumio[9873]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 07:26:43 musical-fidelity volumio[9873]: *** WARNING *** For more information see Oct 27 07:26:43 musical-fidelity volumio[9873]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 27 07:26:43 musical-fidelity volumio[9873]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 07:26:43 musical-fidelity volumio[9873]: *** WARNING *** For more information see Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 27 07:26:43 musical-fidelity volumio[9873]: Discovery: StartAdv! undefined Oct 27 07:26:43 musical-fidelity volumio[9873]: Discovery: Started advertising... musical-fidelity - undefined Oct 27 07:26:43 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Plugin outputs is not enabled Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "albumart"... Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Plugin example_plugin is not enabled Oct 27 07:26:43 musical-fidelity volumio[9873]: info: Loading plugin "hi_res_audio"... Oct 27 07:26:44 musical-fidelity volumio[9873]: info: Applying required configuration parameters for plugin hi_res_audio Oct 27 07:26:44 musical-fidelity volumio[9873]: info: Loading plugin "inputs"... Oct 27 07:26:44 musical-fidelity volumio[9873]: info: Adding inputs REST Endpoints Oct 27 07:26:44 musical-fidelity volumio[9873]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/inputs Oct 27 07:26:44 musical-fidelity volumio[9873]: info: Scanning Audio Inputs Oct 27 07:26:44 musical-fidelity volumio[9873]: info: Checking against Known Cards name Oct 27 07:26:44 musical-fidelity volumio[9873]: info: Loading plugin "qobuz"... Oct 27 07:26:44 musical-fidelity volumio[9873]: Forking 3 albumart workers Oct 27 07:26:45 musical-fidelity volumio[9873]: Starting albumart workers Oct 27 07:26:45 musical-fidelity volumio[9873]: Starting albumart workers Oct 27 07:26:45 musical-fidelity volumio[9873]: Starting albumart workers Oct 27 07:26:46 musical-fidelity volumio[9873]: info: Loading plugin "spop"... Oct 27 07:26:46 musical-fidelity volumio[9873]: info: Loading plugin "tidal"... Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Loading plugin "gpios"... Oct 27 07:26:48 musical-fidelity volumio[9873]: error: ATTENTION!!!: Plugin gpios does not return adequate promise from onVolumioStart: please update! Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Loading plugin "updater_comm"... Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Plugin mpdemulation is not enabled Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Loading plugin "rest_api"... Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Loading plugin "websocket"... Oct 27 07:26:48 musical-fidelity volumio[9873]: info: ___________ START PLUGINS ___________ Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Adding METAVOLUMIO REST API Endpoints Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 27 07:26:48 musical-fidelity volumio[9873]: info: [1761550008508] CoreMusicLibrary::Adding element Shairport-Sync Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:26:48 musical-fidelity sudo[10052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name musical-fidelity Oct 27 07:26:48 musical-fidelity sudo[10052]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Preparing CD Folders Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Adding CD REST API Endpoints Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Oct 27 07:26:48 musical-fidelity sudo[10052]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Detecting CD presence with Flag file Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Starting RAAT Plugin Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Additional UI Settings Added for plugin music_service/raat Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Not Reporting Auto name since its the default one Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Streaming services startup Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Starting Streaming Daemon Oct 27 07:26:48 musical-fidelity sudo[10065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 27 07:26:48 musical-fidelity sudo[10065]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:48 musical-fidelity systemd[1]: Stopping Volumio Streaming Daemon... Oct 27 07:26:48 musical-fidelity systemd[1]: Starting Volumio Streaming Daemon... Oct 27 07:26:48 musical-fidelity systemd[1]: Started Volumio Streaming Daemon. Oct 27 07:26:48 musical-fidelity sudo[10065]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 27 07:26:48 musical-fidelity volumio[9873]: info: [1761550008670] CoreMusicLibrary::Adding element Webradio Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:26:48 musical-fidelity volumio-streaming-daemon[10075]: ############################ Oct 27 07:26:48 musical-fidelity volumio-streaming-daemon[10075]: # Volumio Streaming Daemon # Oct 27 07:26:48 musical-fidelity volumio-streaming-daemon[10075]: # Running on port 7777 # Oct 27 07:26:48 musical-fidelity volumio-streaming-daemon[10075]: ############################ Oct 27 07:26:48 musical-fidelity volumio-streaming-daemon[10075]: Checking variant in static cache Oct 27 07:26:48 musical-fidelity volumio-streaming-daemon[10075]: Enabled services: Oct 27 07:26:48 musical-fidelity volumio-streaming-daemon[10075]: 1) tidal Oct 27 07:26:48 musical-fidelity volumio-streaming-daemon[10075]: 2) qobuz Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 07:26:48 musical-fidelity volumio[9873]: error: Hi Res Audio Failed Login: Missing Login Data Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:26:48 musical-fidelity volumio[9873]: [SpotifyConnect] Creating VLS config file Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: [SpotifyConnect] Starting metadata listener Oct 27 07:26:48 musical-fidelity volumio[9873]: error: Plugin gpios does not return adequate promise from onStart: please update! Oct 27 07:26:48 musical-fidelity sudo[10086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 27 07:26:48 musical-fidelity volumio[9873]: info: Loading i18n strings for locale zh Oct 27 07:26:48 musical-fidelity sudo[10086]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:48 musical-fidelity volumio[9873]: Updating browse sources language Oct 27 07:26:48 musical-fidelity volumio[9873]: Cannot find translation for sourceBluetooth Oct 27 07:26:48 musical-fidelity volumio[9873]: Cannot find translation for sourceUSB/PC Oct 27 07:26:48 musical-fidelity volumio[9873]: Cannot find translation for sourceShairport-Sync Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:26:48 musical-fidelity systemd[1]: Started Volspotconnect2 Daemon. Oct 27 07:26:48 musical-fidelity sudo[10086]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::initPlayerControls Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: info: BOOT COMPLETED Oct 27 07:26:48 musical-fidelity volumio[9873]: [Metrics] CommandRouter: 9s 148.12ms Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 07:26:48 musical-fidelity volumio[9873]: aplay: main:722: audio open error: Device or resource busy Oct 27 07:26:48 musical-fidelity volumio[9873]: Cannot play startup sound Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::Close All Modals sent Oct 27 07:26:48 musical-fidelity volumio[9873]: info: CoreCommandRouter::Close All Modals sent Oct 27 07:26:48 musical-fidelity volumio[9873]: Express server listening on port 3000 Oct 27 07:26:48 musical-fidelity volumio[9873]: [Metrics] WebUI: 9s 703.78ms Oct 27 07:26:48 musical-fidelity volumio[9873]: Volumio Calling Home Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreStateMachine::resetVolumioState Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreStateMachine::getcurrentVolume Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 07:26:49 musical-fidelity volumio[9873]: info: Stopping AccessToken refresher cron for QOBUZ Oct 27 07:26:49 musical-fidelity volumio[9873]: info: AccessToken refresher cron started for QOBUZ Oct 27 07:26:49 musical-fidelity volumio[9873]: info: Stopping AccessToken refresher cron Oct 27 07:26:49 musical-fidelity volumio[9873]: info: AccessToken refresher cron started Oct 27 07:26:49 musical-fidelity volumio[9873]: info: Cannot mount NAS shared at system boot, trial number 2 ,retrying in 5 seconds Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:26:49 musical-fidelity volumio[9873]: info: Serial port opened successfully Oct 27 07:26:49 musical-fidelity sudo[10119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart leddaemon.service Oct 27 07:26:49 musical-fidelity sudo[10119]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:49 musical-fidelity systemd[1]: Stopping LED Daemon Service... Oct 27 07:26:49 musical-fidelity systemd[1]: Starting LED Daemon Service... Oct 27 07:26:49 musical-fidelity systemd[1]: Started LED Daemon Service. Oct 27 07:26:49 musical-fidelity led-daemon[10125]: Starting Network LED Daemon Oct 27 07:26:49 musical-fidelity sudo[10119]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:49 musical-fidelity volumio[9873]: info: Setting Device type: Raspberry PI Oct 27 07:26:49 musical-fidelity volumio[9873]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 27 07:26:49 musical-fidelity volumio[9873]: [Metrics] SpotifyConnect: 0s 875.19ms Oct 27 07:26:49 musical-fidelity volumio[9873]: info: Bluetooth name changed to musical-fidelity Oct 27 07:26:49 musical-fidelity volumio[9873]: info: Shairport-Sync Stopped Oct 27 07:26:49 musical-fidelity volumio[9873]: info: MPD running with PID755 Oct 27 07:26:49 musical-fidelity volumio[9873]: ,establishing connection Oct 27 07:26:49 musical-fidelity sudo[10135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 27 07:26:49 musical-fidelity sudo[10135]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:49 musical-fidelity sudo[10135]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:49 musical-fidelity volumio[9873]: info: VolumeController:: Volume=100 Mute =false Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreStateMachine::updateTrackBlock Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrackBlock Oct 27 07:26:49 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 07:26:49 musical-fidelity volumio[9873]: info: Volumio called home Oct 27 07:26:50 musical-fidelity volumio[9873]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Oct 27 07:26:50 musical-fidelity volumio[9873]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 27 07:26:50 musical-fidelity volumio[9873]: info: Reloading queue from file Oct 27 07:26:50 musical-fidelity volumio[9873]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Oct 27 07:26:50 musical-fidelity sudo[10153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Oct 27 07:26:50 musical-fidelity sudo[10153]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:50 musical-fidelity volumio[9873]: error: Failed LSINFO: Error: [50@0] {} No such directory Oct 27 07:26:50 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:26:50 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:26:50 musical-fidelity sudo[10153]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreStateMachine::setRepeat null single undefined Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreStateMachine::setRandom null Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:26:50 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:26:51 musical-fidelity volumio[9873]: info: Raat Daemon started successfully Oct 27 07:26:51 musical-fidelity volumio[9873]: info: Executing endpoint restartRAATSocket Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:26:51 musical-fidelity volumio[9873]: info: VolumeController:: Volume=100 Mute =false Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:26:51 musical-fidelity systemd[1]: raat-daemon.service: main process exited, code=killed, status=6/ABRT Oct 27 07:26:51 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:26:51 musical-fidelity volumio[9873]: Discovery: adding 439480b0-83e6-4a87-981a-0ce674d419d9 Oct 27 07:26:51 musical-fidelity volumio[9873]: info: mDNS: Found device musical-fidelity Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioGetState Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:51 musical-fidelity volumio[9873]: info: Updating push state because input changed Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumiosetSourceActivempd Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:26:51 musical-fidelity systemd[1]: raat-daemon.service holdoff time over, scheduling restart. Oct 27 07:26:51 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:26:51 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:26:51 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:26:51 musical-fidelity volumio[9873]: Discovery: this is already registered, 439480b0-83e6-4a87-981a-0ce674d419d9 Oct 27 07:26:51 musical-fidelity volumio[9873]: info: mDNS: Found device musical-fidelity Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioGetState Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 07:26:51 musical-fidelity volumio[9873]: info: Executing endpoint restartRAATSocket Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:51 musical-fidelity sudo[10194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 27 07:26:51 musical-fidelity sudo[10194]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:51 musical-fidelity sudo[10194]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:51 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:51 musical-fidelity systemd[1]: raat-daemon.service: main process exited, code=killed, status=6/ABRT Oct 27 07:26:51 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:26:51 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:51 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:51 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioGetState Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioGetQueue Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreStateMachine::getQueue Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CorePlayQueue::getQueue Oct 27 07:26:51 musical-fidelity volumio[9873]: info: Listing playlists Oct 27 07:26:51 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 07:26:51 musical-fidelity volumio[9873]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 07:26:51 musical-fidelity systemd[1]: raat-daemon.service holdoff time over, scheduling restart. Oct 27 07:26:51 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:26:51 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:26:51 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:26:51 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:51 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:52 musical-fidelity volumio[9873]: info: Executing endpoint restartRAATSocket Oct 27 07:26:52 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:26:52 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:52 musical-fidelity systemd[1]: raat-daemon.service: main process exited, code=killed, status=6/ABRT Oct 27 07:26:52 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:26:52 musical-fidelity sudo[10217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 07:26:52 musical-fidelity volumio-streaming-daemon[10075]: Checking variant in static cache Oct 27 07:26:52 musical-fidelity sudo[10219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 07:26:52 musical-fidelity sudo[10217]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:52 musical-fidelity sudo[10217]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:52 musical-fidelity sudo[10219]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:52 musical-fidelity sudo[10219]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:52 musical-fidelity volumio[9873]: info: Fetching Streaming Services browse cache Oct 27 07:26:52 musical-fidelity sudo[10236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 27 07:26:52 musical-fidelity sudo[10236]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:52 musical-fidelity systemd[1]: raat-daemon.service holdoff time over, scheduling restart. Oct 27 07:26:52 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:26:52 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:26:52 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:26:52 musical-fidelity systemd[1]: Started UPnP Renderer front-end to MPD. Oct 27 07:26:52 musical-fidelity sudo[10236]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:52 musical-fidelity volumio[9873]: info: Upmpdcli Daemon Started Oct 27 07:26:52 musical-fidelity volumio[9873]: info: Executing endpoint restartRAATSocket Oct 27 07:26:52 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:26:52 musical-fidelity sudo[10258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 27 07:26:52 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 27 07:26:52 musical-fidelity volumio[9873]: info: Setting Additional System Software info: Hardware Firmware Version: 117 Hardware Revision: 1 Oct 27 07:26:52 musical-fidelity sudo[10258]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:26:52 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:52 musical-fidelity sudo[10258]: pam_unix(sudo:session): session closed for user root Oct 27 07:26:52 musical-fidelity systemd[1]: raat-daemon.service: main process exited, code=killed, status=6/ABRT Oct 27 07:26:52 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:52 musical-fidelity pulseaudio[10271]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 27 07:26:52 musical-fidelity pulseaudio[10271]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 27 07:26:52 musical-fidelity pulseaudio[10271]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Oct 27 07:26:52 musical-fidelity bluetoothd[712]: Endpoint registered: sender=:1.88 path=/MediaEndpoint/A2DPSource Oct 27 07:26:52 musical-fidelity bluetoothd[712]: Endpoint registered: sender=:1.88 path=/MediaEndpoint/A2DPSink Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:52 musical-fidelity systemd[1]: raat-daemon.service holdoff time over, scheduling restart. Oct 27 07:26:52 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:26:52 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:26:52 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:52 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:52 musical-fidelity volumio[9873]: info: Executing endpoint restartRAATSocket Oct 27 07:26:52 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:26:53 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:26:53 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:53 musical-fidelity systemd[1]: raat-daemon.service: main process exited, code=killed, status=6/ABRT Oct 27 07:26:53 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:26:53 musical-fidelity volumio[9873]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:26:53 musical-fidelity systemd[1]: raat-daemon.service holdoff time over, scheduling restart. Oct 27 07:26:53 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:26:53 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:26:53 musical-fidelity systemd[1]: raat-daemon.service start request repeated too quickly, refusing to start. Oct 27 07:26:53 musical-fidelity systemd[1]: Failed to start RAAT DAEMON. Oct 27 07:26:53 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:26:53 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:53 musical-fidelity volumio[9873]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:26:53 musical-fidelity volumio[9873]: info: Checking Spotify Web API Oct 27 07:26:54 musical-fidelity volumio[9873]: info: Cannot mount NAS shared at system boot, trial number 3 ,retrying in 5 seconds Oct 27 07:26:55 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:26:55] [connect] Successful connection Oct 27 07:26:55 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:26:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1761550015 101 Oct 27 07:26:59 musical-fidelity volumio[9873]: info: Cannot mount NAS shared at system boot, trial number 4 ,retrying in 5 seconds Oct 27 07:27:04 musical-fidelity volumio[9873]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 27 07:27:15 musical-fidelity volumio[9873]: info: Clearing queue after UPNP request Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::ClearQueue Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::stop Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 07:27:16 musical-fidelity volumio[9873]: verbose: UNSET VOLATILE Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CorePlayQueue::clearPlayQueue Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CorePlayQueue::saveQueue Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushQueue Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Updating RAAT Signal Path Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Oct 27 07:27:16 musical-fidelity volumio[9873]: ---------------------------- MPD announces system playlist update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Ignoring MPD Status Update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Oct 27 07:27:16 musical-fidelity volumio[9873]: ---------------------------- MPD announces state update: player Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ControllerMpd::getState Oct 27 07:27:16 musical-fidelity volumio[9873]: verbose: ControllerMpd::sendMpdCommand status Oct 27 07:27:16 musical-fidelity volumio[9873]: error: updateQueue error: null Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ------------------------------ 9ms Oct 27 07:27:16 musical-fidelity volumio[9873]: info: sendMpdCommand status took 6 milliseconds Oct 27 07:27:16 musical-fidelity volumio[9873]: verbose: ControllerMpd::parseState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ControllerMpd::pushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::servicePushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:27:16 musical-fidelity volumio[9873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 27 07:27:16 musical-fidelity volumio[9873]: verbose: CURRENT POSITION 0 Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::syncState stateService stop Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::syncState currentStatus stop Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:27:16 musical-fidelity volumio[9873]: info: No code Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ------------------------------ 41ms Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Starting UPNP Playback Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Preparing playback through UPNP Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioGetState Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CorePlayQueue::getTrack 0 Oct 27 07:27:16 musical-fidelity volumio[9873]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Updating RAAT Signal Path Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Updating RAAT Signal Path Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Updating RAAT Signal Path Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Oct 27 07:27:16 musical-fidelity volumio[9873]: ---------------------------- MPD announces system playlist update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Ignoring MPD Status Update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ------------------------------ 4ms Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Oct 27 07:27:16 musical-fidelity volumio[9873]: ---------------------------- MPD announces system playlist update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Ignoring MPD Status Update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ------------------------------ 3ms Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Oct 27 07:27:16 musical-fidelity volumio[9873]: ---------------------------- MPD announces system playlist update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Ignoring MPD Status Update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ------------------------------ 3ms Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Oct 27 07:27:16 musical-fidelity volumio[9873]: ---------------------------- MPD announces system playlist update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Ignoring MPD Status Update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ------------------------------ 3ms Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Oct 27 07:27:16 musical-fidelity volumio[9873]: ---------------------------- MPD announces system playlist update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Ignoring MPD Status Update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ------------------------------ 3ms Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Oct 27 07:27:16 musical-fidelity volumio[9873]: ---------------------------- MPD announces system playlist update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: Ignoring MPD Status Update Oct 27 07:27:16 musical-fidelity volumio[9873]: info: ------------------------------ 3ms Oct 27 07:27:18 musical-fidelity volumio[9873]: info: Oct 27 07:27:18 musical-fidelity volumio[9873]: ---------------------------- MPD announces state update: player Oct 27 07:27:18 musical-fidelity volumio[9873]: info: ControllerMpd::getState Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: ControllerMpd::sendMpdCommand status Oct 27 07:27:18 musical-fidelity volumio[9873]: info: Oct 27 07:27:18 musical-fidelity volumio[9873]: ---------------------------- MPD announces state update: player Oct 27 07:27:18 musical-fidelity volumio[9873]: info: sendMpdCommand status took 4 milliseconds Oct 27 07:27:18 musical-fidelity volumio[9873]: info: ControllerMpd::getState Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: ControllerMpd::sendMpdCommand status Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: ControllerMpd::parseState Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 07:27:18 musical-fidelity volumio[9873]: info: sendMpdCommand status took 3 milliseconds Oct 27 07:27:18 musical-fidelity volumio[9873]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: ControllerMpd::parseState Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: ControllerMpd::parseTrackInfo Oct 27 07:27:18 musical-fidelity volumio[9873]: info: ControllerMpd::pushState Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::servicePushState Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: In UPNP mode Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"787 Kbps","isStreaming":false,"title":"Deutschland","artist":"Rammstein","album":"Rammstein","uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlNmM4M2M1NDU2YzgzMDFhMjQ1ODE0NGQ3Y2FkNTA5MF82MS5tcDQ/0.flac?token=1761553351~YzcxMmY3MGNiZGY4NzJmZTVhNTUyZjlhOGRjMDIzOGYyYTQwMjViZQ==","trackType":"tidal"} Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: CURRENT POSITION 0 Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreStateMachine::syncState stateService play Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreStateMachine::syncState currentStatus stop Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:27:18 musical-fidelity volumio[9873]: info: ------------------------------ 27ms Oct 27 07:27:18 musical-fidelity volumio[9873]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: ControllerMpd::parseTrackInfo Oct 27 07:27:18 musical-fidelity volumio[9873]: info: ControllerMpd::pushState Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::servicePushState Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: In UPNP mode Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"787 Kbps","isStreaming":false,"title":"Deutschland","artist":"Rammstein","album":"Rammstein","uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlNmM4M2M1NDU2YzgzMDFhMjQ1ODE0NGQ3Y2FkNTA5MF82MS5tcDQ/0.flac?token=1761553351~YzcxMmY3MGNiZGY4NzJmZTVhNTUyZjlhOGRjMDIzOGYyYTQwMjViZQ==","trackType":"tidal"} Oct 27 07:27:18 musical-fidelity volumio[9873]: verbose: CURRENT POSITION 0 Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreStateMachine::syncState stateService play Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreStateMachine::syncState currentStatus play Oct 27 07:27:18 musical-fidelity volumio[9873]: info: Received an update from plugin. extracting info from payload Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreStateMachine::pushState Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::volumioPushState Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:27:18 musical-fidelity volumio[9873]: info: ------------------------------ 50ms Oct 27 07:27:18 musical-fidelity volumio[9873]: info: Updating RAAT Signal Path Oct 27 07:27:18 musical-fidelity volumio[9873]: info: Updating RAAT Signal Path Oct 27 07:27:18 musical-fidelity volumio[9873]: info: Updating RAAT Signal Path Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 07:27:18 musical-fidelity volumio[9873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 07:27:19 musical-fidelity volumio[9873]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:27:19 musical-fidelity volumio[9873]: { Error: connect ECONNREFUSED 199.59.148.7:443 Oct 27 07:27:19 musical-fidelity volumio[9873]: at Object._errnoException (util.js:1022:11) Oct 27 07:27:19 musical-fidelity volumio[9873]: at _exceptionWithHostPort (util.js:1044:20) Oct 27 07:27:19 musical-fidelity volumio[9873]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 27 07:27:19 musical-fidelity volumio[9873]: code: 'ECONNREFUSED', Oct 27 07:27:19 musical-fidelity volumio[9873]: errno: 'ECONNREFUSED', Oct 27 07:27:19 musical-fidelity volumio[9873]: syscall: 'connect', Oct 27 07:27:19 musical-fidelity volumio[9873]: address: '199.59.148.7', Oct 27 07:27:19 musical-fidelity volumio[9873]: port: 443 } Oct 27 07:27:19 musical-fidelity volumio[9873]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:27:20 musical-fidelity sudo[10480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-27 07:26 Oct 27 07:27:20 musical-fidelity sudo[10480]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"