-- Logs begin at Sun 2025-03-16 01:29:52 UTC, end at Sun 2025-03-16 01:32:51 UTC. -- Mar 16 01:32:29 volumio systemd-timedated[1582]: Changed local time to Sun Mar 16 01:32:29 2025 Mar 16 01:32:29 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:29 volumio volumio-time-update[752]: volumio-time-update-util: System time updated successfully. Mar 16 01:32:29 volumio systemd[1]: Started Volumio Time Update Utility. Mar 16 01:32:29 volumio systemd[1]: mpd.service: Succeeded. Mar 16 01:32:29 volumio systemd[1]: Stopped Music Player Daemon. Mar 16 01:32:29 volumio systemd[1]: Starting Music Player Daemon... Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: Volumio Calling Home Mar 16 01:32:29 volumio sudo[1597]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 16 01:32:29 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:29 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:29 volumio nmbd[905]: [2025/03/16 01:32:29.136921, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 01:32:29 volumio nmbd[905]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 16 01:32:29 volumio systemd[1]: Started Samba NMB Daemon. Mar 16 01:32:29 volumio nmbd[905]: [2025/03/16 01:32:29.148249, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 16 01:32:29 volumio nmbd[905]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.0.164 for name WORKGROUP<1d>. Mar 16 01:32:29 volumio nmbd[905]: This response was from IP 10.0.0.9, reporting an IP address of 10.0.0.9. Mar 16 01:32:29 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 16 01:32:29 volumio volumio[992]: info: MPD Permissions set Mar 16 01:32:29 volumio volumio[992]: info: MPD Permissions set Mar 16 01:32:29 volumio volumio[992]: info: Discovery: adding 07176e10-5420-4779-ae27-d778fe7031ab Mar 16 01:32:29 volumio volumio[992]: info: Discovery: Found device Volumio Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Mar 16 01:32:29 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:29 volumio volumio[992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 16 01:32:29 volumio winbindd[1612]: [2025/03/16 01:32:29.444791, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 16 01:32:29 volumio winbindd[1612]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 01:32:29 volumio winbindd[1612]: [2025/03/16 01:32:29.459802, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 01:32:29 volumio winbindd[1612]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 16 01:32:29 volumio systemd[1]: Started Samba Winbind Daemon. Mar 16 01:32:29 volumio systemd[1]: Starting Samba SMB Daemon... Mar 16 01:32:29 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Mar 16 01:32:29 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:29 volumio volumio[992]: info: Starting Shairport Sync Mar 16 01:32:29 volumio volumio[992]: info: Starting Shairport Sync Mar 16 01:32:29 volumio sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 01:32:29 volumio volumio[992]: info: Starting Shairport Sync Mar 16 01:32:29 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 01:32:29 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 16 01:32:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 01:32:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 01:32:29 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:29 volumio sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 01:32:29 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:29 volumio ntpd[1525]: Soliciting pool server 5.79.108.34 Mar 16 01:32:29 volumio sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 01:32:29 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:29 volumio ntpd[1525]: Soliciting pool server 119.28.206.193 Mar 16 01:32:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 01:32:29 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 16 01:32:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 01:32:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 01:32:29 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:29 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 16 01:32:29 volumio volumio[992]: info: CoreStateMachine::getcurrentVolume Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 01:32:29 volumio volumio[992]: info: CoreStateMachine::pushState Mar 16 01:32:29 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::volumioPushState Mar 16 01:32:29 volumio sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 16 01:32:29 volumio sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:29 volumio volumio[992]: info: Shairport-Sync Started Mar 16 01:32:29 volumio sudo[1644]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:29 volumio volumio[992]: Error adding Membership: Error: addMembership EINVAL Mar 16 01:32:29 volumio volumio[992]: info: Shairport-Sync Started Mar 16 01:32:29 volumio volumio[992]: info: Volumio called home Mar 16 01:32:29 volumio volumio[992]: info: Shairport-Sync Started Mar 16 01:32:29 volumio volumio[992]: info: Permissions for /etc/hostapd/hostapd.conf Mar 16 01:32:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 16 01:32:29 volumio volumio[992]: info: Discovery: Restarting Advertising due to device name change Mar 16 01:32:29 volumio sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 16 01:32:29 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:30 volumio systemd[1]: Starting Wireless Services... Mar 16 01:32:30 volumio smbd[1628]: [2025/03/16 01:32:30.107198, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 01:32:30 volumio systemd[1]: Started Samba SMB Daemon. Mar 16 01:32:30 volumio smbd[1628]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 16 01:32:30 volumio mpd[1609]: Mar 16 01:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 16 01:32:30 volumio systemd[1]: Started Music Player Daemon. Mar 16 01:32:30 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:30 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:30 volumio volumio[992]: info: Completed starting Core Plugins Mar 16 01:32:30 volumio volumio[992]: info: ------------------------------------------- Mar 16 01:32:30 volumio volumio[992]: info: ----- MyVolumio plugins startup ---- Mar 16 01:32:30 volumio volumio[992]: info: ------------------------------------------- Mar 16 01:32:30 volumio volumio[992]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 16 01:32:30 volumio wireless.js[1653]: WIRELESS: No wireless interface, exiting Mar 16 01:32:30 volumio iw[1671]: command failed: No such device (-19) Mar 16 01:32:30 volumio volumio[992]: error: MPD error: The expression evaluated to a falsy value: Mar 16 01:32:30 volumio volumio[992]: assert.ok(self.idling) Mar 16 01:32:30 volumio volumio[992]: error: The expression evaluated to a falsy value: Mar 16 01:32:30 volumio volumio[992]: assert.ok(self.idling) Mar 16 01:32:30 volumio volumio[992]: info: MPD running with PID1609 Mar 16 01:32:30 volumio volumio[992]: ,establishing connection Mar 16 01:32:30 volumio volumio[992]: error: updateQueue error: null Mar 16 01:32:30 volumio volumio[992]: error: updateQueue error: null Mar 16 01:32:30 volumio iwconfig[1674]: Error for wireless request "Set Power Management" (8B2C) : Mar 16 01:32:30 volumio iwconfig[1674]: SET failed on device wlan0 ; No such device. Mar 16 01:32:30 volumio systemd[1]: wireless.service: Succeeded. Mar 16 01:32:30 volumio systemd[1]: Started Wireless Services. Mar 16 01:32:30 volumio sudo[1650]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:30 volumio systemd[1]: Reached target Multi-User System. Mar 16 01:32:30 volumio systemd[1]: Reached target Graphical Interface. Mar 16 01:32:30 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 16 01:32:30 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 16 01:32:30 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 16 01:32:30 volumio systemd[1]: Startup finished in 17.390s (firmware) + 8.482s (loader) + 32.149s (kernel) + 16.463s (userspace) = 1min 14.486s. Mar 16 01:32:30 volumio ntpd[1525]: Soliciting pool server 116.203.151.74 Mar 16 01:32:30 volumio ntpd[1525]: Soliciting pool server 202.118.1.130 Mar 16 01:32:30 volumio ntpd[1525]: Soliciting pool server 78.46.102.180 Mar 16 01:32:30 volumio volumio[992]: info: Discovery: A device disappeared from network Mar 16 01:32:30 volumio volumio[992]: info: Discovery: Device volumio disappeared from network Mar 16 01:32:31 volumio ntpd[1525]: Soliciting pool server 185.209.85.222 Mar 16 01:32:31 volumio ntpd[1525]: Soliciting pool server 117.80.112.205 Mar 16 01:32:31 volumio ntpd[1525]: Soliciting pool server 84.16.73.33 Mar 16 01:32:31 volumio ntpd[1525]: Soliciting pool server 193.182.111.14 Mar 16 01:32:32 volumio ntpd[1525]: Soliciting pool server 193.182.111.142 Mar 16 01:32:32 volumio ntpd[1525]: Soliciting pool server 193.182.111.143 Mar 16 01:32:33 volumio ntpd[1525]: Soliciting pool server 108.59.2.24 Mar 16 01:32:34 volumio volumio[992]: info: Discovery: Started advertising with name: Volumio Mar 16 01:32:35 volumio volumio[992]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 16 01:32:35 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 16 01:32:35 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 16 01:32:35 volumio sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 01:32:35 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:35 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:35 volumio volumio-remote-updater[741]: [2025-03-16 01:32:35] [connect] Successful connection Mar 16 01:32:35 volumio volumio-remote-updater[741]: [2025-03-16 01:32:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742088755 101 Mar 16 01:32:35 volumio volumio[992]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Mar 16 01:32:35 volumio volumio[992]: info: Volumio Network Manager: Network status updated: 1 Mar 16 01:32:36 volumio sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 01:32:36 volumio sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:36 volumio volumio[992]: info: Discovery: adding 07176e10-5420-4779-ae27-d778fe7031ab Mar 16 01:32:36 volumio volumio[992]: info: Discovery: Found device Volumio Mar 16 01:32:36 volumio sudo[1701]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:36 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Mar 16 01:32:36 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:36 volumio sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 01:32:36 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:36 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:36 volumio sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 16 01:32:36 volumio sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:36 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 16 01:32:36 volumio sudo[1709]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:36 volumio volumio[992]: info: Upmpdcli Daemon Started Mar 16 01:32:36 volumio volumio[1711]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 16 01:32:39 volumio volumio[992]: info: Adding plugin bluetooth to MyMusic Plugins Mar 16 01:32:39 volumio volumio[992]: info: Adding plugin multiroom to MyMusic Plugins Mar 16 01:32:39 volumio volumio[992]: info: Adding plugin metavolumio to MyMusic Plugins Mar 16 01:32:39 volumio volumio[992]: info: Adding plugin cd_controller to MyMusic Plugins Mar 16 01:32:39 volumio volumio[992]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 16 01:32:39 volumio volumio[992]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 16 01:32:39 volumio volumio[992]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 16 01:32:41 volumio volumio[992]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 16 01:32:41 volumio volumio[992]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 16 01:32:41 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 01:32:41 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 01:32:41 volumio volumio[992]: info: Starting MyVolumio Remote Streaming Endpoints Mar 16 01:32:41 volumio volumio[992]: info: MyVolumio not started Mar 16 01:32:41 volumio volumio[992]: info: Initializing device activation check Mar 16 01:32:41 volumio volumio[992]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 16 01:32:41 volumio volumio[992]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 16 01:32:43 volumio volumio[992]: info: Starting Streaming Service Transparent Proxy Mar 16 01:32:43 volumio volumio[992]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 16 01:32:43 volumio volumio[992]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 16 01:32:43 volumio volumio[992]: info: Streaming services startup Mar 16 01:32:43 volumio volumio[992]: info: Starting Streaming Daemon Mar 16 01:32:43 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 16 01:32:43 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:43 volumio volumio[992]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 16 01:32:43 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:43 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Mar 16 01:32:43 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Mar 16 01:32:43 volumio volumio[992]: info: Refreshing Cached IP Addresses Mar 16 01:32:43 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 16 01:32:43 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 01:32:43 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:43 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:44 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:44 volumio sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 01:32:44 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:44 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:44 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:44 volumio volumio[992]: error: Cannot start Volumio Streaming Daemon Mar 16 01:32:44 volumio volumio[992]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 16 01:32:44 volumio volumio[992]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 16 01:32:44 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 01:32:44 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 01:32:44 volumio volumio[992]: info: Discovery: Getting this device information Mar 16 01:32:44 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Mar 16 01:32:44 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:44 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 01:32:44 volumio volumio[992]: verbose: New Socket.io Connection to 10.0.0.164 from 10.0.0.52 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Mar 16 01:32:44 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Mar 16 01:32:44 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:44 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 16 01:32:44 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 16 01:32:44 volumio volumio[992]: STREAMING PROXY: Starting server on port 3245 Mar 16 01:32:44 volumio volumio[992]: Node JS runtime: 14 Mar 16 01:32:45 volumio dbus-daemon[754]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.16' (uid=0 pid=1733 comm="/usr/lib/chromium/chromium --show-component-extens") Mar 16 01:32:45 volumio systemd[1]: Starting Bluetooth service... Mar 16 01:32:45 volumio bluetoothd[1811]: Bluetooth daemon 5.50 Mar 16 01:32:45 volumio kernel: Bluetooth: Core ver 2.22 Mar 16 01:32:45 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Mar 16 01:32:45 volumio kernel: Bluetooth: HCI device and connection manager initialized Mar 16 01:32:45 volumio kernel: Bluetooth: HCI socket layer initialized Mar 16 01:32:45 volumio kernel: Bluetooth: L2CAP socket layer initialized Mar 16 01:32:45 volumio kernel: Bluetooth: SCO socket layer initialized Mar 16 01:32:45 volumio bluetoothd[1811]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 16 01:32:45 volumio dbus-daemon[754]: [system] Successfully activated service 'org.bluez' Mar 16 01:32:45 volumio systemd[1]: Started Bluetooth service. Mar 16 01:32:45 volumio bluetoothd[1811]: Starting SDP server Mar 16 01:32:45 volumio bluetoothd[1811]: Excluding (cli) sap Mar 16 01:32:45 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 16 01:32:45 volumio kernel: Bluetooth: BNEP filters: protocol multicast Mar 16 01:32:45 volumio kernel: Bluetooth: BNEP socket layer initialized Mar 16 01:32:45 volumio bluetoothd[1811]: Bluetooth management interface 1.22 initialized Mar 16 01:32:45 volumio dbus-daemon[754]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.17' (uid=0 pid=1811 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 16 01:32:46 volumio systemd[1]: Starting Hostname Service... Mar 16 01:32:46 volumio dbus-daemon[754]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 16 01:32:46 volumio systemd[1]: Started Hostname Service. Mar 16 01:32:47 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 16 01:32:47 volumio sudo[1950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 01:32:47 volumio sudo[1950]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:47 volumio sudo[1950]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:47 volumio sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 01:32:47 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:47 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:47 volumio volumio[992]: verbose: New Socket.io Connection to 10.0.0.164 from 10.0.0.52 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Mar 16 01:32:47 volumio volumio[992]: info: CoreCommandRouter::volumioGetVisibleSources Mar 16 01:32:47 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 16 01:32:48 volumio volumio[992]: info: Received Get System Info Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 01:32:48 volumio volumio[992]: info: Discovery: Getting this device information Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Mar 16 01:32:48 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 01:32:48 volumio volumio-remote-updater[741]: No test mode Mar 16 01:32:48 volumio volumio-remote-updater[741]: No alpha test mode Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 01:32:48 volumio volumio[992]: 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 Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::volumioGetVisibleSources Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 16 01:32:48 volumio volumio[992]: info: Received Get System Info Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 01:32:48 volumio volumio[992]: info: Discovery: Getting this device information Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Mar 16 01:32:48 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 01:32:48 volumio volumio-remote-updater[741]: No test mode Mar 16 01:32:48 volumio volumio-remote-updater[741]: No alpha test mode Mar 16 01:32:48 volumio volumio[992]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 01:32:48 volumio volumio[992]: info: Retrieving Cloud Streaming UI Mar 16 01:32:48 volumio volumio[992]: info: Getting Tidal Cloud Configuration Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 01:32:48 volumio volumio[992]: info: Getting Qobuz Cloud Configuration Mar 16 01:32:48 volumio volumio[992]: info: Asking plugin for UI Config Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 01:32:48 volumio volumio[992]: info: Getting Spotify Cloud Configuration Mar 16 01:32:48 volumio volumio[992]: info: Asking plugin for UI Config Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 01:32:48 volumio volumio[992]: info: Saving Spotify Acccount Mar 16 01:32:48 volumio volumio[992]: info: Got Tidal Cloud Configuration Mar 16 01:32:48 volumio volumio[992]: info: Got it Mar 16 01:32:48 volumio volumio[992]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 16 01:32:48 volumio volumio[992]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 01:32:48 volumio volumio[992]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 16 01:32:48 volumio volumio[1711]: .........................................................................................................................................................................................................................................................................................................................................................................................................................++++ Mar 16 01:32:49 volumio volumio[992]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 16 01:32:49 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 16 01:32:49 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 16 01:32:49 volumio volumio[992]: info: Loading i18n strings for locale zh Mar 16 01:32:49 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 16 01:32:49 volumio volumio[992]: info: Setting timezone to Asia/Shanghai Mar 16 01:32:49 volumio sudo[1966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 16 01:32:49 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:49 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:49 volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Mar 16 01:32:49 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:49 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:49 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 16 01:32:49 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:49 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:49 volumio sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 16 01:32:49 volumio sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:49 volumio systemd-timedated[1582]: Failed to set time zone: Permission denied Mar 16 01:32:49 volumio sudo[1975]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:49 volumio volumio[992]: Failed to set time zone: Access denied Mar 16 01:32:49 volumio volumio[992]: info: Could not set timezone, retrying Mar 16 01:32:49 volumio volumio[992]: Updating browse sources language Mar 16 01:32:49 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 01:32:49 volumio volumio[992]: info: Fetching Streaming Services browse cache Mar 16 01:32:49 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 16 01:32:49 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 16 01:32:50 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 16 01:32:50 volumio volumio[992]: info: Loading i18n strings for locale en Mar 16 01:32:50 volumio volumio[992]: Updating browse sources language Mar 16 01:32:50 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 01:32:50 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 16 01:32:50 volumio volumio[992]: info: Setting timezone to UTC Mar 16 01:32:50 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 16 01:32:50 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:50 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:50 volumio sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Mar 16 01:32:50 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:50 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:50 volumio sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 16 01:32:50 volumio sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:50 volumio sudo[1984]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:50 volumio sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Mar 16 01:32:50 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:50 volumio systemd-timedated[1582]: Failed to set time zone: Permission denied Mar 16 01:32:50 volumio sudo[1987]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:50 volumio volumio[992]: Failed to set time zone: Access denied Mar 16 01:32:50 volumio volumio[992]: info: Could not set timezone, retrying Mar 16 01:32:50 volumio sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 16 01:32:50 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 01:32:50 volumio systemd-timedated[1582]: Failed to set time zone: Permission denied Mar 16 01:32:50 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root Mar 16 01:32:50 volumio volumio[992]: Failed to set time zone: Access denied Mar 16 01:32:50 volumio volumio[992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 01:32:50 volumio volumio[992]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Asia/Shanghai' Mar 16 01:32:50 volumio volumio[992]: Failed to set time zone: Access denied Mar 16 01:32:50 volumio volumio[992]: at checkExecSyncError (child_process.js:790:11) Mar 16 01:32:50 volumio volumio[992]: at execSync (child_process.js:863:15) Mar 16 01:32:50 volumio volumio[992]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Mar 16 01:32:50 volumio volumio[992]: at listOnTimeout (internal/timers.js:557:17) Mar 16 01:32:50 volumio volumio[992]: at processTimers (internal/timers.js:500:7) { Mar 16 01:32:50 volumio volumio[992]: status: 1, Mar 16 01:32:50 volumio volumio[992]: signal: null, Mar 16 01:32:50 volumio volumio[992]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Mar 16 01:32:50 volumio volumio[992]: pid: 1989, Mar 16 01:32:50 volumio volumio[992]: stdout: '', Mar 16 01:32:50 volumio volumio[992]: stderr: 'Failed to set time zone: Access denied\n' Mar 16 01:32:50 volumio volumio[992]: } Mar 16 01:32:50 volumio volumio[992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 01:32:51 volumio sudo[2002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 01:31 Mar 16 01:32:51 volumio sudo[2002]: 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="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"