-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-06-23 16:13:14 CEST. -- Jun 23 16:12:57 volumio systemd-timedated[1739]: Changed local time to Mon Jun 23 14:12:57 2025 Jun 23 16:12:57 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root Jun 23 16:12:57 volumio volumio-time-update[768]: volumio-time-update-util: System time updated successfully. Jun 23 16:12:57 volumio systemd[1]: Starting Daily man-db regeneration... Jun 23 16:12:57 volumio systemd[1]: Starting Daily apt download activities... Jun 23 16:12:57 volumio systemd[1]: Started Volumio Time Update Utility. Jun 23 16:12:57 volumio dhcpcd[1643]: eth0: offered 10.10.10.23 from 10.10.10.1 Jun 23 16:12:57 volumio dhcpcd[1643]: eth0: leased 10.10.10.23 for 864000 seconds Jun 23 16:12:57 volumio dhcpcd[1643]: eth0: adding route to 10.10.10.0/24 Jun 23 16:12:57 volumio dhcpcd[1643]: eth0: adding default route via 10.10.10.1 Jun 23 16:12:57 volumio dhcpcd[1643]: eth0: adding route to 0.2.0.0/-1 Jun 23 16:12:57 volumio dhcpcd[1643]: if_route (ADD): Invalid argument Jun 23 16:12:57 volumio ntpd[1709]: Soliciting pool server 158.180.28.150 Jun 23 16:12:57 volumio smbd[1735]: [2025/06/23 14:12:57.178955, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 23 16:12:57 volumio smbd[1735]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 23 16:12:57 volumio systemd[1]: Started Samba SMB Daemon. Jun 23 16:12:57 volumio systemd[1]: Reached target Multi-User System. Jun 23 16:12:57 volumio systemd[1]: Reached target Graphical Interface. Jun 23 16:12:57 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 23 16:12:57 volumio systemd[1]: man-db.service: Succeeded. Jun 23 16:12:57 volumio systemd[1]: Started Daily man-db regeneration. Jun 23 16:12:57 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 23 16:12:57 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 23 16:12:57 volumio volumio[1008]: info: Discovery: Started advertising with name: Volumio Jun 23 16:12:57 volumio ntpd[1709]: ntpd exiting on signal 15 (Terminated) Jun 23 16:12:57 volumio systemd[1]: Stopping Network Time Service... Jun 23 16:12:57 volumio ntpd[1709]: 85.10.240.253 local addr 10.10.10.23 -> Jun 23 16:12:57 volumio ntpd[1709]: 116.202.118.202 local addr 10.10.10.23 -> Jun 23 16:12:57 volumio ntpd[1709]: 158.180.28.150 local addr 10.10.10.23 -> Jun 23 16:12:57 volumio systemd[1]: ntp.service: Succeeded. Jun 23 16:12:57 volumio systemd[1]: Stopped Network Time Service. Jun 23 16:12:57 volumio nmbd[932]: [2025/06/23 14:12:57.352229, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 23 16:12:57 volumio nmbd[932]: query_name_response: Multiple (2) responses received for a query on subnet 10.10.10.23 for name WORKGROUP<1d>. Jun 23 16:12:57 volumio nmbd[932]: This response was from IP 10.10.10.12, reporting an IP address of 10.10.10.12. Jun 23 16:12:57 volumio systemd[1]: Starting Network Time Service... Jun 23 16:12:57 volumio ntpd[1797]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 23 16:12:57 volumio ntpd[1797]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 23 16:12:57 volumio systemd[1]: Started Network Time Service. Jun 23 16:12:57 volumio ntpd[1806]: proto: precision = 0.238 usec (-22) Jun 23 16:12:57 volumio ntpd[1806]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 23 16:12:57 volumio ntpd[1806]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 23 16:12:57 volumio ntpd[1806]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 909 days ago Jun 23 16:12:57 volumio ntpd[1806]: Listen and drop on 0 v6wildcard [::]:123 Jun 23 16:12:57 volumio ntpd[1806]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 23 16:12:57 volumio ntpd[1806]: Listen normally on 2 lo 127.0.0.1:123 Jun 23 16:12:57 volumio ntpd[1806]: Listen normally on 3 eth0 10.10.10.23:123 Jun 23 16:12:57 volumio ntpd[1806]: Listening on routing socket on fd #20 for interface updates Jun 23 16:12:57 volumio ntpd[1806]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jun 23 16:12:57 volumio ntpd[1806]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jun 23 16:12:57 volumio volumio[1008]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 23 16:12:57 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 23 16:12:57 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 23 16:12:57 volumio sudo[1837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 23 16:12:57 volumio sudo[1837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:12:57 volumio sudo[1837]: pam_unix(sudo:session): session closed for user root Jun 23 16:12:57 volumio volumio-remote-updater[779]: [2025-06-23 14:12:57] [connect] Successful connection Jun 23 16:12:57 volumio volumio-remote-updater[779]: [2025-06-23 14:12:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750687977 101 Jun 23 16:12:57 volumio volumio[1008]: 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: 5 Jun 23 16:12:58 volumio systemd[1]: apt-daily.service: Succeeded. Jun 23 16:12:58 volumio systemd[1]: Started Daily apt download activities. Jun 23 16:12:58 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jun 23 16:12:58 volumio volumio[1008]: info: Volumio Network Manager: Network status updated: 1 Jun 23 16:12:58 volumio ntpd[1806]: Soliciting pool server 85.10.240.253 Jun 23 16:12:58 volumio sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 23 16:12:58 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:12:58 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Jun 23 16:12:58 volumio sudo[1910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 23 16:12:58 volumio sudo[1910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:12:58 volumio sudo[1910]: pam_unix(sudo:session): session closed for user root Jun 23 16:12:58 volumio sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 23 16:12:58 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:12:58 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 23 16:12:58 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Jun 23 16:12:58 volumio volumio[1008]: info: Upmpdcli Daemon Started Jun 23 16:12:58 volumio volumio[1922]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 23 16:12:58 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 23 16:12:58 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 23 16:12:58 volumio systemd[1]: Startup finished in 5.996s (firmware) + 8.319s (loader) + 10.827s (kernel) + 22.820s (userspace) = 47.964s. Jun 23 16:12:59 volumio ntpd[1806]: Soliciting pool server 116.202.118.202 Jun 23 16:12:59 volumio ntpd[1806]: Soliciting pool server 158.180.28.150 Jun 23 16:12:59 volumio volumio[1922]: ...................................++++ Jun 23 16:12:59 volumio volumio[1008]: info: Discovery: adding 88fd5001-c3b6-4bcf-857d-fd8cde6338b1 Jun 23 16:12:59 volumio volumio[1008]: info: Discovery: Found device Volumio Jun 23 16:12:59 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Jun 23 16:12:59 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Jun 23 16:13:00 volumio ntpd[1806]: Soliciting pool server 79.133.44.139 Jun 23 16:13:00 volumio ntpd[1806]: Soliciting pool server 192.248.187.154 Jun 23 16:13:00 volumio ntpd[1806]: Soliciting pool server 212.132.97.26 Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 23 16:13:01 volumio volumio[1008]: info: Adding plugin bluetooth to MyMusic Plugins Jun 23 16:13:01 volumio volumio[1008]: info: Adding plugin multiroom to MyMusic Plugins Jun 23 16:13:01 volumio volumio[1008]: info: Adding plugin metavolumio to MyMusic Plugins Jun 23 16:13:01 volumio volumio[1008]: info: Adding plugin cd_controller to MyMusic Plugins Jun 23 16:13:01 volumio volumio[1008]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 23 16:13:01 volumio volumio[1008]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 23 16:13:01 volumio volumio[1008]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 23 16:13:01 volumio volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 23 16:13:01 volumio volumio[1922]: ..........................................................++++ Jun 23 16:13:01 volumio volumio[1922]: e is 65537 (0x010001) Jun 23 16:13:01 volumio volumio[1922]: writing RSA key Jun 23 16:13:01 volumio ntpd[1806]: Soliciting pool server 128.140.120.189 Jun 23 16:13:01 volumio ntpd[1806]: Soliciting pool server 5.9.193.27 Jun 23 16:13:01 volumio ntpd[1806]: Soliciting pool server 213.209.109.44 Jun 23 16:13:01 volumio ntpd[1806]: Soliciting pool server 176.9.157.155 Jun 23 16:13:02 volumio ntpd[1806]: Soliciting pool server 88.99.86.9 Jun 23 16:13:02 volumio ntpd[1806]: Soliciting pool server 194.59.205.229 Jun 23 16:13:02 volumio ntpd[1806]: Soliciting pool server 162.159.200.1 Jun 23 16:13:03 volumio volumio[1008]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 23 16:13:03 volumio volumio[1008]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 23 16:13:03 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 16:13:03 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 16:13:03 volumio volumio[1008]: info: Starting MyVolumio Remote Streaming Endpoints Jun 23 16:13:03 volumio volumio[1008]: info: MyVolumio not started Jun 23 16:13:03 volumio volumio[1008]: info: Initializing device activation check Jun 23 16:13:03 volumio volumio[1008]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 23 16:13:03 volumio volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 23 16:13:03 volumio ntpd[1806]: Soliciting pool server 193.203.3.171 Jun 23 16:13:03 volumio ntpd[1806]: Soliciting pool server 144.91.126.59 Jun 23 16:13:04 volumio ntpd[1806]: Soliciting pool server 168.119.211.223 Jun 23 16:13:04 volumio ntpd[1806]: Soliciting pool server 2a01:4f8:c2c:1cfb::1 Jun 23 16:13:05 volumio volumio[1008]: info: Starting Streaming Service Transparent Proxy Jun 23 16:13:05 volumio volumio[1008]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 23 16:13:05 volumio volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 23 16:13:05 volumio volumio[1008]: info: Streaming services startup Jun 23 16:13:05 volumio volumio[1008]: info: Starting Streaming Daemon Jun 23 16:13:05 volumio sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 23 16:13:05 volumio sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:05 volumio sudo[2026]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:05 volumio volumio[1008]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 23 16:13:05 volumio volumio[1008]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.816&uuid=ee2fba57b201785680adedc02c31cb22" http://updates.volumio.org/downloader-v1/track-device Jun 23 16:13:05 volumio volumio[1008]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 23 16:13:05 volumio volumio[1008]: Dload Upload Total Spent Left Speed Jun 23 16:13:05 volumio volumio[1008]: [132B blob data] Jun 23 16:13:05 volumio volumio[1008]: retrying in 5 seconds, trial 0 Jun 23 16:13:05 volumio volumio[1008]: info: Volumio Calling Home Jun 23 16:13:05 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 23 16:13:05 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 23 16:13:05 volumio volumio[1008]: info: Refreshing Cached IP Addresses Jun 23 16:13:05 volumio sudo[2036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 23 16:13:05 volumio sudo[2036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:05 volumio sudo[2036]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:05 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 23 16:13:05 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:05 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:05 volumio volumio[1008]: verbose: New Socket.io Connection to 10.10.10.23 from 10.10.10.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 23 16:13:05 volumio sudo[2042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 23 16:13:05 volumio sudo[2042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:05 volumio volumio[1008]: error: Cannot start Volumio Streaming Daemon Jun 23 16:13:05 volumio volumio[1008]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 23 16:13:05 volumio volumio[1008]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 23 16:13:05 volumio sudo[2042]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:05 volumio systemd[1]: upmpdcli.service: Succeeded. Jun 23 16:13:05 volumio volumio[1008]: info: Volumio called home Jun 23 16:13:06 volumio volumio[1008]: STREAMING PROXY: Starting server on port 3245 Jun 23 16:13:06 volumio volumio[1008]: Node JS runtime: 14 Jun 23 16:13:06 volumio ntpd[1806]: receive: Unexpected origin timestamp 0xec03df72.63125458 does not match aorg 0000000000.00000000 from server@88.99.86.9 xmt 0xec03df72.cf33a1af Jun 23 16:13:06 volumio ntpd[1806]: receive: Unexpected origin timestamp 0xec03df72.6321149b does not match aorg 0000000000.00000000 from server@79.133.44.139 xmt 0xec03df72.ce9e715b Jun 23 16:13:06 volumio ntpd[1806]: receive: Unexpected origin timestamp 0xec03df72.631ae399 does not match aorg 0000000000.00000000 from server@128.140.120.189 xmt 0xec03df72.cf91923e Jun 23 16:13:06 volumio ntpd[1806]: receive: Unexpected origin timestamp 0xec03df72.63229625 does not match aorg 0000000000.00000000 from server@158.180.28.150 xmt 0xec03df72.cfd5f6c8 Jun 23 16:13:06 volumio ntpd[1806]: receive: Unexpected origin timestamp 0xec03df72.631f6a82 does not match aorg 0000000000.00000000 from server@213.209.109.44 xmt 0xec03df72.cf42bee5 Jun 23 16:13:07 volumio dbus-daemon[803]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.14' (uid=0 pid=1960 comm="/usr/lib/chromium/chromium --show-component-extens") Jun 23 16:13:07 volumio systemd[1]: Starting Bluetooth service... Jun 23 16:13:08 volumio volumio[1008]: verbose: New Socket.io Connection to 10.10.10.23 from 10.10.10.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Jun 23 16:13:08 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Jun 23 16:13:08 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 23 16:13:08 volumio volumio[1008]: info: Received Get System Info Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 16:13:08 volumio volumio[1008]: info: Discovery: Getting this device information Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Jun 23 16:13:08 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Jun 23 16:13:08 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Jun 23 16:13:08 volumio volumio[1008]: info: Listing playlists Jun 23 16:13:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 23 16:13:08 volumio kernel: Bluetooth: Core ver 2.22 Jun 23 16:13:08 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Jun 23 16:13:08 volumio kernel: Bluetooth: HCI device and connection manager initialized Jun 23 16:13:08 volumio kernel: Bluetooth: HCI socket layer initialized Jun 23 16:13:08 volumio kernel: Bluetooth: L2CAP socket layer initialized Jun 23 16:13:08 volumio kernel: Bluetooth: SCO socket layer initialized Jun 23 16:13:08 volumio bluetoothd[2056]: Bluetooth daemon 5.50 Jun 23 16:13:08 volumio bluetoothd[2056]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 23 16:13:08 volumio dbus-daemon[803]: [system] Successfully activated service 'org.bluez' Jun 23 16:13:08 volumio systemd[1]: Started Bluetooth service. Jun 23 16:13:08 volumio bluetoothd[2056]: Starting SDP server Jun 23 16:13:08 volumio bluetoothd[2056]: Excluding (cli) sap Jun 23 16:13:08 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 23 16:13:08 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jun 23 16:13:08 volumio kernel: Bluetooth: BNEP socket layer initialized Jun 23 16:13:08 volumio bluetoothd[2056]: Bluetooth management interface 1.22 initialized Jun 23 16:13:08 volumio dbus-daemon[803]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.15' (uid=0 pid=2056 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 23 16:13:08 volumio systemd[1]: Starting Hostname Service... Jun 23 16:13:08 volumio dbus-daemon[803]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 23 16:13:08 volumio systemd[1]: Started Hostname Service. Jun 23 16:13:09 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 23 16:13:10 volumio volumio[1008]: 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: 8 Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 23 16:13:10 volumio volumio[1008]: info: Received Get System Info Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 16:13:10 volumio volumio[1008]: info: Discovery: Getting this device information Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Jun 23 16:13:10 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Jun 23 16:13:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 16:13:10 volumio volumio-remote-updater[779]: No test mode Jun 23 16:13:10 volumio volumio-remote-updater[779]: No alpha test mode Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:11 volumio volumio[1008]: info: Retrieving Cloud Streaming UI Jun 23 16:13:11 volumio volumio[1008]: info: Getting Tidal Cloud Configuration Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:11 volumio volumio[1008]: info: Getting Qobuz Cloud Configuration Jun 23 16:13:11 volumio volumio[1008]: info: Asking plugin for UI Config Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:11 volumio volumio[1008]: info: Getting Spotify Cloud Configuration Jun 23 16:13:11 volumio volumio[1008]: info: Asking plugin for UI Config Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:11 volumio volumio[1008]: info: Saving Spotify Acccount Jun 23 16:13:11 volumio volumio[1008]: info: Got Tidal Cloud Configuration Jun 23 16:13:11 volumio volumio[1008]: info: Got it Jun 23 16:13:11 volumio volumio[1008]: info: Got it Jun 23 16:13:11 volumio volumio[1008]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:11 volumio volumio[1008]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 23 16:13:11 volumio volumio[1008]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 23 16:13:11 volumio sudo[2198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 23 16:13:11 volumio sudo[2198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:11 volumio sudo[2198]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:11 volumio sudo[2200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 23 16:13:11 volumio sudo[2200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:11 volumio sudo[2200]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:11 volumio volumio[1008]: verbose: New Socket.io Connection to 10.10.10.23 from 10.10.10.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 23 16:13:11 volumio volumio[1008]: info: Received Get System Info Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 16:13:11 volumio volumio[1008]: info: Discovery: Getting this device information Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Jun 23 16:13:11 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 16:13:11 volumio volumio-remote-updater[779]: No test mode Jun 23 16:13:11 volumio volumio-remote-updater[779]: No alpha test mode Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 23 16:13:11 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:12 volumio volumio[1008]: info: Retrieving Cloud Streaming UI Jun 23 16:13:12 volumio volumio[1008]: info: Getting Tidal Cloud Configuration Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:12 volumio volumio[1008]: info: Getting Qobuz Cloud Configuration Jun 23 16:13:12 volumio volumio[1008]: info: Asking plugin for UI Config Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:12 volumio volumio[1008]: info: Getting Spotify Cloud Configuration Jun 23 16:13:12 volumio volumio[1008]: info: Asking plugin for UI Config Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:12 volumio volumio[1008]: info: Saving Spotify Acccount Jun 23 16:13:12 volumio volumio[1008]: info: Got Tidal Cloud Configuration Jun 23 16:13:12 volumio volumio[1008]: info: Got it Jun 23 16:13:12 volumio volumio[1008]: info: Got it Jun 23 16:13:12 volumio volumio[1008]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 16:13:12 volumio volumio[1008]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 23 16:13:12 volumio volumio[1008]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 23 16:13:12 volumio volumio[1008]: info: Loading i18n strings for locale en Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 23 16:13:12 volumio volumio[1008]: info: Setting timezone to UTC Jun 23 16:13:12 volumio sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 23 16:13:12 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:12 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:12 volumio sudo[2213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Jun 23 16:13:12 volumio sudo[2213]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:12 volumio sudo[2213]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:12 volumio sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 23 16:13:12 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:12 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:12 volumio sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jun 23 16:13:12 volumio sudo[2219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:12 volumio systemd-timedated[1739]: Failed to set time zone: Permission denied Jun 23 16:13:12 volumio sudo[2219]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:12 volumio volumio[1008]: Failed to set time zone: Access denied Jun 23 16:13:12 volumio volumio[1008]: info: Could not set timezone, retrying Jun 23 16:13:12 volumio volumio[1008]: Updating browse sources language Jun 23 16:13:12 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 23 16:13:13 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 23 16:13:13 volumio volumio[1008]: info: Loading i18n strings for locale de Jun 23 16:13:13 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 23 16:13:13 volumio volumio[1008]: info: Setting timezone to Europe/Berlin Jun 23 16:13:13 volumio sudo[2222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 23 16:13:13 volumio sudo[2222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:13 volumio sudo[2222]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:13 volumio sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Jun 23 16:13:13 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:13 volumio sudo[2225]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:13 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 23 16:13:13 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:13 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:13 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Jun 23 16:13:13 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:13 volumio systemd-timedated[1739]: Failed to set time zone: Permission denied Jun 23 16:13:13 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:13 volumio volumio[1008]: Failed to set time zone: Access denied Jun 23 16:13:13 volumio volumio[1008]: info: Could not set timezone, retrying Jun 23 16:13:13 volumio volumio[1008]: info: Fetching Streaming Services browse cache Jun 23 16:13:13 volumio volumio[1008]: Updating browse sources language Jun 23 16:13:13 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 23 16:13:13 volumio volumio[1008]: info: Fetching Streaming Services browse cache Jun 23 16:13:13 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 23 16:13:13 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jun 23 16:13:13 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 16:13:13 volumio systemd-timedated[1739]: Failed to set time zone: Permission denied Jun 23 16:13:13 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Jun 23 16:13:13 volumio volumio[1008]: Failed to set time zone: Access denied Jun 23 16:13:13 volumio volumio[1008]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 16:13:13 volumio volumio[1008]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC' Jun 23 16:13:13 volumio volumio[1008]: Failed to set time zone: Access denied Jun 23 16:13:13 volumio volumio[1008]: at checkExecSyncError (child_process.js:790:11) Jun 23 16:13:13 volumio volumio[1008]: at execSync (child_process.js:863:15) Jun 23 16:13:13 volumio volumio[1008]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9) Jun 23 16:13:13 volumio volumio[1008]: at listOnTimeout (internal/timers.js:557:17) Jun 23 16:13:13 volumio volumio[1008]: at processTimers (internal/timers.js:500:7) { Jun 23 16:13:13 volumio volumio[1008]: status: 1, Jun 23 16:13:13 volumio volumio[1008]: signal: null, Jun 23 16:13:13 volumio volumio[1008]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Jun 23 16:13:13 volumio volumio[1008]: pid: 2234, Jun 23 16:13:13 volumio volumio[1008]: stdout: '', Jun 23 16:13:13 volumio volumio[1008]: stderr: 'Failed to set time zone: Access denied\n' Jun 23 16:13:13 volumio volumio[1008]: } Jun 23 16:13:13 volumio volumio[1008]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 16:13:14 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-23 16:12 Jun 23 16:13:14 volumio sudo[2246]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"