-- Logs begin at Tue 2025-06-10 22:14:50 CEST, end at Tue 2025-06-10 22:17:37 CEST. -- Jun 10 22:16:10 volumio systemd-timedated[1090]: Changed local time to Tue Jun 10 22:16:10 2025 Jun 10 22:16:10 volumio sudo[1088]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:10 volumio volumio-time-update[675]: volumio-time-update-util: System time updated successfully. Jun 10 22:16:10 volumio systemd[1]: Started Volumio Time Update Utility. Jun 10 22:16:10 volumio ntpd[799]: Listen normally on 3 eth0 192.168.8.109:123 Jun 10 22:16:10 volumio ntpd[799]: new interface(s) found: waking up resolver Jun 10 22:16:10 volumio volumio[831]: info: Volumio Network Manager: Network status updated: 1 Jun 10 22:16:10 volumio nmbd[771]: [2025/06/10 22:16:10.964812, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 10 22:16:10 volumio nmbd[771]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 10 22:16:10 volumio systemd[1]: Started Samba NMB Daemon. Jun 10 22:16:10 volumio sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 22:16:10 volumio sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:16:11 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 10 22:16:10 volumio sudo[1107]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:10 volumio sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 22:16:10 volumio sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:16:11 volumio sudo[1110]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:11 volumio sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 10 22:16:11 volumio sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:16:11 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 10 22:16:11 volumio sudo[1114]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:11 volumio volumio[831]: info: Upmpdcli Daemon Started Jun 10 22:16:11 volumio volumio[1116]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 10 22:16:11 volumio winbindd[1106]: [2025/06/10 22:16:11.229026, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 10 22:16:11 volumio winbindd[1106]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 10 22:16:11 volumio winbindd[1106]: [2025/06/10 22:16:11.242906, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 10 22:16:11 volumio winbindd[1106]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 10 22:16:11 volumio systemd[1]: Started Samba Winbind Daemon. Jun 10 22:16:11 volumio systemd[1]: Starting Samba SMB Daemon... Jun 10 22:16:11 volumio systemd[1]: Started Samba SMB Daemon. Jun 10 22:16:11 volumio systemd[1]: Reached target Multi-User System. Jun 10 22:16:11 volumio systemd[1]: Reached target Graphical Interface. Jun 10 22:16:11 volumio smbd[1135]: [2025/06/10 22:16:11.622069, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 10 22:16:11 volumio smbd[1135]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 10 22:16:11 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 10 22:16:11 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 10 22:16:11 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 10 22:16:11 volumio systemd[1]: Startup finished in 11.858s (kernel) + 20.928s (userspace) = 32.787s. Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 10 22:16:12 volumio volumio[831]: info: Adding plugin bluetooth to MyMusic Plugins Jun 10 22:16:12 volumio volumio[831]: info: Adding plugin multiroom to MyMusic Plugins Jun 10 22:16:12 volumio volumio[831]: info: Adding plugin metavolumio to MyMusic Plugins Jun 10 22:16:12 volumio volumio[831]: info: Adding plugin cd_controller to MyMusic Plugins Jun 10 22:16:12 volumio volumio[831]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 10 22:16:12 volumio volumio[831]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 10 22:16:12 volumio volumio[831]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 10 22:16:12 volumio volumio[831]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 10 22:16:13 volumio volumio[831]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 10 22:16:13 volumio volumio[831]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 10 22:16:13 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:16:13 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:16:13 volumio volumio[831]: info: Starting MyVolumio Remote Streaming Endpoints Jun 10 22:16:13 volumio volumio[831]: info: MyVolumio not started Jun 10 22:16:13 volumio volumio[831]: info: Initializing device activation check Jun 10 22:16:13 volumio volumio[831]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 10 22:16:13 volumio volumio[831]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 10 22:16:14 volumio volumio[831]: info: Starting Streaming Service Transparent Proxy Jun 10 22:16:14 volumio volumio[831]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 10 22:16:14 volumio volumio[831]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 10 22:16:14 volumio volumio[831]: info: Streaming services startup Jun 10 22:16:14 volumio volumio[831]: info: Starting Streaming Daemon Jun 10 22:16:14 volumio sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 10 22:16:14 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:16:14 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:14 volumio volumio[831]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 10 22:16:14 volumio volumio[831]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.812&uuid=44ff360b8741b2acf4ed2eea62cf927a" http://updates.volumio.org/downloader-v1/track-device Jun 10 22:16:14 volumio volumio[831]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 10 22:16:14 volumio volumio[831]: Dload Upload Total Spent Left Speed Jun 10 22:16:14 volumio volumio[831]: [132B blob data] Jun 10 22:16:14 volumio volumio[831]: retrying in 5 seconds, trial 0 Jun 10 22:16:14 volumio volumio[831]: info: Volumio Calling Home Jun 10 22:16:14 volumio volumio[831]: error: Cannot start Volumio Streaming Daemon Jun 10 22:16:14 volumio volumio[831]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 10 22:16:14 volumio volumio[831]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 10 22:16:14 volumio volumio[831]: info: Volumio called home Jun 10 22:16:14 volumio volumio[1116]: .......................................++++ Jun 10 22:16:14 volumio volumio[831]: STREAMING PROXY: Starting server on port 3245 Jun 10 22:16:14 volumio volumio[831]: Node JS runtime: 14 Jun 10 22:16:23 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 10 22:16:25 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 10 22:16:27 volumio sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 22:16:27 volumio sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:16:27 volumio sudo[1183]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:27 volumio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 22:16:27 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:16:27 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:27 volumio volumio[831]: verbose: New Socket.io Connection to 192.168.8.109 from 192.168.8.207 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 4 Jun 10 22:16:28 volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 22:16:28 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:16:28 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:28 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 22:16:28 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:16:28 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 22:16:28 volumio volumio[831]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 10 22:16:28 volumio volumio[831]: verbose: New Socket.io Connection to 192.168.8.109 from 192.168.8.207 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::volumioGetState Jun 10 22:16:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::volumioGetState Jun 10 22:16:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 10 22:16:28 volumio volumio[831]: info: Received Get System Info Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 22:16:28 volumio volumio[831]: info: Discovery: Getting this device information Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::volumioGetState Jun 10 22:16:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::volumioGetState Jun 10 22:16:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:16:28 volumio volumio[831]: info: Listing playlists Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 22:16:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 10 22:16:29 volumio volumio[831]: info: CoreCommandRouter::volumioGetState Jun 10 22:16:29 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:16:30 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 10 22:16:30 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 10 22:16:30 volumio volumio[831]: info: Received Get System Info Jun 10 22:16:30 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 22:16:30 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 22:16:30 volumio volumio[831]: info: Discovery: Getting this device information Jun 10 22:16:30 volumio volumio[831]: info: CoreCommandRouter::volumioGetState Jun 10 22:16:30 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:16:30 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 22:16:31 volumio volumio[1116]: ........................................................................................................................................................................................++++ Jun 10 22:16:31 volumio volumio[1116]: e is 65537 (0x010001) Jun 10 22:16:31 volumio volumio[1116]: writing RSA key Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 22:16:33 volumio volumio-remote-updater[671]: No test mode Jun 10 22:16:33 volumio volumio-remote-updater[671]: No alpha test mode Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 10 22:16:33 volumio volumio[831]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 22:16:33 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 22:16:34 volumio volumio[831]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 10 22:16:34 volumio volumio[831]: info: Completed starting MyVolumio Plugin Jun 10 22:16:34 volumio volumio[831]: [Metrics] CommandRouter: 35s 500.07ms Jun 10 22:16:34 volumio volumio[831]: info: CoreCommandRouter::volumiosetStartupVolume Jun 10 22:16:34 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 22:16:34 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:16:34 volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:16:34 volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:16:35 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 10 22:16:35 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 22:16:35 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 10 22:16:38 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 22:16:39 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 22:16:40 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 10 22:16:41 volumio volumio[831]: info: BOOT COMPLETED Jun 10 22:16:42 volumio volumio[831]: info: CoreCommandRouter::volumioGetState Jun 10 22:16:42 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:16:43 volumio volumio[831]: info: Retrieving Cloud Streaming UI Jun 10 22:16:43 volumio volumio[831]: info: Getting Tidal Cloud Configuration Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:16:43 volumio volumio[831]: info: Getting Qobuz Cloud Configuration Jun 10 22:16:43 volumio volumio[831]: info: Asking plugin for UI Config Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:16:43 volumio volumio[831]: info: Getting Spotify Cloud Configuration Jun 10 22:16:43 volumio volumio[831]: info: Asking plugin for UI Config Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:16:43 volumio volumio[831]: info: Saving Spotify Acccount Jun 10 22:16:43 volumio volumio[831]: info: Got Tidal Cloud Configuration Jun 10 22:16:43 volumio volumio[831]: info: Got it Jun 10 22:16:43 volumio volumio[831]: info: Got it Jun 10 22:16:43 volumio volumio[831]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:16:43 volumio volumio[831]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:16:43 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 10 22:16:47 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:16:51 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:16:55 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:16:59 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:01 volumio ntpd[799]: Soliciting pool server 185.252.140.126 Jun 10 22:17:02 volumio ntpd[799]: Soliciting pool server 185.252.140.125 Jun 10 22:17:02 volumio ntpd[799]: Soliciting pool server 185.207.105.38 Jun 10 22:17:03 volumio ntpd[799]: Soliciting pool server 162.159.200.1 Jun 10 22:17:03 volumio ntpd[799]: Soliciting pool server 131.234.220.232 Jun 10 22:17:03 volumio ntpd[799]: Soliciting pool server 192.248.187.154 Jun 10 22:17:03 volumio ntpd[799]: Soliciting pool server 194.59.205.229 Jun 10 22:17:03 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:04 volumio ntpd[799]: Soliciting pool server 129.70.132.33 Jun 10 22:17:04 volumio ntpd[799]: Soliciting pool server 79.133.44.140 Jun 10 22:17:04 volumio ntpd[799]: Soliciting pool server 185.228.139.165 Jun 10 22:17:04 volumio ntpd[799]: Soliciting pool server 130.162.237.177 Jun 10 22:17:05 volumio ntpd[799]: Soliciting pool server 178.215.228.24 Jun 10 22:17:05 volumio ntpd[799]: Soliciting pool server 85.215.93.134 Jun 10 22:17:05 volumio ntpd[799]: Soliciting pool server 185.11.138.90 Jun 10 22:17:06 volumio ntpd[799]: Soliciting pool server 88.198.200.96 Jun 10 22:17:06 volumio ntpd[799]: Soliciting pool server 85.214.83.151 Jun 10 22:17:07 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:11 volumio ntpd[799]: receive: Unexpected origin timestamp 0xebf31146.1dbb569d does not match aorg 0000000000.00000000 from server@185.228.139.165 xmt 0xebf31147.062e4396 Jun 10 22:17:12 volumio kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci_hcd Jun 10 22:17:12 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=1576, bcdDevice= 2.04 Jun 10 22:17:12 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 10 22:17:12 volumio kernel: usb 2-1: Product: JMS579 Jun 10 22:17:12 volumio kernel: usb 2-1: Manufacturer: JMicron Jun 10 22:17:12 volumio kernel: usb 2-1: SerialNumber: 987654321029 Jun 10 22:17:12 volumio kernel: scsi host0: uas Jun 10 22:17:12 volumio kernel: scsi 0:0:0:0: Direct-Access CT500MX5 00SSD1 0204 PQ: 0 ANSI: 6 Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB) Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Jun 10 22:17:12 volumio kernel: sda: sda1 sda2 Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jun 10 22:17:12 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jun 10 22:17:12 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:16 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:20 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:24 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:27 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jun 10 22:17:27 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand rescan Jun 10 22:17:27 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:27 volumio volumio[831]: info: Jun 10 22:17:27 volumio volumio[831]: ---------------------------- MPD announces state update: update Jun 10 22:17:27 volumio volumio[831]: info: sendMpdCommand rescan took 3 milliseconds Jun 10 22:17:27 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:27 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:27 volumio volumio[831]: info: sendMpdCommand status took 4 milliseconds Jun 10 22:17:27 volumio volumio[831]: info: sendMpdCommand status took 1 milliseconds Jun 10 22:17:27 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: Command Router : Notfying DB Updatetrue Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 74ms Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: Jun 10 22:17:28 volumio volumio[831]: ---------------------------- MPD announces state update: update Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: Jun 10 22:17:28 volumio volumio[831]: ---------------------------- MPD announces state update: update Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: MPD Database updated - AlbumList cache refreshed Jun 10 22:17:28 volumio volumio[831]: info: Jun 10 22:17:28 volumio volumio[831]: ---------------------------- MPD announces state update: database Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: Jun 10 22:17:28 volumio volumio[831]: ---------------------------- MPD announces state update: update Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 7 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 6 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 5 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 4 milliseconds Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: Command Router : Notfying DB Updatefalse Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: Command Router : Notfying DB Updatefalse Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 70ms Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 70ms Jun 10 22:17:28 volumio volumio[831]: info: MPD Database updated - AlbumList cache refreshed Jun 10 22:17:28 volumio volumio[831]: info: Jun 10 22:17:28 volumio volumio[831]: ---------------------------- MPD announces state update: database Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: Jun 10 22:17:28 volumio volumio[831]: ---------------------------- MPD announces state update: update Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: MPD Database updated - AlbumList cache refreshed Jun 10 22:17:28 volumio volumio[831]: info: Jun 10 22:17:28 volumio volumio[831]: ---------------------------- MPD announces state update: database Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: Jun 10 22:17:28 volumio volumio[831]: ---------------------------- MPD announces state update: update Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::getState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 85 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 84 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 84 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 16 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 17 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 16 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 15 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 14 milliseconds Jun 10 22:17:28 volumio volumio[831]: info: sendMpdCommand status took 14 milliseconds Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: Command Router : Notfying DB Updatefalse Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: Command Router : Notfying DB Updatefalse Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: Command Router : Notfying DB Updatefalse Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: verbose: ControllerMpd::parseState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ControllerMpd::pushState Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::servicePushState Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 10 22:17:28 volumio volumio[831]: verbose: CURRENT POSITION 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState stateService stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: No code Jun 10 22:17:28 volumio volumio[831]: info: CoreStateMachine::pushState Jun 10 22:17:28 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::volumioPushState Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 212ms Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 213ms Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 146ms Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 147ms Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 146ms Jun 10 22:17:28 volumio volumio[831]: info: ------------------------------ 147ms Jun 10 22:17:28 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:32 volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 22:17:34 volumio volumio[831]: info: CoreCommandRouter::volumioGetState Jun 10 22:17:34 volumio volumio[831]: info: CorePlayQueue::getTrack 0 Jun 10 22:17:35 volumio volumio[831]: info: CoreCommandRouter::volumioGetQueue Jun 10 22:17:35 volumio volumio[831]: info: CoreStateMachine::getQueue Jun 10 22:17:35 volumio volumio[831]: info: CorePlayQueue::getQueue Jun 10 22:17:36 volumio volumio[831]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/...And%20You%20Will%20Know%20Us%20By%20The%20Trail%20Of%20Dead/86f05127-66cc-46fd-b12d-fb911dc26d30.jpg' Jun 10 22:17:36 volumio volumio[831]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Agnostic%20Mountain%20Gospel%20Choir/278fedfa-1da0-4f1e-ba84-3ad8a44a54ce.jpg' Jun 10 22:17:36 volumio volumio[831]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Air/875bdd48-2b01-43bf-9eec-acf33e8b7926.jpg' Jun 10 22:17:37 volumio volumio[831]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 22:17:37 volumio volumio[831]: Error: connect ECONNREFUSED 0.0.0.0:80 Jun 10 22:17:37 volumio volumio[831]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 10 22:17:37 volumio volumio[831]: errno: -111, Jun 10 22:17:37 volumio volumio[831]: code: 'ECONNREFUSED', Jun 10 22:17:37 volumio volumio[831]: syscall: 'connect', Jun 10 22:17:37 volumio volumio[831]: address: '0.0.0.0', Jun 10 22:17:37 volumio volumio[831]: port: 80 Jun 10 22:17:37 volumio volumio[831]: } Jun 10 22:17:37 volumio volumio[831]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 22:17:37 volumio sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 22:16 Jun 10 22:17:37 volumio sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"