-- Logs begin at Sat 2025-09-06 05:54:48 UTC, end at Sat 2025-09-06 06:30:35 UTC. -- Sep 06 06:29:00 volumio volumio[5754]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 06:29:00 volumio volumio[5754]: { Error: connect ETIMEDOUT 192.133.77.59:443 Sep 06 06:29:00 volumio volumio[5754]: at Object._errnoException (util.js:1022:11) Sep 06 06:29:00 volumio volumio[5754]: at _exceptionWithHostPort (util.js:1044:20) Sep 06 06:29:00 volumio volumio[5754]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 06 06:29:00 volumio volumio[5754]: code: 'ETIMEDOUT', Sep 06 06:29:00 volumio volumio[5754]: errno: 'ETIMEDOUT', Sep 06 06:29:00 volumio volumio[5754]: syscall: 'connect', Sep 06 06:29:00 volumio volumio[5754]: address: '192.133.77.59', Sep 06 06:29:00 volumio volumio[5754]: port: 443 } Sep 06 06:29:00 volumio volumio[5754]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 06:29:01 volumio sudo[6078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-06 06:28 Sep 06 06:29:01 volumio sudo[6078]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:01 volumio sudo[6078]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:02 volumio volumio-remote-updater[723]: [2025-09-06 06:29:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 06 06:29:02 volumio volumio-remote-updater[723]: [2025-09-06 06:29:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 06 06:29:02 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Sep 06 06:29:02 volumio systemd[1]: Unit volumio.service entered failed state. Sep 06 06:29:02 volumio systemd[1]: Starting dynamicswap service... Sep 06 06:29:02 volumio systemd[1]: Started dynamicswap service. Sep 06 06:29:02 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Sep 06 06:29:02 volumio systemd[1]: Starting dynamicswap service... Sep 06 06:29:02 volumio systemd[1]: Started dynamicswap service. Sep 06 06:29:02 volumio systemd[1]: Stopping Volumio Backend Module... Sep 06 06:29:02 volumio systemd[1]: Starting Volumio Backend Module... Sep 06 06:29:02 volumio systemd[1]: Started Volumio Backend Module. Sep 06 06:29:04 volumio volumio[6102]: info: ------------------------------------------- Sep 06 06:29:04 volumio volumio[6102]: info: ----- Volumio2 ---- Sep 06 06:29:04 volumio volumio[6102]: info: ------------------------------------------- Sep 06 06:29:04 volumio volumio[6102]: info: ----- System startup ---- Sep 06 06:29:04 volumio volumio[6102]: info: ------------------------------------------- Sep 06 06:29:04 volumio volumio[6102]: info: MYVOLUMIO Environment detected Sep 06 06:29:06 volumio volumio[6102]: info: Plugin folders cleanup Sep 06 06:29:06 volumio volumio[6102]: info: Scanning into folder /volumio/app/plugins/ Sep 06 06:29:06 volumio volumio[6102]: info: Scanning category audio_interface Sep 06 06:29:06 volumio volumio[6102]: info: Scanning category miscellanea Sep 06 06:29:06 volumio volumio[6102]: info: Scanning category music_service Sep 06 06:29:06 volumio volumio[6102]: info: Scanning category plugins.json Sep 06 06:29:06 volumio volumio[6102]: info: Scanning category system_controller Sep 06 06:29:06 volumio volumio[6102]: info: Scanning category user_interface Sep 06 06:29:06 volumio volumio[6102]: info: Scanning into folder /data/plugins/ Sep 06 06:29:06 volumio volumio[6102]: info: Plugin folders cleanup completed Sep 06 06:29:06 volumio volumio[6102]: info: ------------------------------------------- Sep 06 06:29:06 volumio volumio[6102]: info: ----- Core plugins startup ---- Sep 06 06:29:06 volumio volumio[6102]: info: ------------------------------------------- Sep 06 06:29:06 volumio volumio[6102]: info: Loading plugins from folder /volumio/app/plugins/ Sep 06 06:29:06 volumio volumio[6102]: info: Adding plugin upnp to MyMusic Plugins Sep 06 06:29:06 volumio volumio[6102]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 06 06:29:06 volumio volumio[6102]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 06 06:29:06 volumio volumio[6102]: info: Loading plugins from folder /data/plugins/ Sep 06 06:29:06 volumio volumio[6102]: info: Loading plugin "system"... Sep 06 06:29:06 volumio volumio[6102]: info: Loading plugin "appearance"... Sep 06 06:29:07 volumio volumio-remote-updater[723]: [2025-09-06 06:29:07] [connect] Successful connection Sep 06 06:29:07 volumio volumio[6102]: info: Loading plugin "network"... Sep 06 06:29:07 volumio volumio[6102]: info: Refreshing Cached IP Addresses Sep 06 06:29:07 volumio volumio[6102]: info: Loading plugin "services"... Sep 06 06:29:07 volumio sudo[6129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 06:29:07 volumio volumio[6102]: info: Loading plugin "alsa_controller"... Sep 06 06:29:07 volumio sudo[6129]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:07 volumio sudo[6131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 06:29:07 volumio sudo[6129]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:07 volumio sudo[6131]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:07 volumio sudo[6131]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:07 volumio volumio[6102]: info: Loading plugin "wizard"... Sep 06 06:29:07 volumio volumio[6102]: info: Loading plugin "volumio_command_line_client"... Sep 06 06:29:07 volumio volumio[6102]: info: Loading plugin "upnp"... Sep 06 06:29:07 volumio volumio[6102]: info: [1757140147672] Starting Upmpd Daemon Sep 06 06:29:07 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 06:29:07 volumio volumio[6102]: info: Loading plugin "my_music"... Sep 06 06:29:07 volumio volumio[6102]: info: Loading plugin "mpd"... Sep 06 06:29:08 volumio volumio[6102]: info: Loading plugin "upnp_browser"... Sep 06 06:29:08 volumio volumio[6102]: info: Loading plugin "networkfs"... Sep 06 06:29:08 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 06:29:08 volumio volumio[6102]: info: Loading plugin "alarm-clock"... Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "airplay_emulation"... Sep 06 06:29:09 volumio volumio[6102]: info: Starting Shairport Sync Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "last_100"... Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "webradio"... Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "i2s_dacs"... Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "volumiodiscovery"... Sep 06 06:29:09 volumio volumio[6102]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 06 06:29:09 volumio node[6102]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 06 06:29:09 volumio node[6102]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 06:29:09 volumio node[6102]: *** WARNING *** For more information see Sep 06 06:29:09 volumio node[6102]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 06 06:29:09 volumio node[6102]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 06:29:09 volumio node[6102]: *** WARNING *** For more information see Sep 06 06:29:09 volumio volumio[6102]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 06:29:09 volumio volumio[6102]: *** WARNING *** For more information see Sep 06 06:29:09 volumio volumio[6102]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 06 06:29:09 volumio volumio[6102]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 06:29:09 volumio volumio[6102]: *** WARNING *** For more information see Sep 06 06:29:09 volumio volumio[6102]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 06 06:29:09 volumio volumio[6102]: Discovery: StartAdv! undefined Sep 06 06:29:09 volumio volumio[6102]: Discovery: Started advertising... Volumio - undefined Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "outputs"... Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "albumart"... Sep 06 06:29:09 volumio volumio[6102]: info: Plugin example_plugin is not enabled Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "inputs"... Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "updater_comm"... Sep 06 06:29:09 volumio volumio[6102]: info: Plugin mpdemulation is not enabled Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "rest_api"... Sep 06 06:29:09 volumio volumio[6102]: info: Loading plugin "websocket"... Sep 06 06:29:09 volumio volumio[6102]: info: ___________ START PLUGINS ___________ Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 06:29:09 volumio volumio[6102]: info: [1757140149712] CoreMusicLibrary::Adding element Media Servers Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:09 volumio volumio[6102]: Forking 3 albumart workers Sep 06 06:29:09 volumio volumio[6102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 06:29:09 volumio volumio[6102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 06:29:09 volumio volumio[6102]: info: [1757140149885] CoreMusicLibrary::Adding element Last_100 Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 06:29:09 volumio volumio[6102]: info: [1757140149890] CoreMusicLibrary::Adding element Webradio Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 06:29:09 volumio volumio[6102]: info: ------------------------------------------- Sep 06 06:29:09 volumio volumio[6102]: info: ----- MyVolumio plugins startup ---- Sep 06 06:29:09 volumio volumio[6102]: info: ------------------------------------------- Sep 06 06:29:09 volumio volumio[6102]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 06 06:29:09 volumio volumio[6102]: info: Loading i18n strings for locale zh Sep 06 06:29:09 volumio volumio[6102]: Updating browse sources language Sep 06 06:29:09 volumio volumio[6102]: Cannot find translation for sourceMedia Servers Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::initPlayerControls Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:09 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 06:29:10 volumio volumio[6102]: info: BOOT COMPLETED Sep 06 06:29:10 volumio volumio[6102]: [Metrics] CommandRouter: 5s 219.03ms Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:29:10 volumio volumio[6102]: aplay: main:722: audio open error: Device or resource busy Sep 06 06:29:10 volumio volumio[6102]: Cannot play startup sound Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::Close All Modals sent Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::Close All Modals sent Sep 06 06:29:10 volumio volumio[6102]: Express server listening on port 3000 Sep 06 06:29:10 volumio volumio[6102]: [Metrics] WebUI: 6s 95.00ms Sep 06 06:29:10 volumio volumio[6102]: Volumio Calling Home Sep 06 06:29:10 volumio volumio[6102]: info: CoreStateMachine::resetVolumioState Sep 06 06:29:10 volumio volumio[6102]: info: CoreStateMachine::getcurrentVolume Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 06:29:10 volumio volumio[6102]: info: CoreStateMachine::pushState Sep 06 06:29:10 volumio volumio[6102]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::volumioPushState Sep 06 06:29:10 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 06:29:10 volumio volumio[6102]: info: Setting Device type: Raspberry PI Sep 06 06:29:10 volumio volumio[6102]: info: MPD running with PID793 Sep 06 06:29:10 volumio volumio[6102]: ,establishing connection Sep 06 06:29:11 volumio volumio-remote-updater[723]: [2025-09-06 06:29:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1757140147 101 Sep 06 06:29:11 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 06 06:29:11 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 06:29:11 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 06 06:29:11 volumio volumio[6102]: Starting albumart workers Sep 06 06:29:11 volumio volumio[6102]: Starting albumart workers Sep 06 06:29:11 volumio volumio[6102]: Starting albumart workers Sep 06 06:29:13 volumio volumio[6102]: info: Reloading queue from file Sep 06 06:29:13 volumio volumio[6102]: info: CoreStateMachine::setRepeat true single undefined Sep 06 06:29:13 volumio volumio[6102]: info: CoreStateMachine::pushState Sep 06 06:29:13 volumio volumio[6102]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:13 volumio volumio[6102]: info: CoreCommandRouter::volumioPushState Sep 06 06:29:13 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 06:29:13 volumio volumio[6102]: info: CoreStateMachine::setRandom false Sep 06 06:29:13 volumio volumio[6102]: info: CoreStateMachine::pushState Sep 06 06:29:13 volumio volumio[6102]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:13 volumio volumio[6102]: info: CoreCommandRouter::volumioPushState Sep 06 06:29:13 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 06:29:13 volumio volumio[6102]: info: Starting Shairport Sync Sep 06 06:29:13 volumio volumio[6102]: info: Starting Shairport Sync Sep 06 06:29:13 volumio sudo[6221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 06:29:13 volumio sudo[6223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 06:29:13 volumio sudo[6221]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 06:29:13 volumio sudo[6223]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:13 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 06 06:29:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 06:29:13 volumio sudo[6221]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 06:29:13 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 06 06:29:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 06:29:13 volumio sudo[6223]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:13 volumio volumio[6102]: info: CoreCommandRouter::volumioGetState Sep 06 06:29:13 volumio volumio[6102]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:14 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:29:14 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:14 volumio volumio[6102]: Discovery: adding cbbd0678-4031-449f-b79c-6121b805b13d Sep 06 06:29:14 volumio volumio[6102]: info: mDNS: Found device Volumio Sep 06 06:29:14 volumio volumio[6102]: info: CoreCommandRouter::volumioGetState Sep 06 06:29:14 volumio volumio[6102]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:14 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 06 06:29:14 volumio volumio[6102]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 06:29:14 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:14 volumio volumio[6102]: info: Listing playlists Sep 06 06:29:14 volumio volumio[6102]: info: CoreCommandRouter::volumioGetQueue Sep 06 06:29:14 volumio volumio[6102]: info: CoreStateMachine::getQueue Sep 06 06:29:14 volumio volumio[6102]: info: CorePlayQueue::getQueue Sep 06 06:29:15 volumio volumio[6102]: Cannot compose Albumart path Sep 06 06:29:15 volumio volumio[6102]: info: Shairport-Sync Started Sep 06 06:29:15 volumio volumio[6102]: Error adding Membership: Error: addMembership EINVAL Sep 06 06:29:15 volumio volumio[6102]: info: Shairport-Sync Started Sep 06 06:29:15 volumio volumio[6102]: Discovery: this is already registered, cbbd0678-4031-449f-b79c-6121b805b13d Sep 06 06:29:15 volumio volumio[6102]: info: mDNS: Found device Volumio Sep 06 06:29:15 volumio volumio[6102]: info: CoreCommandRouter::volumioGetState Sep 06 06:29:15 volumio volumio[6102]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:16 volumio volumio[6102]: info: CoreCommandRouter::volumioGetState Sep 06 06:29:16 volumio volumio[6102]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:17 volumio sudo[6256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 06:29:17 volumio sudo[6256]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:17 volumio sudo[6259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 06:29:17 volumio sudo[6256]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:17 volumio sudo[6259]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:17 volumio sudo[6259]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:17 volumio volumio[6102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%94%A1%E7%90%B4/%E6%B0%91%E6%AD%8C%E8%94%A1%E7%90%B4/677c4b89-145e-4523-b045-1615a42b7473.png' Sep 06 06:29:18 volumio sudo[6274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 06 06:29:18 volumio sudo[6274]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:18 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 06 06:29:18 volumio sudo[6274]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:18 volumio volumio[6102]: info: Upmpdcli Daemon Started Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 06 06:29:19 volumio volumio[6102]: info: Adding plugin bluetooth to MyMusic Plugins Sep 06 06:29:19 volumio volumio[6102]: info: Adding plugin cd_controller to MyMusic Plugins Sep 06 06:29:19 volumio volumio[6102]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 06 06:29:19 volumio volumio[6102]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 06 06:29:21 volumio volumio[6102]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 06 06:29:22 volumio volumio[6102]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 06 06:29:22 volumio volumio[6102]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 06 06:29:22 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:29:22 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:29:22 volumio volumio[6102]: info: Starting MyVolumio Remote Streaming Endpoints Sep 06 06:29:22 volumio volumio[6102]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 06 06:29:22 volumio volumio[6102]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 06 06:29:22 volumio volumio[6102]: info: Streaming services startup Sep 06 06:29:22 volumio volumio[6102]: info: Starting Streaming Daemon Sep 06 06:29:22 volumio volumio[6102]: info: MyVolumio not started Sep 06 06:29:22 volumio volumio[6102]: info: Initializing device activation check Sep 06 06:29:22 volumio sudo[6285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 06 06:29:22 volumio sudo[6285]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:22 volumio volumio[6102]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 06 06:29:22 volumio systemd[1]: Stopping Volumio Streaming Daemon... Sep 06 06:29:22 volumio systemd[1]: Starting Volumio Streaming Daemon... Sep 06 06:29:22 volumio sudo[6285]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:22 volumio systemd[1]: Started Volumio Streaming Daemon. Sep 06 06:29:22 volumio volumio[6102]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=834b38077ff486866b9de335cefca4be" http://updates.volumio.org:7070/downloader-v1/track-device Sep 06 06:29:22 volumio volumio[6102]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 06 06:29:22 volumio volumio[6102]: Dload Upload Total Spent Left Speed Sep 06 06:29:22 volumio volumio[6102]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 06 06:29:22 volumio volumio[6102]: retrying in 5 seconds, trial 0 Sep 06 06:29:22 volumio volumio-streaming-daemon[6291]: ############################ Sep 06 06:29:22 volumio volumio-streaming-daemon[6291]: # Volumio Streaming Daemon # Sep 06 06:29:22 volumio volumio-streaming-daemon[6291]: # Running on port 7777 # Sep 06 06:29:22 volumio volumio-streaming-daemon[6291]: ############################ Sep 06 06:29:22 volumio volumio[6102]: Volumio Calling Home Sep 06 06:29:22 volumio volumio-streaming-daemon[6291]: Environment: production Sep 06 06:29:25 volumio volumio-streaming-daemon[6291]: Environment: production Sep 06 06:29:25 volumio volumio[6102]: info: Fetching Streaming Services browse cache Sep 06 06:29:25 volumio volumio[6102]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Sep 06 06:29:25 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:25 volumio volumio[6102]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Sep 06 06:29:25 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:26 volumio volumio[6102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Mariah%20Carey/Greatest%20Hits/bf780191-c754-404c-ac95-5396b41c7be8.png' Sep 06 06:29:32 volumio volumio[6102]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=834b38077ff486866b9de335cefca4be" http://updates.volumio.org:7070/downloader-v1/track-device Sep 06 06:29:32 volumio volumio[6102]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 06 06:29:32 volumio volumio[6102]: Dload Upload Total Spent Left Speed Sep 06 06:29:32 volumio volumio[6102]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 06 06:29:32 volumio volumio[6102]: retrying in 5 seconds, trial 1 Sep 06 06:29:32 volumio volumio[6102]: Volumio Calling Home Sep 06 06:29:33 volumio volumio[6102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2015%20GRAMMY%20Nominees/868fad4e-1e11-48a3-bebf-6eca96f18203.png' Sep 06 06:29:39 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 06:29:39 volumio volumio[6102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:29:43 volumio volumio[6102]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=834b38077ff486866b9de335cefca4be" http://updates.volumio.org:7070/downloader-v1/track-device Sep 06 06:29:43 volumio volumio[6102]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 06 06:29:43 volumio volumio[6102]: Dload Upload Total Spent Left Speed Sep 06 06:29:43 volumio volumio[6102]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 06 06:29:43 volumio volumio[6102]: retrying in 5 seconds, trial 2 Sep 06 06:29:43 volumio volumio[6102]: Volumio Calling Home Sep 06 06:29:47 volumio volumio[6102]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 06:29:47 volumio volumio[6102]: { Error: connect ETIMEDOUT 192.133.77.59:443 Sep 06 06:29:47 volumio volumio[6102]: at Object._errnoException (util.js:1022:11) Sep 06 06:29:47 volumio volumio[6102]: at _exceptionWithHostPort (util.js:1044:20) Sep 06 06:29:47 volumio volumio[6102]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 06 06:29:47 volumio volumio[6102]: code: 'ETIMEDOUT', Sep 06 06:29:47 volumio volumio[6102]: errno: 'ETIMEDOUT', Sep 06 06:29:47 volumio volumio[6102]: syscall: 'connect', Sep 06 06:29:47 volumio volumio[6102]: address: '192.133.77.59', Sep 06 06:29:47 volumio volumio[6102]: port: 443 } Sep 06 06:29:47 volumio volumio[6102]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 06:29:48 volumio sudo[6367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-06 06:28 Sep 06 06:29:48 volumio sudo[6367]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:48 volumio sudo[6367]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:48 volumio volumio-remote-updater[723]: [2025-09-06 06:29:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 06 06:29:48 volumio volumio-remote-updater[723]: [2025-09-06 06:29:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 06 06:29:48 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Sep 06 06:29:48 volumio systemd[1]: Unit volumio.service entered failed state. Sep 06 06:29:48 volumio systemd[1]: Starting dynamicswap service... Sep 06 06:29:48 volumio systemd[1]: Started dynamicswap service. Sep 06 06:29:48 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Sep 06 06:29:48 volumio systemd[1]: Starting dynamicswap service... Sep 06 06:29:48 volumio systemd[1]: Started dynamicswap service. Sep 06 06:29:48 volumio systemd[1]: Stopping Volumio Backend Module... Sep 06 06:29:48 volumio systemd[1]: Starting Volumio Backend Module... Sep 06 06:29:48 volumio systemd[1]: Started Volumio Backend Module. Sep 06 06:29:50 volumio volumio[6388]: info: ------------------------------------------- Sep 06 06:29:50 volumio volumio[6388]: info: ----- Volumio2 ---- Sep 06 06:29:50 volumio volumio[6388]: info: ------------------------------------------- Sep 06 06:29:50 volumio volumio[6388]: info: ----- System startup ---- Sep 06 06:29:50 volumio volumio[6388]: info: ------------------------------------------- Sep 06 06:29:50 volumio volumio[6388]: info: MYVOLUMIO Environment detected Sep 06 06:29:51 volumio volumio[6388]: info: Plugin folders cleanup Sep 06 06:29:51 volumio volumio[6388]: info: Scanning into folder /volumio/app/plugins/ Sep 06 06:29:51 volumio volumio[6388]: info: Scanning category audio_interface Sep 06 06:29:51 volumio volumio[6388]: info: Scanning category miscellanea Sep 06 06:29:51 volumio volumio[6388]: info: Scanning category music_service Sep 06 06:29:51 volumio volumio[6388]: info: Scanning category plugins.json Sep 06 06:29:51 volumio volumio[6388]: info: Scanning category system_controller Sep 06 06:29:51 volumio volumio[6388]: info: Scanning category user_interface Sep 06 06:29:51 volumio volumio[6388]: info: Scanning into folder /data/plugins/ Sep 06 06:29:51 volumio volumio[6388]: info: Plugin folders cleanup completed Sep 06 06:29:51 volumio volumio[6388]: info: ------------------------------------------- Sep 06 06:29:51 volumio volumio[6388]: info: ----- Core plugins startup ---- Sep 06 06:29:51 volumio volumio[6388]: info: ------------------------------------------- Sep 06 06:29:51 volumio volumio[6388]: info: Loading plugins from folder /volumio/app/plugins/ Sep 06 06:29:51 volumio volumio[6388]: info: Adding plugin upnp to MyMusic Plugins Sep 06 06:29:51 volumio volumio[6388]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 06 06:29:51 volumio volumio[6388]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 06 06:29:51 volumio volumio[6388]: info: Loading plugins from folder /data/plugins/ Sep 06 06:29:51 volumio volumio[6388]: info: Loading plugin "system"... Sep 06 06:29:51 volumio volumio[6388]: info: Loading plugin "appearance"... Sep 06 06:29:52 volumio volumio[6388]: info: Loading plugin "network"... Sep 06 06:29:52 volumio volumio[6388]: info: Refreshing Cached IP Addresses Sep 06 06:29:52 volumio sudo[6413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 06:29:52 volumio volumio[6388]: info: Loading plugin "services"... Sep 06 06:29:52 volumio volumio[6388]: info: Loading plugin "alsa_controller"... Sep 06 06:29:52 volumio sudo[6413]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:52 volumio sudo[6413]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:52 volumio sudo[6416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 06:29:52 volumio sudo[6416]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:52 volumio sudo[6416]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:53 volumio volumio[6388]: info: Loading plugin "wizard"... Sep 06 06:29:53 volumio volumio[6388]: info: Loading plugin "volumio_command_line_client"... Sep 06 06:29:53 volumio volumio[6388]: info: Loading plugin "upnp"... Sep 06 06:29:53 volumio volumio[6388]: info: [1757140193102] Starting Upmpd Daemon Sep 06 06:29:53 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 06:29:53 volumio volumio[6388]: info: Loading plugin "my_music"... Sep 06 06:29:53 volumio volumio[6388]: info: Loading plugin "mpd"... Sep 06 06:29:53 volumio volumio-remote-updater[723]: [2025-09-06 06:29:53] [connect] Successful connection Sep 06 06:29:53 volumio volumio[6388]: info: Loading plugin "upnp_browser"... Sep 06 06:29:53 volumio volumio[6388]: info: Loading plugin "networkfs"... Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "alarm-clock"... Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "airplay_emulation"... Sep 06 06:29:54 volumio volumio[6388]: info: Starting Shairport Sync Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "last_100"... Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "webradio"... Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "i2s_dacs"... Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "volumiodiscovery"... Sep 06 06:29:54 volumio volumio[6388]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 06 06:29:54 volumio node[6388]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 06 06:29:54 volumio node[6388]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 06:29:54 volumio node[6388]: *** WARNING *** For more information see Sep 06 06:29:54 volumio node[6388]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 06 06:29:54 volumio node[6388]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 06:29:54 volumio node[6388]: *** WARNING *** For more information see Sep 06 06:29:54 volumio volumio[6388]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 06:29:54 volumio volumio[6388]: *** WARNING *** For more information see Sep 06 06:29:54 volumio volumio[6388]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 06 06:29:54 volumio volumio[6388]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 06:29:54 volumio volumio[6388]: *** WARNING *** For more information see Sep 06 06:29:54 volumio volumio[6388]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 06 06:29:54 volumio volumio[6388]: Discovery: StartAdv! undefined Sep 06 06:29:54 volumio volumio[6388]: Discovery: Started advertising... Volumio - undefined Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "outputs"... Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "albumart"... Sep 06 06:29:54 volumio volumio[6388]: info: Plugin example_plugin is not enabled Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "inputs"... Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "updater_comm"... Sep 06 06:29:54 volumio volumio[6388]: info: Plugin mpdemulation is not enabled Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "rest_api"... Sep 06 06:29:54 volumio volumio[6388]: info: Loading plugin "websocket"... Sep 06 06:29:54 volumio volumio[6388]: info: ___________ START PLUGINS ___________ Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 06:29:54 volumio volumio[6388]: info: [1757140194828] CoreMusicLibrary::Adding element Media Servers Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:54 volumio volumio[6388]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 06:29:54 volumio volumio[6388]: Forking 3 albumart workers Sep 06 06:29:54 volumio volumio[6388]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 06:29:54 volumio volumio[6388]: info: [1757140194963] CoreMusicLibrary::Adding element Last_100 Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 06:29:54 volumio volumio[6388]: info: [1757140194970] CoreMusicLibrary::Adding element Webradio Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:54 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 06:29:54 volumio volumio[6388]: info: ------------------------------------------- Sep 06 06:29:54 volumio volumio[6388]: info: ----- MyVolumio plugins startup ---- Sep 06 06:29:54 volumio volumio[6388]: info: ------------------------------------------- Sep 06 06:29:54 volumio volumio[6388]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 06 06:29:55 volumio volumio[6388]: info: Loading i18n strings for locale zh Sep 06 06:29:55 volumio volumio[6388]: Updating browse sources language Sep 06 06:29:55 volumio volumio[6388]: Cannot find translation for sourceMedia Servers Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::initPlayerControls Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: info: BOOT COMPLETED Sep 06 06:29:55 volumio volumio[6388]: [Metrics] CommandRouter: 4s 326.30ms Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:29:55 volumio volumio[6388]: aplay: main:722: audio open error: Device or resource busy Sep 06 06:29:55 volumio volumio[6388]: Cannot play startup sound Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::Close All Modals sent Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::Close All Modals sent Sep 06 06:29:55 volumio volumio[6388]: Express server listening on port 3000 Sep 06 06:29:55 volumio volumio[6388]: [Metrics] WebUI: 5s 3.26ms Sep 06 06:29:55 volumio volumio[6388]: Volumio Calling Home Sep 06 06:29:55 volumio volumio[6388]: info: CoreStateMachine::resetVolumioState Sep 06 06:29:55 volumio volumio[6388]: info: CoreStateMachine::getcurrentVolume Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 06:29:55 volumio volumio[6388]: info: CoreStateMachine::pushState Sep 06 06:29:55 volumio volumio[6388]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::volumioPushState Sep 06 06:29:55 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 06:29:55 volumio volumio[6388]: info: Setting Device type: Raspberry PI Sep 06 06:29:55 volumio volumio[6388]: info: MPD running with PID793 Sep 06 06:29:55 volumio volumio[6388]: ,establishing connection Sep 06 06:29:55 volumio kernel: rpi_firmware_get_throttled: 4 callbacks suppressed Sep 06 06:29:55 volumio kernel: Under-voltage detected! (0x00050005) Sep 06 06:29:56 volumio volumio-remote-updater[723]: [2025-09-06 06:29:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1757140193 101 Sep 06 06:29:56 volumio volumio[6388]: Starting albumart workers Sep 06 06:29:56 volumio volumio[6388]: Starting albumart workers Sep 06 06:29:56 volumio volumio[6388]: Starting albumart workers Sep 06 06:29:57 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 06 06:29:57 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 06:29:57 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 06 06:29:57 volumio volumio[6388]: info: Reloading queue from file Sep 06 06:29:57 volumio volumio[6388]: info: CoreStateMachine::setRepeat true single undefined Sep 06 06:29:57 volumio volumio[6388]: info: CoreStateMachine::pushState Sep 06 06:29:57 volumio volumio[6388]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:57 volumio volumio[6388]: info: CoreCommandRouter::volumioPushState Sep 06 06:29:57 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 06:29:57 volumio volumio[6388]: info: CoreStateMachine::setRandom false Sep 06 06:29:57 volumio volumio[6388]: info: CoreStateMachine::pushState Sep 06 06:29:57 volumio volumio[6388]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:57 volumio volumio[6388]: info: CoreCommandRouter::volumioPushState Sep 06 06:29:57 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 06:29:57 volumio volumio[6388]: info: Starting Shairport Sync Sep 06 06:29:57 volumio volumio[6388]: info: Starting Shairport Sync Sep 06 06:29:57 volumio sudo[6510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 06:29:58 volumio sudo[6513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 06:29:58 volumio sudo[6510]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:58 volumio sudo[6513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:29:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 06:29:58 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 06 06:29:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 06:29:58 volumio sudo[6510]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:58 volumio sudo[6513]: pam_unix(sudo:session): session closed for user root Sep 06 06:29:59 volumio volumio[6388]: info: CoreCommandRouter::volumioGetState Sep 06 06:29:59 volumio volumio[6388]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:59 volumio volumio[6388]: Discovery: adding cbbd0678-4031-449f-b79c-6121b805b13d Sep 06 06:29:59 volumio volumio[6388]: info: mDNS: Found device Volumio Sep 06 06:29:59 volumio volumio[6388]: info: CoreCommandRouter::volumioGetState Sep 06 06:29:59 volumio volumio[6388]: info: CorePlayQueue::getTrack 0 Sep 06 06:29:59 volumio volumio[6388]: Cannot compose Albumart path Sep 06 06:30:00 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 06 06:30:00 volumio volumio[6388]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 06:30:00 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:30:00 volumio volumio[6388]: info: Listing playlists Sep 06 06:30:00 volumio volumio[6388]: info: CoreCommandRouter::volumioGetQueue Sep 06 06:30:00 volumio volumio[6388]: info: CoreStateMachine::getQueue Sep 06 06:30:00 volumio volumio[6388]: info: CorePlayQueue::getQueue Sep 06 06:30:00 volumio volumio[6388]: info: Shairport-Sync Started Sep 06 06:30:00 volumio volumio[6388]: Error adding Membership: Error: addMembership EINVAL Sep 06 06:30:00 volumio volumio[6388]: info: Shairport-Sync Started Sep 06 06:30:00 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:30:00 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 06:30:00 volumio volumio[6388]: Discovery: this is already registered, cbbd0678-4031-449f-b79c-6121b805b13d Sep 06 06:30:00 volumio volumio[6388]: info: mDNS: Found device Volumio Sep 06 06:30:00 volumio volumio[6388]: info: CoreCommandRouter::volumioGetState Sep 06 06:30:00 volumio volumio[6388]: info: CorePlayQueue::getTrack 0 Sep 06 06:30:01 volumio volumio[6388]: info: CoreCommandRouter::volumioGetState Sep 06 06:30:01 volumio volumio[6388]: info: CorePlayQueue::getTrack 0 Sep 06 06:30:03 volumio sudo[6556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 06:30:03 volumio sudo[6556]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:30:03 volumio sudo[6558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 06:30:03 volumio sudo[6556]: pam_unix(sudo:session): session closed for user root Sep 06 06:30:03 volumio sudo[6558]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:30:03 volumio sudo[6558]: pam_unix(sudo:session): session closed for user root Sep 06 06:30:03 volumio sudo[6574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 06 06:30:03 volumio sudo[6574]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:30:03 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 06 06:30:03 volumio sudo[6574]: pam_unix(sudo:session): session closed for user root Sep 06 06:30:03 volumio volumio[6388]: info: Upmpdcli Daemon Started Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 06 06:30:04 volumio volumio[6388]: info: Adding plugin bluetooth to MyMusic Plugins Sep 06 06:30:04 volumio volumio[6388]: info: Adding plugin cd_controller to MyMusic Plugins Sep 06 06:30:04 volumio volumio[6388]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 06 06:30:04 volumio volumio[6388]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 06 06:30:06 volumio volumio[6388]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 06 06:30:06 volumio kernel: rpi_firmware_get_throttled: 4 callbacks suppressed Sep 06 06:30:06 volumio kernel: Voltage normalised (0x00000000) Sep 06 06:30:07 volumio volumio[6388]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 06 06:30:07 volumio volumio[6388]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 06 06:30:07 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:30:07 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:30:07 volumio volumio[6388]: info: Starting MyVolumio Remote Streaming Endpoints Sep 06 06:30:07 volumio volumio[6388]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 06 06:30:07 volumio volumio[6388]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 06 06:30:07 volumio volumio[6388]: info: Streaming services startup Sep 06 06:30:07 volumio volumio[6388]: info: Starting Streaming Daemon Sep 06 06:30:07 volumio volumio[6388]: info: MyVolumio not started Sep 06 06:30:07 volumio volumio[6388]: info: Initializing device activation check Sep 06 06:30:07 volumio sudo[6586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 06 06:30:07 volumio sudo[6586]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 06:30:07 volumio volumio[6388]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 06 06:30:07 volumio systemd[1]: Stopping Volumio Streaming Daemon... Sep 06 06:30:07 volumio systemd[1]: Starting Volumio Streaming Daemon... Sep 06 06:30:07 volumio systemd[1]: Started Volumio Streaming Daemon. Sep 06 06:30:07 volumio sudo[6586]: pam_unix(sudo:session): session closed for user root Sep 06 06:30:07 volumio volumio-streaming-daemon[6592]: ############################ Sep 06 06:30:07 volumio volumio-streaming-daemon[6592]: # Volumio Streaming Daemon # Sep 06 06:30:07 volumio volumio-streaming-daemon[6592]: # Running on port 7777 # Sep 06 06:30:07 volumio volumio-streaming-daemon[6592]: ############################ Sep 06 06:30:07 volumio volumio-streaming-daemon[6592]: Environment: production Sep 06 06:30:07 volumio volumio[6388]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=834b38077ff486866b9de335cefca4be" http://updates.volumio.org:7070/downloader-v1/track-device Sep 06 06:30:07 volumio volumio[6388]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 06 06:30:07 volumio volumio[6388]: Dload Upload Total Spent Left Speed Sep 06 06:30:07 volumio volumio[6388]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 06 06:30:07 volumio volumio[6388]: retrying in 5 seconds, trial 0 Sep 06 06:30:07 volumio volumio[6388]: Volumio Calling Home Sep 06 06:30:09 volumio volumio-streaming-daemon[6592]: Environment: production Sep 06 06:30:09 volumio volumio[6388]: info: Fetching Streaming Services browse cache Sep 06 06:30:09 volumio volumio[6388]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Sep 06 06:30:09 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:30:09 volumio volumio[6388]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Sep 06 06:30:09 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 06:30:17 volumio volumio[6388]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=834b38077ff486866b9de335cefca4be" http://updates.volumio.org:7070/downloader-v1/track-device Sep 06 06:30:17 volumio volumio[6388]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 06 06:30:17 volumio volumio[6388]: Dload Upload Total Spent Left Speed Sep 06 06:30:17 volumio volumio[6388]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 06 06:30:17 volumio volumio[6388]: retrying in 5 seconds, trial 1 Sep 06 06:30:17 volumio volumio[6388]: Volumio Calling Home Sep 06 06:30:25 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 06:30:25 volumio volumio[6388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 06:30:27 volumio volumio[6388]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=834b38077ff486866b9de335cefca4be" http://updates.volumio.org:7070/downloader-v1/track-device Sep 06 06:30:27 volumio volumio[6388]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 06 06:30:27 volumio volumio[6388]: Dload Upload Total Spent Left Speed Sep 06 06:30:27 volumio volumio[6388]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 06 06:30:27 volumio volumio[6388]: retrying in 5 seconds, trial 2 Sep 06 06:30:27 volumio volumio[6388]: Volumio Calling Home Sep 06 06:30:34 volumio volumio[6388]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 06:30:34 volumio volumio[6388]: { Error: connect ETIMEDOUT 179.60.193.9:443 Sep 06 06:30:34 volumio volumio[6388]: at Object._errnoException (util.js:1022:11) Sep 06 06:30:34 volumio volumio[6388]: at _exceptionWithHostPort (util.js:1044:20) Sep 06 06:30:34 volumio volumio[6388]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 06 06:30:34 volumio volumio[6388]: code: 'ETIMEDOUT', Sep 06 06:30:34 volumio volumio[6388]: errno: 'ETIMEDOUT', Sep 06 06:30:34 volumio volumio[6388]: syscall: 'connect', Sep 06 06:30:34 volumio volumio[6388]: address: '179.60.193.9', Sep 06 06:30:34 volumio volumio[6388]: port: 443 } Sep 06 06:30:34 volumio volumio[6388]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 06:30:35 volumio sudo[6679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-06 06:29 Sep 06 06:30:35 volumio sudo[6679]: 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="ceaca221623bd3cfdc448100963369088321c5bb" VOLUMIO_FE_VERSION="4917998f86709e30d6499627aa39df0350cb55f8" VOLUMIO_BE_VERSION="2eb1d573179cee7a262e71ad6e6f6c07489b563f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 29 20:47:51 CEST 2020" VOLUMIO_VERSION="2.806" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="a5dbec2d8b23cb06ebf9780d5fc2c84f"