-- Logs begin at Thu 2025-05-01 23:59:52 CST, end at Fri 2025-05-02 00:01:52 CST. -- May 02 00:00:00 volumio volumio[1088]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 02 00:00:00 volumio volumio[1088]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:00 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 00:00:00 volumio volumio[1088]: info: Received Get System Info May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 00:00:00 volumio volumio[1088]: info: Discovery: Getting this device information May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:00 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:00 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:00 volumio volumio[1088]: info: Listing playlists May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 02 00:00:00 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 00:00:01 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:01 volumio dhcpcd[1015]: eth0: using static address 192.168.3.38/24 May 02 00:00:01 volumio dhcpcd[1015]: eth0: adding route to 192.168.3.0/24 May 02 00:00:01 volumio dhcpcd[1015]: eth0: adding default route via 192.168.3.1 May 02 00:00:01 volumio avahi-daemon[831]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.38. May 02 00:00:01 volumio avahi-daemon[831]: New relevant interface eth0.IPv4 for mDNS. May 02 00:00:01 volumio avahi-daemon[831]: Registering new address record for 192.168.3.38 on eth0.IPv4. May 02 00:00:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 02 00:00:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 00:00:02 volumio volumio[1088]: info: Received Get System Info May 02 00:00:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 00:00:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 00:00:02 volumio volumio[1088]: info: Discovery: Getting this device information May 02 00:00:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 00:00:02 volumio volumio[1088]: info: Discovery: adding fb01895a-ebae-453f-bcee-4c834f3efed2 May 02 00:00:02 volumio volumio[1088]: info: Discovery: Found device Volumio May 02 00:00:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:03 volumio ntpd[1055]: Listen normally on 3 eth0 192.168.3.38:123 May 02 00:00:03 volumio ntpd[1055]: new interface(s) found: waking up resolver May 02 00:00:03 volumio systemd[1]: Starting Daily man-db regeneration... May 02 00:00:03 volumio systemd[1]: man-db.service: Succeeded. May 02 00:00:03 volumio systemd[1]: Started Daily man-db regeneration. May 02 00:00:03 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:03 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:03 volumio volumio-time-update[818]: volumio-time-update-util: Fetching time from Volumio... May 02 00:00:04 volumio nmbd[1024]: [2025/05/02 00:00:04.262835, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 02 00:00:04 volumio nmbd[1024]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 02 00:00:04 volumio systemd[1]: Started Samba NMB Daemon. May 02 00:00:04 volumio systemd[1]: Starting Samba Winbind Daemon... May 02 00:00:04 volumio winbindd[1715]: [2025/05/02 00:00:04.334550, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 02 00:00:04 volumio winbindd[1715]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 02 00:00:04 volumio winbindd[1715]: [2025/05/02 00:00:04.349439, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 02 00:00:04 volumio winbindd[1715]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 02 00:00:04 volumio systemd[1]: Started Samba Winbind Daemon. May 02 00:00:04 volumio systemd[1]: Starting Samba SMB Daemon... May 02 00:00:04 volumio smbd[1723]: [2025/05/02 00:00:04.473572, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 02 00:00:04 volumio smbd[1723]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 02 00:00:04 volumio systemd[1]: Started Samba SMB Daemon. May 02 00:00:04 volumio volumio-time-update[818]: volumio-time-update-util: Setting system time to: 2025-05-02 00:00:03 May 02 00:00:04 volumio sudo[1732]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-02 00:00:03 May 02 00:00:04 volumio sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 00:00:04 volumio dbus-daemon[817]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=1733 comm="timedatectl set-time 2025-05-02 00:00:03 ") May 02 00:00:04 volumio systemd[1]: Starting Time & Date Service... May 02 00:00:04 volumio dbus-daemon[817]: [system] Successfully activated service 'org.freedesktop.timedate1' May 02 00:00:04 volumio systemd[1]: Started Time & Date Service. May 02 00:00:03 volumio systemd-timedated[1734]: Changed local time to Fri May 2 00:00:03 2025 May 02 00:00:03 volumio sudo[1732]: pam_unix(sudo:session): session closed for user root May 02 00:00:03 volumio volumio-time-update[818]: volumio-time-update-util: System time updated successfully. May 02 00:00:03 volumio systemd[1]: Started Volumio Time Update Utility. May 02 00:00:03 volumio systemd[1]: Reached target Multi-User System. May 02 00:00:03 volumio systemd[1]: Reached target Graphical Interface. May 02 00:00:03 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 02 00:00:03 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 02 00:00:03 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 02 00:00:03 volumio systemd[1]: Startup finished in 6.757s (kernel) + 12.630s (userspace) = 19.388s. May 02 00:00:04 volumio volumio[1088]: info: Volumio Network Manager: Network status updated: 1 May 02 00:00:04 volumio sudo[1934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 00:00:04 volumio sudo[1934]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 00:00:04 volumio sudo[1934]: pam_unix(sudo:session): session closed for user root May 02 00:00:04 volumio sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 00:00:04 volumio sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 00:00:04 volumio sudo[1937]: pam_unix(sudo:session): session closed for user root May 02 00:00:04 volumio sudo[1940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 02 00:00:04 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 00:00:04 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 02 00:00:04 volumio sudo[1940]: pam_unix(sudo:session): session closed for user root May 02 00:00:04 volumio volumio[1088]: info: Upmpdcli Daemon Started May 02 00:00:04 volumio volumio[1942]: Generating RSA private key, 4096 bit long modulus (2 primes) May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 02 00:00:04 volumio volumio[1088]: info: Adding plugin bluetooth to MyMusic Plugins May 02 00:00:04 volumio volumio[1088]: info: Adding plugin multiroom to MyMusic Plugins May 02 00:00:04 volumio volumio[1088]: info: Adding plugin metavolumio to MyMusic Plugins May 02 00:00:04 volumio volumio[1088]: info: Adding plugin cd_controller to MyMusic Plugins May 02 00:00:04 volumio volumio[1088]: info: Adding plugin smart_inputs to MyMusic Plugins May 02 00:00:04 volumio volumio[1088]: info: Adding plugin tidalconnect to MyMusic Plugins May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 02 00:00:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 00:00:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 00:00:04 volumio volumio[1088]: info: Starting MyVolumio Remote Streaming Endpoints May 02 00:00:04 volumio volumio[1088]: info: MyVolumio login type: Token May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 02 00:00:04 volumio volumio[1088]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 02 00:00:05 volumio volumio[1088]: info: Starting Streaming Service Transparent Proxy May 02 00:00:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 02 00:00:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 02 00:00:05 volumio volumio[1088]: info: Streaming services startup May 02 00:00:05 volumio volumio[1088]: info: Starting Streaming Daemon May 02 00:00:05 volumio sudo[1966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 02 00:00:05 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 00:00:05 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root May 02 00:00:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 02 00:00:05 volumio volumio[1088]: error: Cannot start Volumio Streaming Daemon May 02 00:00:05 volumio volumio[1088]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 02 00:00:05 volumio volumio[1088]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 02 00:00:05 volumio volumio[1088]: STREAMING PROXY: Starting server on port 3245 May 02 00:00:05 volumio volumio[1088]: Node JS runtime: 14 May 02 00:00:05 volumio volumio[1088]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.804&uuid=ddb00b89aa503a426ff703107d7a8796" http://updates.volumio.org/downloader-v1/track-device May 02 00:00:05 volumio volumio[1088]: % Total % Received % Xferd Average Speed Time Time Time Current May 02 00:00:05 volumio volumio[1088]: Dload Upload Total Spent Left Speed May 02 00:00:05 volumio volumio[1088]: [132B blob data] May 02 00:00:05 volumio volumio[1088]: retrying in 5 seconds, trial 0 May 02 00:00:05 volumio volumio[1088]: info: Volumio Calling Home May 02 00:00:05 volumio volumio[1942]: ..........................................................................................................................................................................................................................................................................................................................................++++ May 02 00:00:05 volumio volumio[1942]: .......++++ May 02 00:00:05 volumio volumio[1942]: e is 65537 (0x010001) May 02 00:00:05 volumio volumio[1942]: writing RSA key May 02 00:00:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 00:00:09 volumio volumio[1088]: info: Volumio called home May 02 00:00:13 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:13 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 00:00:19 volumio volumio[1088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 02 00:00:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:21 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 02 00:00:22 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 02 00:00:24 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:24 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 00:00:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 00:00:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 02 00:00:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 02 00:00:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 02 00:00:24 volumio volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources May 02 00:00:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 00:00:25 volumio volumio[1088]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 02 00:00:25 volumio volumio[1088]: info: Completed starting MyVolumio Plugin May 02 00:00:25 volumio volumio[1088]: [Metrics] CommandRouter: 31s 538.76ms May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::volumiosetStartupVolume May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 00:00:25 volumio volumio-remote-updater[829]: No test mode May 02 00:00:25 volumio volumio-remote-updater[829]: No alpha test mode May 02 00:00:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 02 00:00:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 02 00:00:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 00:00:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 02 00:00:28 volumio volumio[1088]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 02 00:00:28 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 02 00:00:32 volumio volumio[1088]: info: BOOT COMPLETED May 02 00:00:33 volumio systemd[1]: systemd-timedated.service: Succeeded. May 02 00:00:35 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:35 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:35 volumio volumio[1088]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 02 00:00:35 volumio volumio[1088]: info: Retrying Login Due to Network Error May 02 00:00:45 volumio volumio[1088]: info: MyVolumio login type: Token May 02 00:00:46 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:46 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:57 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:00:57 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:00:57 volumio ntpd[1055]: Soliciting pool server 193.182.111.142 May 02 00:00:58 volumio ntpd[1055]: Soliciting pool server 45.76.221.157 May 02 00:01:00 volumio volumio[1088]: info: Clearing queue after UPNP request May 02 00:01:00 volumio ntpd[1055]: Soliciting pool server 45.76.221.157 May 02 00:01:00 volumio ntpd[1055]: Soliciting pool server 119.28.206.193 May 02 00:01:00 volumio volumio[1088]: info: CoreStateMachine::ClearQueue May 02 00:01:00 volumio volumio[1088]: info: CoreStateMachine::stop May 02 00:01:00 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 00:01:00 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue May 02 00:01:00 volumio volumio[1088]: info: CorePlayQueue::saveQueue May 02 00:01:00 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 02 00:01:00 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue May 02 00:01:00 volumio volumio[1088]: info: May 02 00:01:00 volumio volumio[1088]: ---------------------------- MPD announces system playlist update May 02 00:01:00 volumio volumio[1088]: info: Ignoring MPD Status Update May 02 00:01:00 volumio volumio[1088]: error: updateQueue error: null May 02 00:01:00 volumio volumio[1088]: info: ------------------------------ 1ms May 02 00:01:00 volumio volumio[1088]: info: Starting UPNP Playback May 02 00:01:00 volumio volumio[1088]: info: Preparing playback through UPNP May 02 00:01:00 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:01:00 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:01:00 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 00:01:01 volumio volumio[1088]: info: May 02 00:01:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update May 02 00:01:01 volumio volumio[1088]: info: Ignoring MPD Status Update May 02 00:01:01 volumio volumio[1088]: info: ------------------------------ 0ms May 02 00:01:01 volumio volumio[1088]: info: May 02 00:01:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update May 02 00:01:01 volumio volumio[1088]: info: Ignoring MPD Status Update May 02 00:01:01 volumio volumio[1088]: info: ------------------------------ 1ms May 02 00:01:01 volumio volumio[1088]: info: May 02 00:01:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update May 02 00:01:01 volumio volumio[1088]: info: Ignoring MPD Status Update May 02 00:01:01 volumio volumio[1088]: info: ------------------------------ 0ms May 02 00:01:01 volumio volumio[1088]: info: May 02 00:01:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update May 02 00:01:01 volumio volumio[1088]: info: Ignoring MPD Status Update May 02 00:01:01 volumio volumio[1088]: info: ------------------------------ 1ms May 02 00:01:01 volumio volumio[1088]: info: May 02 00:01:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update May 02 00:01:01 volumio volumio[1088]: info: Ignoring MPD Status Update May 02 00:01:01 volumio volumio[1088]: info: ------------------------------ 0ms May 02 00:01:01 volumio volumio[1088]: info: May 02 00:01:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update May 02 00:01:01 volumio volumio[1088]: info: Ignoring MPD Status Update May 02 00:01:01 volumio volumio[1088]: info: ------------------------------ 1ms May 02 00:01:01 volumio ntpd[1055]: Soliciting pool server 193.182.111.12 May 02 00:01:01 volumio volumio[1088]: info: May 02 00:01:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player May 02 00:01:01 volumio volumio[1088]: info: ControllerMpd::getState May 02 00:01:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 02 00:01:02 volumio volumio[1088]: info: sendMpdCommand status took 34 milliseconds May 02 00:01:02 volumio volumio[1088]: verbose: ControllerMpd::parseState May 02 00:01:02 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 00:01:02 volumio volumio[1088]: info: May 02 00:01:02 volumio volumio[1088]: ---------------------------- MPD announces system playlist update May 02 00:01:02 volumio volumio[1088]: info: Ignoring MPD Status Update May 02 00:01:02 volumio volumio[1088]: info: May 02 00:01:02 volumio volumio[1088]: ---------------------------- MPD announces state update: player May 02 00:01:02 volumio volumio[1088]: info: ControllerMpd::getState May 02 00:01:02 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 02 00:01:02 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds May 02 00:01:02 volumio volumio[1088]: info: ------------------------------ 1ms May 02 00:01:02 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds May 02 00:01:02 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo May 02 00:01:02 volumio volumio[1088]: verbose: ControllerMpd::parseState May 02 00:01:02 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 00:01:02 volumio volumio[1088]: info: ControllerMpd::pushState May 02 00:01:02 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 02 00:01:02 volumio volumio[1088]: verbose: In UPNP mode May 02 00:01:02 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"恋曲1990","artist":"雷婷","album":"婷婷醉歌","uri":"http://fsandroid.kugou.com/202505020001/8178b5189e7c8f76716e588cda960a4d/v3/cd67bcd23698b283f049e15bef8bc240/yp/full/ap1005_us997964742_df0iodf03rd2bw1n1mcs3slqk2_pi2_mx28254114_quviper_tape_s1018478174.mp3","trackType":"mp3"} May 02 00:01:02 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 02 00:01:02 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play May 02 00:01:02 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop May 02 00:01:02 volumio volumio[1088]: info: ------------------------------ 40ms May 02 00:01:02 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds May 02 00:01:02 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo May 02 00:01:02 volumio volumio[1088]: info: ControllerMpd::pushState May 02 00:01:02 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 02 00:01:02 volumio volumio[1088]: verbose: In UPNP mode May 02 00:01:02 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"恋曲1990","artist":"雷婷","album":"婷婷醉歌","uri":"http://fsandroid.kugou.com/202505020001/8178b5189e7c8f76716e588cda960a4d/v3/cd67bcd23698b283f049e15bef8bc240/yp/full/ap1005_us997964742_df0iodf03rd2bw1n1mcs3slqk2_pi2_mx28254114_quviper_tape_s1018478174.mp3","trackType":"mp3"} May 02 00:01:02 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 02 00:01:02 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play May 02 00:01:02 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play May 02 00:01:02 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload May 02 00:01:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 02 00:01:02 volumio volumio[1088]: info: CoreStateMachine::pushState May 02 00:01:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 00:01:02 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 02 00:01:02 volumio volumio[1088]: info: CoreStateMachine::pushState May 02 00:01:02 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 02 00:01:02 volumio volumio[1088]: info: ------------------------------ 10ms May 02 00:01:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue May 02 00:01:02 volumio volumio[1088]: info: CoreStateMachine::getQueue May 02 00:01:02 volumio volumio[1088]: info: CorePlayQueue::getQueue May 02 00:01:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue May 02 00:01:02 volumio volumio[1088]: info: CoreStateMachine::getQueue May 02 00:01:02 volumio volumio[1088]: info: CorePlayQueue::getQueue May 02 00:01:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:01:08 volumio sudo[2276]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 02 00:01:08 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 00:01:08 volumio volumio[1088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 May 02 00:01:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:01:09 volumio volumio[1088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 May 02 00:01:09 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 02 00:01:15 volumio volumio[1088]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 02 00:01:15 volumio volumio[1088]: info: Retrying Login Due to Network Error May 02 00:01:25 volumio volumio[1088]: info: MyVolumio login type: Token May 02 00:01:52 volumio volumio[1088]: info: May 02 00:01:52 volumio volumio[1088]: ---------------------------- MPD announces state update: player May 02 00:01:52 volumio volumio[1088]: info: ControllerMpd::getState May 02 00:01:52 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 02 00:01:52 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds May 02 00:01:52 volumio volumio[1088]: verbose: ControllerMpd::parseState May 02 00:01:52 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 00:01:52 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds May 02 00:01:52 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo May 02 00:01:52 volumio volumio[1088]: info: ControllerMpd::pushState May 02 00:01:52 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 02 00:01:52 volumio volumio[1088]: info: CoreStateMachine::pushState May 02 00:01:52 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 02 00:01:52 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 02 00:01:52 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"恋曲1990","artist":"雷婷","album":"婷婷醉歌","uri":"http://fsandroid.kugou.com/202505020001/8178b5189e7c8f76716e588cda960a4d/v3/cd67bcd23698b283f049e15bef8bc240/yp/full/ap1005_us997964742_df0iodf03rd2bw1n1mcs3slqk2_pi2_mx28254114_quviper_tape_s1018478174.mp3","trackType":"mp3"} May 02 00:01:52 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 02 00:01:52 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop May 02 00:01:52 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play May 02 00:01:52 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 02 00:01:52 volumio volumio[1088]: info: CoreStateMachine::stPlaybackTimer May 02 00:01:52 volumio volumio[1088]: info: ------------------------------ 7ms May 02 00:01:52 volumio volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 00:01:52 volumio volumio[1088]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' May 02 00:01:52 volumio volumio[1088]: at Object.readdirSync (fs.js:1048:3) May 02 00:01:52 volumio volumio[1088]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) May 02 00:01:52 volumio volumio[1088]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) May 02 00:01:52 volumio volumio[1088]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) May 02 00:01:52 volumio volumio[1088]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) May 02 00:01:52 volumio volumio[1088]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) May 02 00:01:52 volumio volumio[1088]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 02 00:01:52 volumio volumio[1088]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) May 02 00:01:52 volumio volumio[1088]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) May 02 00:01:52 volumio volumio[1088]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) May 02 00:01:52 volumio volumio[1088]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 02 00:01:52 volumio volumio[1088]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) May 02 00:01:52 volumio volumio[1088]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) May 02 00:01:52 volumio volumio[1088]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 02 00:01:52 volumio volumio[1088]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) May 02 00:01:52 volumio volumio[1088]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { May 02 00:01:52 volumio volumio[1088]: errno: -20, May 02 00:01:52 volumio volumio[1088]: syscall: 'scandir', May 02 00:01:52 volumio volumio[1088]: code: 'ENOTDIR', May 02 00:01:52 volumio volumio[1088]: path: '/tmp/peppyrunning' May 02 00:01:52 volumio volumio[1088]: } May 02 00:01:52 volumio volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 00:01:52 volumio sudo[2693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 00:00 May 02 00:01:52 volumio sudo[2693]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"