-- Logs begin at Sun 2022-05-01 09:04:38 UTC, end at Sun 2024-09-01 06:29:52 UTC. -- Sep 01 06:28:00 volumio volumio[9134]: info: Sep 01 06:28:00 volumio volumio[9134]: ---------------------------- Client requests Volume 30 Sep 01 06:28:00 volumio volumio[9134]: info: VolumeController::SetAlsaVolume30 Sep 01 06:28:00 volumio volumio[9134]: info: CoreStateMachine::pushState Sep 01 06:28:00 volumio volumio[9134]: info: CoreStateMachine::getState Sep 01 06:28:00 volumio volumio[9134]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:00 volumio volumio[9134]: info: CoreCommandRouter::volumioPushState Sep 01 06:28:00 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:28:00 volumio sudo[9289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:00 volumio sudo[9289]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:00 volumio sudo[9289]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:00 volumio volumio[9134]: info: Setting volume on startup at 30 Sep 01 06:28:00 volumio volumio[9134]: info: CoreCommandRouter::volumioGetState Sep 01 06:28:00 volumio volumio[9134]: info: CoreStateMachine::getState Sep 01 06:28:00 volumio volumio[9134]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:00 volumio sudo[9298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:00 volumio sudo[9298]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:00 volumio sudo[9298]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:02 volumio volumio[9134]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F/a77a6c7d-2c04-48b1-8d28-0f15fde84616.jpg' Sep 01 06:28:02 volumio volumio[9134]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 06:28:02 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:02 volumio volumio[9134]: info: CoreCommandRouter::volumioGetState Sep 01 06:28:02 volumio volumio[9134]: info: CoreStateMachine::getState Sep 01 06:28:02 volumio volumio[9134]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:02 volumio sudo[9311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:02 volumio sudo[9311]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:02 volumio sudo[9311]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:02 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 06:28:02 volumio sudo[9316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:02 volumio volumio[9134]: info: Listing playlists Sep 01 06:28:02 volumio volumio[9134]: info: CoreCommandRouter::volumioGetQueue Sep 01 06:28:02 volumio volumio[9134]: info: CoreStateMachine::getQueue Sep 01 06:28:02 volumio volumio[9134]: info: CorePlayQueue::getQueue Sep 01 06:28:02 volumio sudo[9316]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:02 volumio sudo[9316]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:05 volumio sudo[9333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:05 volumio sudo[9333]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:05 volumio sudo[9333]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:05 volumio sudo[9336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 06:28:05 volumio sudo[9336]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:05 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 01 06:28:05 volumio sudo[9336]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:05 volumio volumio[9134]: info: Upmpdcli Daemon Started Sep 01 06:28:07 volumio volumio[9134]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:28:07 volumio volumio[9134]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:28:07 volumio volumio[9134]: Dload Upload Total Spent Left Speed Sep 01 06:28:07 volumio volumio[9134]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 01 06:28:07 volumio volumio[9134]: retrying in 5 seconds, trial 0 Sep 01 06:28:07 volumio volumio[9134]: Volumio Calling Home Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 06:28:08 volumio volumio[9134]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 06:28:08 volumio volumio[9134]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 06:28:08 volumio volumio[9134]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 06:28:08 volumio volumio[9134]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 06:28:09 volumio volumio[9134]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 06:28:09 volumio volumio[9134]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 06:28:09 volumio volumio[9134]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 06:28:09 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:28:09 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:28:09 volumio volumio[9134]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 06:28:09 volumio volumio[9134]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 06:28:09 volumio volumio[9134]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 06:28:09 volumio volumio[9134]: info: Streaming services startup Sep 01 06:28:09 volumio volumio[9134]: info: Starting Streaming Daemon Sep 01 06:28:09 volumio volumio[9134]: info: MyVolumio not started Sep 01 06:28:09 volumio volumio[9134]: info: Initializing device activation check Sep 01 06:28:09 volumio sudo[9360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 06:28:09 volumio sudo[9360]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:09 volumio volumio[9134]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 06:28:09 volumio systemd[1]: Stopping Volumio Streaming Daemon... Sep 01 06:28:09 volumio systemd[1]: Starting Volumio Streaming Daemon... Sep 01 06:28:09 volumio systemd[1]: Started Volumio Streaming Daemon. Sep 01 06:28:09 volumio sudo[9360]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:09 volumio volumio-streaming-daemon[9366]: ############################ Sep 01 06:28:09 volumio volumio-streaming-daemon[9366]: # Volumio Streaming Daemon # Sep 01 06:28:09 volumio volumio-streaming-daemon[9366]: # Running on port 7777 # Sep 01 06:28:09 volumio volumio-streaming-daemon[9366]: ############################ Sep 01 06:28:12 volumio volumio[9134]: info: Fetching Streaming Services browse cache Sep 01 06:28:12 volumio volumio[9134]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Sep 01 06:28:12 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:12 volumio volumio[9134]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Sep 01 06:28:12 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:14 volumio nmbd[799]: [2024/09/01 06:28:14.934954, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 01 06:28:14 volumio nmbd[799]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.25 for name WORKGROUP<1d>. Sep 01 06:28:14 volumio nmbd[799]: This response was from IP 192.168.10.9, reporting an IP address of 192.168.10.9. Sep 01 06:28:19 volumio volumio[9134]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:28:19 volumio volumio[9134]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:28:19 volumio volumio[9134]: Dload Upload Total Spent Left Speed Sep 01 06:28:19 volumio volumio[9134]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 01 06:28:19 volumio volumio[9134]: retrying in 5 seconds, trial 1 Sep 01 06:28:19 volumio volumio[9134]: Volumio Calling Home Sep 01 06:28:27 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 06:28:27 volumio volumio[9134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:28:30 volumio volumio[9134]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:28:30 volumio volumio[9134]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:28:30 volumio volumio[9134]: Dload Upload Total Spent Left Speed Sep 01 06:28:30 volumio volumio[9134]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 01 06:28:30 volumio volumio[9134]: retrying in 5 seconds, trial 2 Sep 01 06:28:30 volumio volumio[9134]: Volumio Calling Home Sep 01 06:28:32 volumio volumio[9134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 06:28:32 volumio volumio[9134]: { Error: connect ETIMEDOUT 104.244.43.182:80 Sep 01 06:28:32 volumio volumio[9134]: at Object._errnoException (util.js:1022:11) Sep 01 06:28:32 volumio volumio[9134]: at _exceptionWithHostPort (util.js:1044:20) Sep 01 06:28:32 volumio volumio[9134]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 01 06:28:32 volumio volumio[9134]: code: 'ETIMEDOUT', Sep 01 06:28:32 volumio volumio[9134]: errno: 'ETIMEDOUT', Sep 01 06:28:32 volumio volumio[9134]: syscall: 'connect', Sep 01 06:28:32 volumio volumio[9134]: address: '104.244.43.182', Sep 01 06:28:32 volumio volumio[9134]: port: 80 } Sep 01 06:28:32 volumio volumio[9134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 06:28:32 volumio sudo[9441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 06:27 Sep 01 06:28:32 volumio sudo[9441]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:32 volumio sudo[9441]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:32 volumio volumio-remote-updater[565]: [2024-09-01 06:28:32] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 06:28:32 volumio volumio-remote-updater[565]: [2024-09-01 06:28:32] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 06:28:32 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Sep 01 06:28:32 volumio systemd[1]: Unit volumio.service entered failed state. Sep 01 06:28:32 volumio systemd[1]: Starting dynamicswap service... Sep 01 06:28:32 volumio systemd[1]: Started dynamicswap service. Sep 01 06:28:33 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Sep 01 06:28:33 volumio systemd[1]: Starting dynamicswap service... Sep 01 06:28:33 volumio systemd[1]: Started dynamicswap service. Sep 01 06:28:33 volumio systemd[1]: Stopping Volumio Backend Module... Sep 01 06:28:33 volumio systemd[1]: Starting Volumio Backend Module... Sep 01 06:28:33 volumio systemd[1]: Started Volumio Backend Module. Sep 01 06:28:33 volumio volumio[9462]: info: ------------------------------------------- Sep 01 06:28:33 volumio volumio[9462]: info: ----- Volumio2 ---- Sep 01 06:28:33 volumio volumio[9462]: info: ------------------------------------------- Sep 01 06:28:33 volumio volumio[9462]: info: ----- System startup ---- Sep 01 06:28:33 volumio volumio[9462]: info: ------------------------------------------- Sep 01 06:28:34 volumio volumio[9462]: info: MYVOLUMIO Environment detected Sep 01 06:28:34 volumio volumio[9462]: info: Plugin folders cleanup Sep 01 06:28:34 volumio volumio[9462]: info: Scanning into folder /volumio/app/plugins/ Sep 01 06:28:34 volumio volumio[9462]: info: Scanning category audio_interface Sep 01 06:28:34 volumio volumio[9462]: info: Scanning category miscellanea Sep 01 06:28:34 volumio volumio[9462]: info: Scanning category music_service Sep 01 06:28:34 volumio volumio[9462]: info: Scanning category plugins.json Sep 01 06:28:34 volumio volumio[9462]: info: Scanning category system_controller Sep 01 06:28:34 volumio volumio[9462]: info: Scanning category user_interface Sep 01 06:28:34 volumio volumio[9462]: info: Scanning into folder /data/plugins/ Sep 01 06:28:34 volumio volumio[9462]: info: Plugin folders cleanup completed Sep 01 06:28:34 volumio volumio[9462]: info: ------------------------------------------- Sep 01 06:28:34 volumio volumio[9462]: info: ----- Core plugins startup ---- Sep 01 06:28:34 volumio volumio[9462]: info: ------------------------------------------- Sep 01 06:28:34 volumio volumio[9462]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 06:28:34 volumio volumio[9462]: info: Adding plugin upnp to MyMusic Plugins Sep 01 06:28:34 volumio volumio[9462]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 06:28:34 volumio volumio[9462]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 06:28:34 volumio volumio[9462]: info: Loading plugins from folder /data/plugins/ Sep 01 06:28:34 volumio volumio[9462]: info: Loading plugin "system"... Sep 01 06:28:34 volumio volumio[9462]: info: Loading plugin "appearance"... Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "network"... Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "services"... Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "alsa_controller"... Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "wizard"... Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "volumio_command_line_client"... Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "upnp"... Sep 01 06:28:35 volumio volumio[9462]: info: [1725172115704] Starting Upmpd Daemon Sep 01 06:28:35 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "my_music"... Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "mpd"... Sep 01 06:28:35 volumio volumio[9462]: info: Loading plugin "upnp_browser"... Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "networkfs"... Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "alarm-clock"... Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "airplay_emulation"... Sep 01 06:28:36 volumio volumio[9462]: info: Starting Shairport Sync Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "last_100"... Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "webradio"... Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "i2s_dacs"... Sep 01 06:28:36 volumio volumio[9462]: info: I2S DAC not set, start Auto-detection Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "volumiodiscovery"... Sep 01 06:28:36 volumio volumio[9462]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 06:28:36 volumio node[9462]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 06:28:36 volumio volumio[9462]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 06:28:36 volumio node[9462]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 06:28:36 volumio volumio[9462]: *** WARNING *** For more information see Sep 01 06:28:36 volumio node[9462]: *** WARNING *** For more information see Sep 01 06:28:36 volumio volumio[9462]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 06:28:36 volumio node[9462]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 06:28:36 volumio volumio[9462]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 06:28:36 volumio node[9462]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 06:28:36 volumio volumio[9462]: *** WARNING *** For more information see Sep 01 06:28:36 volumio node[9462]: *** WARNING *** For more information see Sep 01 06:28:36 volumio volumio[9462]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 06:28:36 volumio volumio[9462]: Discovery: StartAdv! undefined Sep 01 06:28:36 volumio volumio[9462]: Discovery: Started advertising... Volumio - undefined Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "outputs"... Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "albumart"... Sep 01 06:28:36 volumio volumio[9462]: info: Plugin example_plugin is not enabled Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "inputs"... Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "updater_comm"... Sep 01 06:28:36 volumio volumio[9462]: info: Plugin mpdemulation is not enabled Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "rest_api"... Sep 01 06:28:36 volumio volumio[9462]: info: Loading plugin "websocket"... Sep 01 06:28:36 volumio volumio[9462]: info: ___________ START PLUGINS ___________ Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 06:28:36 volumio volumio[9462]: info: [1725172116661] CoreMusicLibrary::Adding element Media Servers Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:36 volumio volumio[9462]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 06:28:36 volumio volumio[9462]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 06:28:36 volumio volumio[9462]: Forking 3 albumart workers Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 06:28:36 volumio volumio[9462]: info: [1725172116748] CoreMusicLibrary::Adding element Last_100 Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 06:28:36 volumio volumio[9462]: info: [1725172116750] CoreMusicLibrary::Adding element Webradio Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 06:28:36 volumio volumio[9462]: info: ------------------------------------------- Sep 01 06:28:36 volumio volumio[9462]: info: ----- MyVolumio plugins startup ---- Sep 01 06:28:36 volumio volumio[9462]: info: ------------------------------------------- Sep 01 06:28:36 volumio volumio[9462]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 06:28:36 volumio volumio[9462]: info: Loading i18n strings for locale zh Sep 01 06:28:36 volumio volumio[9462]: Updating browse sources language Sep 01 06:28:36 volumio volumio[9462]: Cannot find translation for sourceMedia Servers Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::initPlayerControls Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: info: BOOT COMPLETED Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:28:36 volumio volumio[9462]: aplay: main:722: audio open error: Device or resource busy Sep 01 06:28:36 volumio volumio[9462]: Cannot play startup sound Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::Close All Modals sent Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::Close All Modals sent Sep 01 06:28:36 volumio volumio[9462]: Express server listening on port 3000 Sep 01 06:28:36 volumio volumio[9462]: Volumio Calling Home Sep 01 06:28:36 volumio volumio[9462]: info: CoreStateMachine::resetVolumioState Sep 01 06:28:36 volumio volumio[9462]: info: CoreStateMachine::getcurrentVolume Sep 01 06:28:36 volumio volumio[9462]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 06:28:37 volumio volumio[9462]: info: Setting Device type: Raspberry PI Sep 01 06:28:37 volumio volumio[9462]: info: MPD running with PID2542 ,establishing connection Sep 01 06:28:37 volumio sudo[9544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Sep 01 06:28:37 volumio sudo[9544]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:37 volumio volumio[9462]: Starting albumart workers Sep 01 06:28:37 volumio sudo[9544]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:37 volumio volumio[9462]: Starting albumart workers Sep 01 06:28:37 volumio volumio[9462]: Starting albumart workers Sep 01 06:28:37 volumio volumio[9462]: info: VolumeController:: Volume=30 Mute =false Sep 01 06:28:37 volumio volumio[9462]: info: CoreStateMachine::pushState Sep 01 06:28:37 volumio volumio[9462]: info: CoreStateMachine::getState Sep 01 06:28:37 volumio volumio[9462]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:37 volumio volumio[9462]: info: CoreCommandRouter::volumioPushState Sep 01 06:28:37 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:28:37 volumio volumio[9462]: info: CoreStateMachine::updateTrackBlock Sep 01 06:28:37 volumio volumio[9462]: info: CorePlayQueue::getTrackBlock Sep 01 06:28:37 volumio volumio[9462]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 06:28:37 volumio volumio-remote-updater[565]: [2024-09-01 06:28:37] [connect] Successful connection Sep 01 06:28:37 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:28:37 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 01 06:28:37 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 01 06:28:38 volumio volumio[9462]: info: Reloading queue from file Sep 01 06:28:38 volumio volumio[9462]: info: CoreStateMachine::setRepeat false single undefined Sep 01 06:28:38 volumio volumio[9462]: info: CoreStateMachine::pushState Sep 01 06:28:38 volumio volumio[9462]: info: CoreStateMachine::getState Sep 01 06:28:38 volumio volumio[9462]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:38 volumio volumio[9462]: info: CoreCommandRouter::volumioPushState Sep 01 06:28:38 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:28:38 volumio volumio[9462]: info: CoreStateMachine::setRandom false Sep 01 06:28:38 volumio volumio[9462]: info: CoreStateMachine::pushState Sep 01 06:28:38 volumio volumio[9462]: info: CoreStateMachine::getState Sep 01 06:28:38 volumio volumio[9462]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:38 volumio volumio[9462]: info: CoreCommandRouter::volumioPushState Sep 01 06:28:38 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:28:38 volumio volumio-remote-updater[565]: [2024-09-01 06:28:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1725172117 101 Sep 01 06:28:38 volumio volumio[9462]: info: Starting Shairport Sync Sep 01 06:28:38 volumio volumio[9462]: info: Starting Shairport Sync Sep 01 06:28:38 volumio sudo[9571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 06:28:38 volumio volumio[9462]: info: VolumeController:: Volume=30 Mute =false Sep 01 06:28:38 volumio volumio[9462]: info: CoreStateMachine::pushState Sep 01 06:28:38 volumio volumio[9462]: info: CoreStateMachine::getState Sep 01 06:28:38 volumio volumio[9462]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:38 volumio volumio[9462]: info: CoreCommandRouter::volumioPushState Sep 01 06:28:38 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:28:38 volumio sudo[9571]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:38 volumio sudo[9574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 06:28:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 06:28:38 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 01 06:28:38 volumio sudo[9574]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 06:28:38 volumio sudo[9571]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 06:28:38 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 01 06:28:38 volumio volumio[9462]: Discovery: adding a74fff33-6cbf-484d-a4f4-c3ffadc32926 Sep 01 06:28:38 volumio volumio[9462]: info: mDNS: Found device Volumio Sep 01 06:28:38 volumio sudo[9574]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 06:28:38 volumio sudo[9598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:38 volumio volumio[9462]: info: Shairport-Sync Started Sep 01 06:28:38 volumio volumio[9462]: Error adding Membership: Error: addMembership EINVAL Sep 01 06:28:38 volumio volumio[9462]: info: Shairport-Sync Started Sep 01 06:28:38 volumio sudo[9598]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:38 volumio sudo[9598]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:39 volumio volumio[9462]: info: CoreCommandRouter::volumioGetState Sep 01 06:28:39 volumio volumio[9462]: info: CoreStateMachine::getState Sep 01 06:28:39 volumio volumio[9462]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:39 volumio sudo[9613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:39 volumio sudo[9613]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:39 volumio sudo[9613]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:40 volumio volumio[9462]: info: Sep 01 06:28:40 volumio volumio[9462]: ---------------------------- Client requests Volume 30 Sep 01 06:28:40 volumio volumio[9462]: info: VolumeController::SetAlsaVolume30 Sep 01 06:28:40 volumio volumio[9462]: info: CoreStateMachine::pushState Sep 01 06:28:40 volumio volumio[9462]: info: CoreStateMachine::getState Sep 01 06:28:40 volumio volumio[9462]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:40 volumio volumio[9462]: info: CoreCommandRouter::volumioPushState Sep 01 06:28:40 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:28:40 volumio sudo[9628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:40 volumio sudo[9628]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:40 volumio sudo[9628]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:40 volumio volumio[9462]: info: Setting volume on startup at 30 Sep 01 06:28:42 volumio volumio[9462]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 06:28:42 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:42 volumio volumio[9462]: info: CoreCommandRouter::volumioGetState Sep 01 06:28:42 volumio volumio[9462]: info: CoreStateMachine::getState Sep 01 06:28:42 volumio volumio[9462]: info: CorePlayQueue::getTrack 0 Sep 01 06:28:43 volumio sudo[9641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:43 volumio sudo[9641]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:43 volumio sudo[9641]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:43 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 06:28:43 volumio volumio[9462]: info: Listing playlists Sep 01 06:28:43 volumio sudo[9647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:43 volumio volumio[9462]: info: CoreCommandRouter::volumioGetQueue Sep 01 06:28:43 volumio volumio[9462]: info: CoreStateMachine::getQueue Sep 01 06:28:43 volumio volumio[9462]: info: CorePlayQueue::getQueue Sep 01 06:28:43 volumio sudo[9647]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:43 volumio sudo[9647]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:45 volumio sudo[9665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:28:45 volumio sudo[9665]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:45 volumio sudo[9665]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:45 volumio sudo[9670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 06:28:45 volumio sudo[9670]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:45 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 01 06:28:45 volumio sudo[9670]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:45 volumio volumio[9462]: info: Upmpdcli Daemon Started Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 06:28:46 volumio volumio[9462]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 06:28:46 volumio volumio[9462]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 06:28:46 volumio volumio[9462]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 06:28:46 volumio volumio[9462]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 06:28:47 volumio volumio[9462]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 06:28:47 volumio volumio[9462]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 06:28:47 volumio volumio[9462]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 06:28:47 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:28:47 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:28:47 volumio volumio[9462]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 06:28:47 volumio volumio[9462]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 06:28:47 volumio volumio[9462]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 06:28:47 volumio volumio[9462]: info: Streaming services startup Sep 01 06:28:47 volumio volumio[9462]: info: Starting Streaming Daemon Sep 01 06:28:47 volumio volumio[9462]: info: MyVolumio not started Sep 01 06:28:47 volumio volumio[9462]: info: Initializing device activation check Sep 01 06:28:47 volumio sudo[9683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 06:28:47 volumio sudo[9683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:28:47 volumio volumio[9462]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 06:28:47 volumio systemd[1]: Stopping Volumio Streaming Daemon... Sep 01 06:28:47 volumio systemd[1]: Starting Volumio Streaming Daemon... Sep 01 06:28:47 volumio systemd[1]: Started Volumio Streaming Daemon. Sep 01 06:28:47 volumio sudo[9683]: pam_unix(sudo:session): session closed for user root Sep 01 06:28:47 volumio volumio[9462]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:28:47 volumio volumio[9462]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:28:47 volumio volumio[9462]: Dload Upload Total Spent Left Speed Sep 01 06:28:47 volumio volumio[9462]: [235B blob data] Sep 01 06:28:47 volumio volumio[9462]: retrying in 5 seconds, trial 0 Sep 01 06:28:47 volumio volumio[9462]: Volumio Calling Home Sep 01 06:28:47 volumio volumio-streaming-daemon[9689]: ############################ Sep 01 06:28:47 volumio volumio-streaming-daemon[9689]: # Volumio Streaming Daemon # Sep 01 06:28:47 volumio volumio-streaming-daemon[9689]: # Running on port 7777 # Sep 01 06:28:47 volumio volumio-streaming-daemon[9689]: ############################ Sep 01 06:28:50 volumio volumio[9462]: info: Fetching Streaming Services browse cache Sep 01 06:28:50 volumio volumio[9462]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Sep 01 06:28:50 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:50 volumio volumio[9462]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Sep 01 06:28:50 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:28:58 volumio volumio[9462]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:28:58 volumio volumio[9462]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:28:58 volumio volumio[9462]: Dload Upload Total Spent Left Speed Sep 01 06:28:58 volumio volumio[9462]: [235B blob data] Sep 01 06:28:58 volumio volumio[9462]: retrying in 5 seconds, trial 1 Sep 01 06:28:58 volumio volumio[9462]: Volumio Calling Home Sep 01 06:29:06 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 06:29:06 volumio volumio[9462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:29:08 volumio volumio[9462]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:29:08 volumio volumio[9462]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:29:08 volumio volumio[9462]: Dload Upload Total Spent Left Speed Sep 01 06:29:08 volumio volumio[9462]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 01 06:29:08 volumio volumio[9462]: retrying in 5 seconds, trial 2 Sep 01 06:29:08 volumio volumio[9462]: Volumio Calling Home Sep 01 06:29:10 volumio volumio[9462]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 06:29:10 volumio volumio[9462]: { Error: connect ETIMEDOUT 104.244.43.182:443 Sep 01 06:29:10 volumio volumio[9462]: at Object._errnoException (util.js:1022:11) Sep 01 06:29:10 volumio volumio[9462]: at _exceptionWithHostPort (util.js:1044:20) Sep 01 06:29:10 volumio volumio[9462]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 01 06:29:10 volumio volumio[9462]: code: 'ETIMEDOUT', Sep 01 06:29:10 volumio volumio[9462]: errno: 'ETIMEDOUT', Sep 01 06:29:10 volumio volumio[9462]: syscall: 'connect', Sep 01 06:29:10 volumio volumio[9462]: address: '104.244.43.182', Sep 01 06:29:10 volumio volumio[9462]: port: 443 } Sep 01 06:29:10 volumio volumio[9462]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 06:29:11 volumio sudo[9765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 06:28 Sep 01 06:29:11 volumio sudo[9765]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:11 volumio sudo[9765]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:13 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Sep 01 06:29:13 volumio volumio-remote-updater[565]: [2024-09-01 06:29:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 06:29:13 volumio volumio-remote-updater[565]: [2024-09-01 06:29:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 06:29:13 volumio systemd[1]: Unit volumio.service entered failed state. Sep 01 06:29:13 volumio systemd[1]: Starting dynamicswap service... Sep 01 06:29:13 volumio systemd[1]: Started dynamicswap service. Sep 01 06:29:13 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Sep 01 06:29:13 volumio systemd[1]: Starting dynamicswap service... Sep 01 06:29:13 volumio systemd[1]: Started dynamicswap service. Sep 01 06:29:13 volumio systemd[1]: Stopping Volumio Backend Module... Sep 01 06:29:13 volumio systemd[1]: Starting Volumio Backend Module... Sep 01 06:29:13 volumio systemd[1]: Started Volumio Backend Module. Sep 01 06:29:14 volumio volumio[9789]: info: ------------------------------------------- Sep 01 06:29:14 volumio volumio[9789]: info: ----- Volumio2 ---- Sep 01 06:29:14 volumio volumio[9789]: info: ------------------------------------------- Sep 01 06:29:14 volumio volumio[9789]: info: ----- System startup ---- Sep 01 06:29:14 volumio volumio[9789]: info: ------------------------------------------- Sep 01 06:29:14 volumio volumio[9789]: info: MYVOLUMIO Environment detected Sep 01 06:29:15 volumio volumio[9789]: info: Plugin folders cleanup Sep 01 06:29:15 volumio volumio[9789]: info: Scanning into folder /volumio/app/plugins/ Sep 01 06:29:15 volumio volumio[9789]: info: Scanning category audio_interface Sep 01 06:29:15 volumio volumio[9789]: info: Scanning category miscellanea Sep 01 06:29:15 volumio volumio[9789]: info: Scanning category music_service Sep 01 06:29:15 volumio volumio[9789]: info: Scanning category plugins.json Sep 01 06:29:15 volumio volumio[9789]: info: Scanning category system_controller Sep 01 06:29:15 volumio volumio[9789]: info: Scanning category user_interface Sep 01 06:29:15 volumio volumio[9789]: info: Scanning into folder /data/plugins/ Sep 01 06:29:15 volumio volumio[9789]: info: Plugin folders cleanup completed Sep 01 06:29:15 volumio volumio[9789]: info: ------------------------------------------- Sep 01 06:29:15 volumio volumio[9789]: info: ----- Core plugins startup ---- Sep 01 06:29:15 volumio volumio[9789]: info: ------------------------------------------- Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 06:29:15 volumio volumio[9789]: info: Adding plugin upnp to MyMusic Plugins Sep 01 06:29:15 volumio volumio[9789]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 06:29:15 volumio volumio[9789]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugins from folder /data/plugins/ Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "system"... Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "appearance"... Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "network"... Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "services"... Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "alsa_controller"... Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "wizard"... Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "volumio_command_line_client"... Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "upnp"... Sep 01 06:29:15 volumio volumio[9789]: info: [1725172155798] Starting Upmpd Daemon Sep 01 06:29:15 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "my_music"... Sep 01 06:29:15 volumio volumio[9789]: info: Loading plugin "mpd"... Sep 01 06:29:16 volumio volumio[9789]: info: Loading plugin "upnp_browser"... Sep 01 06:29:16 volumio volumio[9789]: info: Loading plugin "networkfs"... Sep 01 06:29:16 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 06:29:16 volumio volumio[9789]: info: Loading plugin "alarm-clock"... Sep 01 06:29:16 volumio volumio[9789]: info: Loading plugin "airplay_emulation"... Sep 01 06:29:16 volumio volumio[9789]: info: Starting Shairport Sync Sep 01 06:29:16 volumio volumio[9789]: info: Loading plugin "last_100"... Sep 01 06:29:16 volumio volumio[9789]: info: Loading plugin "webradio"... Sep 01 06:29:16 volumio volumio[9789]: info: Loading plugin "i2s_dacs"... Sep 01 06:29:16 volumio volumio[9789]: info: I2S DAC not set, start Auto-detection Sep 01 06:29:16 volumio volumio[9789]: info: Loading plugin "volumiodiscovery"... Sep 01 06:29:16 volumio volumio[9789]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 06:29:16 volumio node[9789]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 06:29:16 volumio node[9789]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 06:29:16 volumio node[9789]: *** WARNING *** For more information see Sep 01 06:29:16 volumio node[9789]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 06:29:16 volumio node[9789]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 06:29:16 volumio node[9789]: *** WARNING *** For more information see Sep 01 06:29:16 volumio volumio[9789]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 06:29:16 volumio volumio[9789]: *** WARNING *** For more information see Sep 01 06:29:16 volumio volumio[9789]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 06:29:16 volumio volumio[9789]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 06:29:16 volumio volumio[9789]: *** WARNING *** For more information see Sep 01 06:29:17 volumio volumio[9789]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 06:29:17 volumio volumio[9789]: Discovery: StartAdv! undefined Sep 01 06:29:17 volumio volumio[9789]: Discovery: Started advertising... Volumio - undefined Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 06:29:17 volumio volumio[9789]: info: Loading plugin "outputs"... Sep 01 06:29:17 volumio volumio[9789]: info: Loading plugin "albumart"... Sep 01 06:29:17 volumio volumio[9789]: info: Plugin example_plugin is not enabled Sep 01 06:29:17 volumio volumio[9789]: info: Loading plugin "inputs"... Sep 01 06:29:17 volumio volumio[9789]: info: Loading plugin "updater_comm"... Sep 01 06:29:17 volumio volumio[9789]: info: Plugin mpdemulation is not enabled Sep 01 06:29:17 volumio volumio[9789]: info: Loading plugin "rest_api"... Sep 01 06:29:17 volumio volumio[9789]: info: Loading plugin "websocket"... Sep 01 06:29:17 volumio volumio[9789]: info: ___________ START PLUGINS ___________ Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 06:29:17 volumio volumio[9789]: info: [1725172157601] CoreMusicLibrary::Adding element Media Servers Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:29:17 volumio volumio[9789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 06:29:17 volumio volumio[9789]: Forking 3 albumart workers Sep 01 06:29:17 volumio volumio[9789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 06:29:17 volumio volumio[9789]: info: [1725172157691] CoreMusicLibrary::Adding element Last_100 Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 06:29:17 volumio volumio[9789]: info: [1725172157695] CoreMusicLibrary::Adding element Webradio Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 06:29:17 volumio volumio[9789]: info: ------------------------------------------- Sep 01 06:29:17 volumio volumio[9789]: info: ----- MyVolumio plugins startup ---- Sep 01 06:29:17 volumio volumio[9789]: info: ------------------------------------------- Sep 01 06:29:17 volumio volumio[9789]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 06:29:17 volumio volumio[9789]: info: Loading i18n strings for locale zh Sep 01 06:29:17 volumio volumio[9789]: Updating browse sources language Sep 01 06:29:17 volumio volumio[9789]: Cannot find translation for sourceMedia Servers Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::initPlayerControls Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: info: BOOT COMPLETED Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:29:17 volumio volumio[9789]: aplay: main:722: audio open error: Device or resource busy Sep 01 06:29:17 volumio volumio[9789]: Cannot play startup sound Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::Close All Modals sent Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::Close All Modals sent Sep 01 06:29:17 volumio volumio[9789]: Express server listening on port 3000 Sep 01 06:29:17 volumio volumio[9789]: Volumio Calling Home Sep 01 06:29:17 volumio volumio[9789]: info: CoreStateMachine::resetVolumioState Sep 01 06:29:17 volumio volumio[9789]: info: CoreStateMachine::getcurrentVolume Sep 01 06:29:17 volumio volumio[9789]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 06:29:18 volumio volumio[9789]: info: Setting Device type: Raspberry PI Sep 01 06:29:18 volumio volumio[9789]: info: MPD running with PID2542 ,establishing connection Sep 01 06:29:18 volumio sudo[9871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Sep 01 06:29:18 volumio sudo[9871]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:18 volumio volumio[9789]: info: VolumeController:: Volume=30 Mute =false Sep 01 06:29:18 volumio volumio[9789]: info: CoreStateMachine::pushState Sep 01 06:29:18 volumio volumio[9789]: info: CoreStateMachine::getState Sep 01 06:29:18 volumio volumio[9789]: info: CorePlayQueue::getTrack 0 Sep 01 06:29:18 volumio volumio[9789]: info: CoreCommandRouter::volumioPushState Sep 01 06:29:18 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:29:18 volumio volumio[9789]: info: CoreStateMachine::updateTrackBlock Sep 01 06:29:18 volumio volumio[9789]: info: CorePlayQueue::getTrackBlock Sep 01 06:29:18 volumio sudo[9871]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:18 volumio volumio[9789]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 06:29:18 volumio volumio[9789]: Starting albumart workers Sep 01 06:29:18 volumio volumio[9789]: Starting albumart workers Sep 01 06:29:18 volumio volumio-remote-updater[565]: [2024-09-01 06:29:18] [connect] Successful connection Sep 01 06:29:18 volumio volumio[9789]: Starting albumart workers Sep 01 06:29:18 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:29:18 volumio volumio[9789]: info: Reloading queue from file Sep 01 06:29:19 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 01 06:29:19 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 01 06:29:19 volumio volumio[9789]: info: CoreStateMachine::setRepeat false single undefined Sep 01 06:29:19 volumio volumio[9789]: info: CoreStateMachine::pushState Sep 01 06:29:19 volumio volumio[9789]: info: CoreStateMachine::getState Sep 01 06:29:19 volumio volumio[9789]: info: CorePlayQueue::getTrack 0 Sep 01 06:29:19 volumio volumio[9789]: info: CoreCommandRouter::volumioPushState Sep 01 06:29:19 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:29:19 volumio volumio[9789]: info: CoreStateMachine::setRandom false Sep 01 06:29:19 volumio volumio[9789]: info: CoreStateMachine::pushState Sep 01 06:29:19 volumio volumio[9789]: info: CoreStateMachine::getState Sep 01 06:29:19 volumio volumio[9789]: info: CorePlayQueue::getTrack 0 Sep 01 06:29:19 volumio volumio[9789]: info: CoreCommandRouter::volumioPushState Sep 01 06:29:19 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:29:19 volumio volumio-remote-updater[565]: [2024-09-01 06:29:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1725172158 101 Sep 01 06:29:19 volumio volumio[9789]: info: VolumeController:: Volume=30 Mute =false Sep 01 06:29:19 volumio volumio[9789]: info: CoreStateMachine::pushState Sep 01 06:29:19 volumio volumio[9789]: info: CoreStateMachine::getState Sep 01 06:29:19 volumio volumio[9789]: info: CorePlayQueue::getTrack 0 Sep 01 06:29:19 volumio volumio[9789]: info: CoreCommandRouter::volumioPushState Sep 01 06:29:19 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:29:19 volumio volumio[9789]: info: Starting Shairport Sync Sep 01 06:29:19 volumio volumio[9789]: info: Starting Shairport Sync Sep 01 06:29:19 volumio sudo[9897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 06:29:19 volumio sudo[9897]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:19 volumio sudo[9900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 06:29:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 06:29:19 volumio sudo[9900]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:19 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 01 06:29:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 06:29:19 volumio sudo[9897]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 06:29:19 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 01 06:29:19 volumio volumio[9789]: Discovery: adding a74fff33-6cbf-484d-a4f4-c3ffadc32926 Sep 01 06:29:19 volumio volumio[9789]: info: mDNS: Found device Volumio Sep 01 06:29:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 06:29:19 volumio sudo[9900]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:19 volumio volumio[9789]: info: Shairport-Sync Started Sep 01 06:29:19 volumio sudo[9924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:29:19 volumio volumio[9789]: Error adding Membership: Error: addMembership EINVAL Sep 01 06:29:19 volumio volumio[9789]: info: Shairport-Sync Started Sep 01 06:29:19 volumio sudo[9924]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:19 volumio sudo[9924]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:20 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AE%9D%E7%9F%B3gem/%E5%87%BA%E5%BE%81%EF%BC%88%E5%A4%A7%E9%A3%8E%E6%AD%8C%EF%BC%89/67ad7074-a110-4e82-8e0a-b9572fe42740.jpg' Sep 01 06:29:20 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B2%91%E5%AE%81%E5%84%BF/fly/01f1e831-7b33-4f3a-8232-53656c06659e.jpg' Sep 01 06:29:20 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E7%A2%A7%E6%99%A8/My%20Way/7069a420-fb2b-4d3d-891b-130e50aad133.jpg' Sep 01 06:29:20 volumio volumio[9789]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 06:29:20 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:29:20 volumio volumio[9789]: info: CoreCommandRouter::volumioGetState Sep 01 06:29:20 volumio volumio[9789]: info: CoreStateMachine::getState Sep 01 06:29:20 volumio volumio[9789]: info: CorePlayQueue::getTrack 0 Sep 01 06:29:20 volumio sudo[9944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:29:20 volumio sudo[9944]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:20 volumio sudo[9944]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:20 volumio sudo[9950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:29:20 volumio sudo[9950]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:20 volumio sudo[9950]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:20 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 06:29:20 volumio volumio[9789]: info: Listing playlists Sep 01 06:29:20 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E8%89%BA%E5%85%B4/%E5%A4%96%E5%A9%86/cb0a8545-3d3e-4a04-aa4b-ff2ddd5eaa09.jpg' Sep 01 06:29:20 volumio volumio[9789]: info: Sep 01 06:29:20 volumio volumio[9789]: ---------------------------- Client requests Volume 30 Sep 01 06:29:20 volumio volumio[9789]: info: VolumeController::SetAlsaVolume30 Sep 01 06:29:20 volumio volumio[9789]: info: CoreStateMachine::pushState Sep 01 06:29:20 volumio volumio[9789]: info: CoreStateMachine::getState Sep 01 06:29:20 volumio volumio[9789]: info: CorePlayQueue::getTrack 0 Sep 01 06:29:20 volumio volumio[9789]: info: CoreCommandRouter::volumioPushState Sep 01 06:29:20 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 06:29:20 volumio sudo[9966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:29:20 volumio sudo[9966]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:20 volumio sudo[9966]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:20 volumio volumio[9789]: info: Setting volume on startup at 30 Sep 01 06:29:20 volumio volumio[9789]: info: CoreCommandRouter::volumioGetQueue Sep 01 06:29:20 volumio volumio[9789]: info: CoreStateMachine::getQueue Sep 01 06:29:20 volumio volumio[9789]: info: CorePlayQueue::getQueue Sep 01 06:29:21 volumio volumio[9789]: info: CoreCommandRouter::volumioGetState Sep 01 06:29:21 volumio volumio[9789]: info: CoreStateMachine::getState Sep 01 06:29:21 volumio volumio[9789]: info: CorePlayQueue::getTrack 0 Sep 01 06:29:21 volumio sudo[9975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:29:21 volumio sudo[9975]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:21 volumio sudo[9975]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:21 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%98%93%E7%83%8A%E5%8D%83%E7%8E%BA/%E6%B8%A9%E5%B7%AE%E6%84%9F/d02fa6f2-da1c-464a-a7f3-374c2d4646c6.jpg' Sep 01 06:29:22 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%AE%87%E6%98%A5/%E5%92%8F%E6%98%A5/f9640d55-edf5-4caf-be81-52c526b89c36.jpg' Sep 01 06:29:22 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E8%8D%A3%E6%B5%A9/%E8%80%81%E5%8F%8B%E8%AE%B0/6643071e-ca53-4148-bf0f-7a20b43c9d8a.jpg' Sep 01 06:29:22 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E8%8D%A3%E6%B5%A9/%E9%BA%BB%E9%9B%80/ebea71db-3d2a-4f64-bec3-23891e32aa9e.jpg' Sep 01 06:29:22 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A3%89%E5%AD%90/%E5%8B%87%E6%B0%94/fb25b115-0290-4612-8906-27df1a4928bd.jpg' Sep 01 06:29:23 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B1%AA%E8%8B%8F%E6%B3%B7/%E8%BF%87%E5%8E%BB%E7%8E%B0%E5%9C%A8%E6%97%B6/24f6c3e6-4f60-4a8c-b0f7-78915fac5b34.jpg' Sep 01 06:29:24 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%96%9B%E4%B9%8B%E8%B0%A6/%E5%B0%98/f8b02a15-93f4-4326-ac07-316b70596c48.jpg' Sep 01 06:29:24 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A2%81%E5%A8%85%E7%BB%B4/%E4%B8%BA%E4%BD%A0%E8%80%8C%E6%9D%A5/7e1da882-4c30-4cb4-9a1c-9ba391c57d20.jpg' Sep 01 06:29:24 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A6%81%E4%B8%8D%E8%A6%81%E4%B9%B0%E8%8F%9C/%E7%81%AB%E7%BA%A2%E7%9A%84%E8%90%A8%E6%97%A5%E6%9C%97/f6c5df99-7ee7-4602-b8ec-754a72133528.jpg' Sep 01 06:29:24 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E9%AD%8F%E6%B4%B2/REBIRTH/d2cb4a08-d91d-46db-bf09-3df9f1f781b5.jpg' Sep 01 06:29:25 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BB%84%E5%AD%90%E9%9F%AC/%E6%9C%80%E5%A5%BD%E7%9A%84%E6%88%91%E4%BB%AC/8d128908-e204-4129-8e0b-8636775c5612.jpg' Sep 01 06:29:25 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/HITA/%E7%BE%A1%E4%BA%91/cefcb791-0752-40fd-83e1-9ad70878e3b6.jpg' Sep 01 06:29:25 volumio sudo[9995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 06:29:25 volumio sudo[9995]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:25 volumio sudo[9995]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:25 volumio sudo[9999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 06:29:25 volumio sudo[9999]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:25 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 01 06:29:25 volumio sudo[9999]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:25 volumio volumio[9789]: info: Upmpdcli Daemon Started Sep 01 06:29:26 volumio volumio[9789]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BB%BB%E7%84%B6/%E4%BB%8E%E5%B0%8F%E5%88%B0%E5%A4%A7/0b6679d9-7a00-49c4-9642-5c67bb489144.jpg' Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 06:29:27 volumio volumio[9789]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 06:29:27 volumio volumio[9789]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 06:29:27 volumio volumio[9789]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 06:29:27 volumio volumio[9789]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 06:29:28 volumio volumio[9789]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 06:29:29 volumio volumio[9789]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 06:29:29 volumio volumio[9789]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 06:29:29 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:29:29 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:29:29 volumio volumio[9789]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 06:29:29 volumio volumio[9789]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 06:29:29 volumio volumio[9789]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 06:29:29 volumio volumio[9789]: info: Streaming services startup Sep 01 06:29:29 volumio volumio[9789]: info: Starting Streaming Daemon Sep 01 06:29:29 volumio volumio[9789]: info: MyVolumio not started Sep 01 06:29:29 volumio volumio[9789]: info: Initializing device activation check Sep 01 06:29:29 volumio sudo[10017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 06:29:29 volumio sudo[10017]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 06:29:29 volumio volumio[9789]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 06:29:29 volumio systemd[1]: Stopping Volumio Streaming Daemon... Sep 01 06:29:29 volumio systemd[1]: Starting Volumio Streaming Daemon... Sep 01 06:29:29 volumio systemd[1]: Started Volumio Streaming Daemon. Sep 01 06:29:29 volumio sudo[10017]: pam_unix(sudo:session): session closed for user root Sep 01 06:29:29 volumio volumio-streaming-daemon[10023]: ############################ Sep 01 06:29:29 volumio volumio-streaming-daemon[10023]: # Volumio Streaming Daemon # Sep 01 06:29:29 volumio volumio-streaming-daemon[10023]: # Running on port 7777 # Sep 01 06:29:29 volumio volumio-streaming-daemon[10023]: ############################ Sep 01 06:29:29 volumio volumio[9789]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:29:29 volumio volumio[9789]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:29:29 volumio volumio[9789]: Dload Upload Total Spent Left Speed Sep 01 06:29:29 volumio volumio[9789]: [235B blob data] Sep 01 06:29:29 volumio volumio[9789]: retrying in 5 seconds, trial 0 Sep 01 06:29:29 volumio volumio[9789]: Volumio Calling Home Sep 01 06:29:32 volumio volumio[9789]: info: Fetching Streaming Services browse cache Sep 01 06:29:32 volumio volumio[9789]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Sep 01 06:29:32 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:29:32 volumio volumio[9789]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Sep 01 06:29:32 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 06:29:40 volumio volumio[9789]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:29:40 volumio volumio[9789]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:29:40 volumio volumio[9789]: Dload Upload Total Spent Left Speed Sep 01 06:29:40 volumio volumio[9789]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Sep 01 06:29:40 volumio volumio[9789]: retrying in 5 seconds, trial 1 Sep 01 06:29:40 volumio volumio[9789]: Volumio Calling Home Sep 01 06:29:47 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 06:29:47 volumio volumio[9789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 06:29:50 volumio volumio[9789]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=488932dc88a761ffe1ac5115aa9551dd" http://updates.volumio.org:7070/downloader-v1/track-device Sep 01 06:29:50 volumio volumio[9789]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 06:29:50 volumio volumio[9789]: Dload Upload Total Spent Left Speed Sep 01 06:29:50 volumio volumio[9789]: [235B blob data] Sep 01 06:29:50 volumio volumio[9789]: retrying in 5 seconds, trial 2 Sep 01 06:29:50 volumio volumio[9789]: Volumio Calling Home Sep 01 06:29:51 volumio volumio[9789]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 06:29:51 volumio volumio[9789]: { Error: connect ETIMEDOUT 75.126.115.192:443 Sep 01 06:29:51 volumio volumio[9789]: at Object._errnoException (util.js:1022:11) Sep 01 06:29:51 volumio volumio[9789]: at _exceptionWithHostPort (util.js:1044:20) Sep 01 06:29:51 volumio volumio[9789]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 01 06:29:51 volumio volumio[9789]: code: 'ETIMEDOUT', Sep 01 06:29:51 volumio volumio[9789]: errno: 'ETIMEDOUT', Sep 01 06:29:51 volumio volumio[9789]: syscall: 'connect', Sep 01 06:29:51 volumio volumio[9789]: address: '75.126.115.192', Sep 01 06:29:51 volumio volumio[9789]: port: 443 } Sep 01 06:29:51 volumio volumio[9789]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 06:29:52 volumio sudo[10098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 06:28 Sep 01 06:29:52 volumio sudo[10098]: 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="7441f93fb55fc13e1243278874bfebef55eaac89" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="60cbd8a1e03b2e464c11c01f54954168a34e9b45" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Feb 15 03:28:59 CET 2020" VOLUMIO_VERSION="2.703" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c6482eede154d29f2cc010e5a630bcb4"