-- Logs begin at Wed 2022-07-13 14:55:24 UTC, end at Mon 2025-10-27 07:43:41 UTC. -- Oct 27 07:43:19 musical-fidelity volumio[16877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:43:19 musical-fidelity volumio[16877]: { Error: connect ETIMEDOUT 199.96.63.163:443 Oct 27 07:43:19 musical-fidelity volumio[16877]: at Object._errnoException (util.js:1022:11) Oct 27 07:43:19 musical-fidelity volumio[16877]: at _exceptionWithHostPort (util.js:1044:20) Oct 27 07:43:19 musical-fidelity volumio[16877]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 27 07:43:19 musical-fidelity volumio[16877]: code: 'ETIMEDOUT', Oct 27 07:43:19 musical-fidelity volumio[16877]: errno: 'ETIMEDOUT', Oct 27 07:43:19 musical-fidelity volumio[16877]: syscall: 'connect', Oct 27 07:43:19 musical-fidelity volumio[16877]: address: '199.96.63.163', Oct 27 07:43:19 musical-fidelity volumio[16877]: port: 443 } Oct 27 07:43:19 musical-fidelity volumio[16877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:43:19 musical-fidelity sudo[18195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-27 07:42 Oct 27 07:43:19 musical-fidelity sudo[18195]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:19 musical-fidelity sudo[18195]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:21 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:43:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 27 07:43:21 musical-fidelity systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Oct 27 07:43:21 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:43:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 27 07:43:21 musical-fidelity bluetoothd[712]: Endpoint unregistered: sender=:1.128 path=/MediaEndpoint/A2DPSource Oct 27 07:43:21 musical-fidelity bluetoothd[712]: Endpoint unregistered: sender=:1.128 path=/MediaEndpoint/A2DPSink Oct 27 07:43:21 musical-fidelity systemd[1]: Unit volumio.service entered failed state. Oct 27 07:43:21 musical-fidelity systemd[1]: Starting dynamicswap service... Oct 27 07:43:21 musical-fidelity systemd[1]: Started dynamicswap service. Oct 27 07:43:21 musical-fidelity systemd[1]: volumio.service holdoff time over, scheduling restart. Oct 27 07:43:21 musical-fidelity systemd[1]: Starting dynamicswap service... Oct 27 07:43:21 musical-fidelity systemd[1]: Started dynamicswap service. Oct 27 07:43:21 musical-fidelity systemd[1]: Stopping Volumio Backend Module... Oct 27 07:43:21 musical-fidelity systemd[1]: Starting Volumio Backend Module... Oct 27 07:43:21 musical-fidelity systemd[1]: Started Volumio Backend Module. Oct 27 07:43:23 musical-fidelity volumio[18226]: info: ------------------------------------------- Oct 27 07:43:23 musical-fidelity volumio[18226]: info: ----- Volumio2 ---- Oct 27 07:43:23 musical-fidelity volumio[18226]: info: ------------------------------------------- Oct 27 07:43:23 musical-fidelity volumio[18226]: info: ----- System startup ---- Oct 27 07:43:23 musical-fidelity volumio[18226]: info: ------------------------------------------- Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Plugin folders cleanup Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Scanning into folder /volumio/app/plugins/ Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Scanning category audio_interface Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Scanning category miscellanea Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Scanning category music_service Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Scanning category plugins.json Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Scanning category system_controller Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Scanning category user_interface Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Scanning into folder /data/plugins/ Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Plugin folders cleanup completed Oct 27 07:43:24 musical-fidelity volumio[18226]: info: ------------------------------------------- Oct 27 07:43:24 musical-fidelity volumio[18226]: info: ----- Core plugins startup ---- Oct 27 07:43:24 musical-fidelity volumio[18226]: info: ------------------------------------------- Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Loading plugins from folder /volumio/app/plugins/ Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Adding plugin upnp to MyMusic Plugins Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Adding plugin metavolumio to MyMusic Plugins Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Adding plugin cd_controller to MyMusic Plugins Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Adding plugin raat to MyMusic Plugins Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Loading plugins from folder /data/plugins/ Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Loading plugin "system"... Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Applying required configuration parameters for plugin system Oct 27 07:43:24 musical-fidelity volumio[18226]: info: Loading plugin "appearance"... Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Loading plugin "network"... Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Refreshing Cached IP Addresses Oct 27 07:43:25 musical-fidelity sudo[18271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 07:43:25 musical-fidelity sudo[18271]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Loading plugin "services"... Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Loading plugin "alsa_controller"... Oct 27 07:43:25 musical-fidelity sudo[18271]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:25 musical-fidelity sudo[18276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 07:43:25 musical-fidelity sudo[18276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Applying required configuration parameters for plugin alsa_controller Oct 27 07:43:25 musical-fidelity sudo[18276]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Loading plugin "wizard"... Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Loading plugin "volumio_command_line_client"... Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Loading plugin "upnp"... Oct 27 07:43:25 musical-fidelity volumio[18226]: info: [1761551005890] Starting Upmpd Daemon Oct 27 07:43:25 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Loading plugin "my_music"... Oct 27 07:43:25 musical-fidelity volumio[18226]: info: Loading plugin "mpd"... Oct 27 07:43:26 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:43:26] [connect] Successful connection Oct 27 07:43:26 musical-fidelity volumio[18226]: info: Loading plugin "upnp_browser"... Oct 27 07:43:26 musical-fidelity volumio[18226]: info: Loading plugin "networkfs"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Cannot mount NAS shared at system boot, trial number 1 ,retrying in 5 seconds Oct 27 07:43:27 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "bluetooth"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: [1761551007081] Starting BluetoothController Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "multiroom"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "alarm-clock"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "metavolumio"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "airplay_emulation"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Starting Shairport Sync Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "cd_controller"... Oct 27 07:43:27 musical-fidelity sudo[18305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 27 07:43:27 musical-fidelity sudo[18305]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Plugin last_100 is not enabled Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "raat"... Oct 27 07:43:27 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 27 07:43:27 musical-fidelity sudo[18305]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:27 musical-fidelity volumio[18226]: info: RAAT Plugin loaded Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Adding restartRAATSocket REST API Endpoint Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "streaming_services"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "webradio"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "i2s_dacs"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: I2S DAC not set, start Auto-detection Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "volumiodiscovery"... Oct 27 07:43:27 musical-fidelity volumio[18226]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 27 07:43:27 musical-fidelity node[18226]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 27 07:43:27 musical-fidelity node[18226]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 07:43:27 musical-fidelity node[18226]: *** WARNING *** For more information see Oct 27 07:43:27 musical-fidelity node[18226]: *** 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:43:27 musical-fidelity node[18226]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 07:43:27 musical-fidelity node[18226]: *** WARNING *** For more information see Oct 27 07:43:27 musical-fidelity volumio[18226]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 07:43:27 musical-fidelity volumio[18226]: *** WARNING *** For more information see Oct 27 07:43:27 musical-fidelity volumio[18226]: *** 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:43:27 musical-fidelity volumio[18226]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 07:43:27 musical-fidelity volumio[18226]: *** WARNING *** For more information see Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 27 07:43:27 musical-fidelity volumio[18226]: Discovery: StartAdv! undefined Oct 27 07:43:27 musical-fidelity volumio[18226]: Discovery: Started advertising... musical-fidelity - undefined Oct 27 07:43:27 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Plugin outputs is not enabled Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "albumart"... Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Plugin example_plugin is not enabled Oct 27 07:43:27 musical-fidelity volumio[18226]: info: Loading plugin "hi_res_audio"... Oct 27 07:43:28 musical-fidelity volumio[18226]: info: Applying required configuration parameters for plugin hi_res_audio Oct 27 07:43:28 musical-fidelity volumio[18226]: info: Loading plugin "inputs"... Oct 27 07:43:28 musical-fidelity volumio[18226]: info: Adding inputs REST Endpoints Oct 27 07:43:28 musical-fidelity volumio[18226]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/inputs Oct 27 07:43:28 musical-fidelity volumio[18226]: info: Scanning Audio Inputs Oct 27 07:43:28 musical-fidelity volumio[18226]: Forking 3 albumart workers Oct 27 07:43:28 musical-fidelity volumio[18226]: info: Checking against Known Cards name Oct 27 07:43:28 musical-fidelity volumio[18226]: info: Loading plugin "qobuz"... Oct 27 07:43:29 musical-fidelity volumio[18226]: Starting albumart workers Oct 27 07:43:29 musical-fidelity volumio[18226]: Starting albumart workers Oct 27 07:43:29 musical-fidelity volumio[18226]: Starting albumart workers Oct 27 07:43:30 musical-fidelity volumio[18226]: info: Loading plugin "spop"... Oct 27 07:43:30 musical-fidelity volumio[18226]: info: Loading plugin "tidal"... Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Loading plugin "gpios"... Oct 27 07:43:32 musical-fidelity volumio[18226]: error: ATTENTION!!!: Plugin gpios does not return adequate promise from onVolumioStart: please update! Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Loading plugin "updater_comm"... Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Plugin mpdemulation is not enabled Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Loading plugin "rest_api"... Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Loading plugin "websocket"... Oct 27 07:43:32 musical-fidelity volumio[18226]: info: ___________ START PLUGINS ___________ Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Adding METAVOLUMIO REST API Endpoints Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 27 07:43:32 musical-fidelity volumio[18226]: info: [1761551012870] CoreMusicLibrary::Adding element Shairport-Sync Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:43:32 musical-fidelity sudo[18388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name musical-fidelity Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Preparing CD Folders Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Adding CD REST API Endpoints Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Detecting CD presence with Flag file Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Starting RAAT Plugin Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Additional UI Settings Added for plugin music_service/raat Oct 27 07:43:32 musical-fidelity sudo[18388]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:32 musical-fidelity sudo[18388]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:32 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Not Reporting Auto name since its the default one Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Streaming services startup Oct 27 07:43:32 musical-fidelity volumio[18226]: info: Starting Streaming Daemon Oct 27 07:43:32 musical-fidelity sudo[18401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 27 07:43:32 musical-fidelity sudo[18401]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:33 musical-fidelity systemd[1]: Stopping Volumio Streaming Daemon... Oct 27 07:43:33 musical-fidelity systemd[1]: Starting Volumio Streaming Daemon... Oct 27 07:43:33 musical-fidelity systemd[1]: Started Volumio Streaming Daemon. Oct 27 07:43:33 musical-fidelity sudo[18401]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 27 07:43:33 musical-fidelity volumio[18226]: info: [1761551013037] CoreMusicLibrary::Adding element Webradio Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:43:33 musical-fidelity volumio-streaming-daemon[18411]: ############################ Oct 27 07:43:33 musical-fidelity volumio-streaming-daemon[18411]: # Volumio Streaming Daemon # Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 07:43:33 musical-fidelity volumio-streaming-daemon[18411]: # Running on port 7777 # Oct 27 07:43:33 musical-fidelity volumio-streaming-daemon[18411]: ############################ Oct 27 07:43:33 musical-fidelity volumio-streaming-daemon[18411]: Checking variant in static cache Oct 27 07:43:33 musical-fidelity volumio-streaming-daemon[18411]: Enabled services: Oct 27 07:43:33 musical-fidelity volumio-streaming-daemon[18411]: 1) tidal Oct 27 07:43:33 musical-fidelity volumio-streaming-daemon[18411]: 2) qobuz Oct 27 07:43:33 musical-fidelity volumio[18226]: error: Hi Res Audio Failed Login: Missing Login Data Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 07:43:33 musical-fidelity volumio[18226]: [SpotifyConnect] Creating VLS config file Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: [SpotifyConnect] Starting metadata listener Oct 27 07:43:33 musical-fidelity volumio[18226]: error: Plugin gpios does not return adequate promise from onStart: please update! Oct 27 07:43:33 musical-fidelity sudo[18425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 27 07:43:33 musical-fidelity volumio[18226]: info: Loading i18n strings for locale zh Oct 27 07:43:33 musical-fidelity sudo[18425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:33 musical-fidelity volumio[18226]: Updating browse sources language Oct 27 07:43:33 musical-fidelity volumio[18226]: Cannot find translation for sourceBluetooth Oct 27 07:43:33 musical-fidelity volumio[18226]: Cannot find translation for sourceUSB/PC Oct 27 07:43:33 musical-fidelity volumio[18226]: Cannot find translation for sourceShairport-Sync Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:43:33 musical-fidelity systemd[1]: Started Volspotconnect2 Daemon. Oct 27 07:43:33 musical-fidelity sudo[18425]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::initPlayerControls Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: BOOT COMPLETED Oct 27 07:43:33 musical-fidelity volumio[18226]: [Metrics] CommandRouter: 10s 27.72ms Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: aplay: main:722: audio open error: Device or resource busy Oct 27 07:43:33 musical-fidelity volumio[18226]: Cannot play startup sound Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::Close All Modals sent Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::Close All Modals sent Oct 27 07:43:33 musical-fidelity volumio[18226]: Express server listening on port 3000 Oct 27 07:43:33 musical-fidelity volumio[18226]: [Metrics] WebUI: 10s 660.01ms Oct 27 07:43:33 musical-fidelity volumio[18226]: Volumio Calling Home Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreStateMachine::resetVolumioState Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreStateMachine::getcurrentVolume Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 07:43:33 musical-fidelity volumio[18226]: info: Stopping AccessToken refresher cron for QOBUZ Oct 27 07:43:33 musical-fidelity volumio[18226]: info: AccessToken refresher cron started for QOBUZ Oct 27 07:43:33 musical-fidelity volumio[18226]: info: Stopping AccessToken refresher cron Oct 27 07:43:33 musical-fidelity volumio[18226]: info: AccessToken refresher cron started Oct 27 07:43:33 musical-fidelity volumio[18226]: info: Cannot mount NAS shared at system boot, trial number 2 ,retrying in 5 seconds Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 07:43:33 musical-fidelity volumio[18226]: info: Serial port opened successfully Oct 27 07:43:33 musical-fidelity sudo[18455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart leddaemon.service Oct 27 07:43:33 musical-fidelity sudo[18455]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:33 musical-fidelity systemd[1]: Stopping LED Daemon Service... Oct 27 07:43:33 musical-fidelity systemd[1]: Starting LED Daemon Service... Oct 27 07:43:33 musical-fidelity systemd[1]: Started LED Daemon Service. Oct 27 07:43:33 musical-fidelity sudo[18455]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:33 musical-fidelity led-daemon[18461]: Starting Network LED Daemon Oct 27 07:43:34 musical-fidelity volumio[18226]: info: Setting Device type: Raspberry PI Oct 27 07:43:34 musical-fidelity volumio[18226]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 27 07:43:34 musical-fidelity volumio[18226]: [Metrics] SpotifyConnect: 0s 932.16ms Oct 27 07:43:34 musical-fidelity volumio[18226]: info: Bluetooth name changed to musical-fidelity Oct 27 07:43:34 musical-fidelity volumio[18226]: info: Shairport-Sync Stopped Oct 27 07:43:34 musical-fidelity volumio[18226]: info: MPD running with PID755 Oct 27 07:43:34 musical-fidelity volumio[18226]: ,establishing connection Oct 27 07:43:34 musical-fidelity sudo[18471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 27 07:43:34 musical-fidelity sudo[18471]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:34 musical-fidelity sudo[18471]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:34 musical-fidelity volumio[18226]: info: VolumeController:: Volume=100 Mute =false Oct 27 07:43:34 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:34 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:34 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:34 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:34 musical-fidelity volumio[18226]: info: CoreStateMachine::updateTrackBlock Oct 27 07:43:34 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrackBlock Oct 27 07:43:34 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 07:43:34 musical-fidelity volumio[18226]: info: Volumio called home Oct 27 07:43:35 musical-fidelity volumio[18226]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Oct 27 07:43:35 musical-fidelity volumio[18226]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 27 07:43:35 musical-fidelity volumio[18226]: info: Reloading queue from file Oct 27 07:43:35 musical-fidelity volumio[18226]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Oct 27 07:43:35 musical-fidelity sudo[18489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 27 07:43:35 musical-fidelity sudo[18489]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:35 musical-fidelity volumio[18226]: error: Failed LSINFO: Error: [50@0] {} No such directory Oct 27 07:43:35 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:43:35 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:43:35 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:43:35 musical-fidelity sudo[18489]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreStateMachine::setRepeat null single undefined Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreStateMachine::setRandom null Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:35 musical-fidelity volumio[18226]: info: Raat Daemon started successfully Oct 27 07:43:35 musical-fidelity volumio[18226]: info: Executing endpoint restartRAATSocket Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:43:35 musical-fidelity volumio[18226]: info: VolumeController:: Volume=100 Mute =false Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:35 musical-fidelity systemd[1]: raat-daemon.service: main process exited, code=killed, status=6/ABRT Oct 27 07:43:35 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:43:35 musical-fidelity volumio[18226]: Discovery: adding 439480b0-83e6-4a87-981a-0ce674d419d9 Oct 27 07:43:35 musical-fidelity volumio[18226]: info: mDNS: Found device musical-fidelity Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioGetState Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:35 musical-fidelity volumio[18226]: info: Updating push state because input changed Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumiosetSourceActivempd Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:43:35 musical-fidelity sudo[18517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 07:43:35 musical-fidelity systemd[1]: raat-daemon.service holdoff time over, scheduling restart. Oct 27 07:43:35 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:43:35 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:43:35 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:43:35 musical-fidelity sudo[18517]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:35 musical-fidelity sudo[18517]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:35 musical-fidelity sudo[18527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 07:43:35 musical-fidelity volumio[18226]: Discovery: this is already registered, 439480b0-83e6-4a87-981a-0ce674d419d9 Oct 27 07:43:35 musical-fidelity volumio[18226]: info: mDNS: Found device musical-fidelity Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioGetState Oct 27 07:43:35 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:35 musical-fidelity sudo[18533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 07:43:35 musical-fidelity sudo[18533]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:36 musical-fidelity sudo[18533]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:36 musical-fidelity sudo[18527]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:36 musical-fidelity sudo[18527]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Executing endpoint restartRAATSocket Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Clearing queue after UPNP request Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:36 musical-fidelity systemd[1]: raat-daemon.service: main process exited, code=killed, status=6/ABRT Oct 27 07:43:36 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:43:36 musical-fidelity sudo[18560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:36 musical-fidelity sudo[18560]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:36 musical-fidelity systemd[1]: Started UPnP Renderer front-end to MPD. Oct 27 07:43:36 musical-fidelity sudo[18560]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Upmpdcli Daemon Started Oct 27 07:43:36 musical-fidelity systemd[1]: raat-daemon.service holdoff time over, scheduling restart. Oct 27 07:43:36 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:43:36 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:43:36 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::ClearQueue Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::stop Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 07:43:36 musical-fidelity volumio[18226]: verbose: UNSET VOLATILE Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::clearPlayQueue Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::saveQueue Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushQueue Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Updating RAAT Signal Path Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Executing endpoint restartRAATSocket Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Oct 27 07:43:36 musical-fidelity volumio[18226]: ---------------------------- MPD announces system playlist update Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Ignoring MPD Status Update Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Oct 27 07:43:36 musical-fidelity volumio[18226]: ---------------------------- MPD announces state update: player Oct 27 07:43:36 musical-fidelity volumio[18226]: info: ControllerMpd::getState Oct 27 07:43:36 musical-fidelity volumio[18226]: verbose: ControllerMpd::sendMpdCommand status Oct 27 07:43:36 musical-fidelity volumio[18226]: error: updateQueue error: null Oct 27 07:43:36 musical-fidelity volumio[18226]: info: ------------------------------ 11ms Oct 27 07:43:36 musical-fidelity volumio-streaming-daemon[18411]: Checking variant in static cache Oct 27 07:43:36 musical-fidelity volumio[18226]: info: sendMpdCommand status took 11 milliseconds Oct 27 07:43:36 musical-fidelity volumio[18226]: verbose: ControllerMpd::parseState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: ControllerMpd::pushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::servicePushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:36 musical-fidelity volumio[18226]: 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:43:36 musical-fidelity volumio[18226]: verbose: CURRENT POSITION 0 Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::syncState stateService stop Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::syncState currentStatus stop Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:36 musical-fidelity volumio[18226]: info: No code Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:36 musical-fidelity volumio[18226]: info: ------------------------------ 46ms Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Starting UPNP Playback Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Preparing playback through UPNP Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioGetState Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Updating RAAT Signal Path Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Updating RAAT Signal Path Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Updating RAAT Signal Path Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Fetching Streaming Services browse cache Oct 27 07:43:36 musical-fidelity systemd[1]: raat-daemon.service: main process exited, code=killed, status=6/ABRT Oct 27 07:43:36 musical-fidelity systemd[1]: Unit raat-daemon.service entered failed state. Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:36 musical-fidelity systemd[1]: raat-daemon.service holdoff time over, scheduling restart. Oct 27 07:43:36 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Oct 27 07:43:36 musical-fidelity systemd[1]: Starting RAAT DAEMON... Oct 27 07:43:36 musical-fidelity systemd[1]: Started RAAT DAEMON. Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:43:36 musical-fidelity volumio[18226]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Oct 27 07:43:36 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 27 07:43:36 musical-fidelity volumio[18226]: info: Setting Additional System Software info: Hardware Firmware Version: 117 Hardware Revision: 1 Oct 27 07:43:36 musical-fidelity sudo[18591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 27 07:43:36 musical-fidelity sudo[18591]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Executing endpoint restartRAATSocket Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 07:43:37 musical-fidelity sudo[18591]: pam_unix(sudo:session): session closed for user root Oct 27 07:43:37 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:37 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:37 musical-fidelity pulseaudio[18606]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 27 07:43:37 musical-fidelity pulseaudio[18606]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 27 07:43:37 musical-fidelity pulseaudio[18606]: 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:43:37 musical-fidelity bluetoothd[712]: Endpoint registered: sender=:1.138 path=/MediaEndpoint/A2DPSource Oct 27 07:43:37 musical-fidelity bluetoothd[712]: Endpoint registered: sender=:1.138 path=/MediaEndpoint/A2DPSink Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Oct 27 07:43:37 musical-fidelity volumio[18226]: ---------------------------- MPD announces system playlist update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Ignoring MPD Status Update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: ------------------------------ 10ms Oct 27 07:43:37 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Oct 27 07:43:37 musical-fidelity volumio[18226]: ---------------------------- MPD announces system playlist update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Ignoring MPD Status Update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: ------------------------------ 18ms Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioGetState Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioGetQueue Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreStateMachine::getQueue Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CorePlayQueue::getQueue Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Listing playlists Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Oct 27 07:43:37 musical-fidelity volumio[18226]: ---------------------------- MPD announces system playlist update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Ignoring MPD Status Update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: ------------------------------ 6ms Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Oct 27 07:43:37 musical-fidelity volumio[18226]: ---------------------------- MPD announces system playlist update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Ignoring MPD Status Update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Oct 27 07:43:37 musical-fidelity volumio[18226]: ---------------------------- MPD announces system playlist update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Ignoring MPD Status Update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: ------------------------------ 9ms Oct 27 07:43:37 musical-fidelity volumio[18226]: info: ------------------------------ 8ms Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Oct 27 07:43:37 musical-fidelity volumio[18226]: ---------------------------- MPD announces system playlist update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Ignoring MPD Status Update Oct 27 07:43:37 musical-fidelity volumio[18226]: info: ------------------------------ 4ms Oct 27 07:43:37 musical-fidelity volumio[18226]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Oct 27 07:43:37 musical-fidelity volumio[18226]: [VRCP] RAAT stopped playing Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::servicePushState Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CorePlayQueue::getTrack 0 Oct 27 07:43:37 musical-fidelity volumio[18226]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received raat Oct 27 07:43:37 musical-fidelity volumio[18226]: info: Updating RAAT Signal Path Oct 27 07:43:38 musical-fidelity volumio[18226]: info: Checking Spotify Web API Oct 27 07:43:38 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:43:38] [connect] Successful connection Oct 27 07:43:38 musical-fidelity volumio-remote-updater[722]: [2025-10-27 07:43:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1761551018 101 Oct 27 07:43:38 musical-fidelity volumio[18226]: info: Cannot mount NAS shared at system boot, trial number 3 ,retrying in 5 seconds Oct 27 07:43:39 musical-fidelity volumio[18226]: info: Oct 27 07:43:39 musical-fidelity volumio[18226]: ---------------------------- MPD announces state update: player Oct 27 07:43:39 musical-fidelity volumio[18226]: info: ControllerMpd::getState Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: ControllerMpd::sendMpdCommand status Oct 27 07:43:39 musical-fidelity volumio[18226]: info: Oct 27 07:43:39 musical-fidelity volumio[18226]: ---------------------------- MPD announces state update: player Oct 27 07:43:39 musical-fidelity volumio[18226]: info: ControllerMpd::getState Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: ControllerMpd::sendMpdCommand status Oct 27 07:43:39 musical-fidelity volumio[18226]: info: sendMpdCommand status took 6 milliseconds Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: ControllerMpd::parseState Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 07:43:39 musical-fidelity volumio[18226]: info: sendMpdCommand status took 8 milliseconds Oct 27 07:43:39 musical-fidelity volumio[18226]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: ControllerMpd::parseState Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: ControllerMpd::parseTrackInfo Oct 27 07:43:39 musical-fidelity volumio[18226]: info: ControllerMpd::pushState Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::servicePushState Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: In UPNP mode Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Les mots","artist":"Mylène Farmer","album":"Histoires de","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=2443280&eid=105731339&fmt=6&profile=raw&app_id=195822208&cid=3113628&etsp=1761554609&hmac=6sYAOEKvFJ_zkFQ5-guT00HKpLc","trackType":"qobuz"} Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: CURRENT POSITION 0 Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreStateMachine::syncState stateService play Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreStateMachine::syncState currentStatus stop Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:39 musical-fidelity volumio[18226]: info: ------------------------------ 30ms Oct 27 07:43:39 musical-fidelity volumio[18226]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: ControllerMpd::parseTrackInfo Oct 27 07:43:39 musical-fidelity volumio[18226]: info: ControllerMpd::pushState Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::servicePushState Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: In UPNP mode Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: STATE SERVICE {"status":"play","position":0,"seek":92,"duration":289,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Les mots","artist":"Mylène Farmer","album":"Histoires de","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=2443280&eid=105731339&fmt=6&profile=raw&app_id=195822208&cid=3113628&etsp=1761554609&hmac=6sYAOEKvFJ_zkFQ5-guT00HKpLc","trackType":"qobuz"} Oct 27 07:43:39 musical-fidelity volumio[18226]: verbose: CURRENT POSITION 0 Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreStateMachine::syncState stateService play Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreStateMachine::syncState currentStatus play Oct 27 07:43:39 musical-fidelity volumio[18226]: info: Received an update from plugin. extracting info from payload Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreStateMachine::pushState Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::volumioPushState Oct 27 07:43:39 musical-fidelity volumio[18226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:43:39 musical-fidelity volumio[18226]: info: ------------------------------ 73ms Oct 27 07:43:39 musical-fidelity volumio[18226]: info: Updating RAAT Signal Path Oct 27 07:43:39 musical-fidelity volumio[18226]: info: Updating RAAT Signal Path Oct 27 07:43:39 musical-fidelity volumio[18226]: info: Updating RAAT Signal Path Oct 27 07:43:40 musical-fidelity volumio[18226]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:43:40 musical-fidelity volumio[18226]: { Error: connect ECONNREFUSED 124.11.210.175:443 Oct 27 07:43:40 musical-fidelity volumio[18226]: at Object._errnoException (util.js:1022:11) Oct 27 07:43:40 musical-fidelity volumio[18226]: at _exceptionWithHostPort (util.js:1044:20) Oct 27 07:43:40 musical-fidelity volumio[18226]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 27 07:43:40 musical-fidelity volumio[18226]: code: 'ECONNREFUSED', Oct 27 07:43:40 musical-fidelity volumio[18226]: errno: 'ECONNREFUSED', Oct 27 07:43:40 musical-fidelity volumio[18226]: syscall: 'connect', Oct 27 07:43:40 musical-fidelity volumio[18226]: address: '124.11.210.175', Oct 27 07:43:40 musical-fidelity volumio[18226]: port: 443 } Oct 27 07:43:40 musical-fidelity volumio[18226]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:43:41 musical-fidelity sudo[18655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-27 07:42 Oct 27 07:43:41 musical-fidelity sudo[18655]: 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"