-- Logs begin at Fri 2020-11-20 18:48:08 UTC, end at Fri 2025-04-18 16:16:33 UTC. -- Apr 18 16:16:00 volumio systemd-timedated[1924]: Changed local time to Fri Apr 18 16:16:00 2025 Apr 18 16:16:00 volumio sudo[1918]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: A device disappeared from network Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Device hdmi-i2s-vol disappeared from network Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Disconnected from remote: 10.0.0.22 Apr 18 16:16:00 volumio systemd[1]: Starting Daily man-db regeneration... Apr 18 16:16:00 volumio systemd[1]: Starting Daily apt download activities... Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: A device disappeared from network Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Device titan disappeared from network Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Disconnected from remote: 10.0.0.19 Apr 18 16:16:00 volumio volumio-time-update[573]: volumio-time-update-util: System time updated successfully. Apr 18 16:16:00 volumio systemd[1]: Started Volumio Time Update Utility. Apr 18 16:16:00 volumio systemd[1]: man-db.service: Succeeded. Apr 18 16:16:00 volumio systemd[1]: Started Daily man-db regeneration. Apr 18 16:16:00 volumio sudo[1905]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:00 volumio wireless.js[1769]: Start wireless flow Apr 18 16:16:00 volumio wireless.js[1769]: Stopped hotspot (if there).. Apr 18 16:16:00 volumio sudo[1956]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 18 16:16:00 volumio sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:00 volumio sudo[1956]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: adding fe8c3697-6689-40d4-a433-42a50dda70c8 Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Found device Hdmi-I2S-Vol Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Connecting to remote: 10.0.0.22 Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Connected to remote: 10.0.0.22 Apr 18 16:16:00 volumio sudo[1976]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 18 16:16:00 volumio sudo[1976]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:00 volumio ntpd[1784]: Soliciting pool server 193.57.144.50 Apr 18 16:16:00 volumio dhcpcd[1764]: wlan0: carrier lost Apr 18 16:16:00 volumio sudo[1976]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: adding 3075d3ef-0d50-40ee-a088-14c9cbc465b8 Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Found device Titan Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Connecting to remote: 10.0.0.19 Apr 18 16:16:00 volumio volumio[1004]: info: Discovery: Connected to remote: 10.0.0.19 Apr 18 16:16:00 volumio wireless.js[1769]: DHCP IP Apr 18 16:16:00 volumio ntpd[1784]: Soliciting pool server 176.58.109.199 Apr 18 16:16:00 volumio wireless.js[1769]: Start ap Apr 18 16:16:00 volumio wpa_supplicant[1998]: Successfully initialized wpa_supplicant Apr 18 16:16:00 volumio sudo[2001]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 18 16:16:00 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:00 volumio dhcpcd[2023]: sending commands to master dhcpcd process Apr 18 16:16:00 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:00 volumio dhcpcd[1764]: wlan0: carrier acquired Apr 18 16:16:00 volumio dhcpcd[1764]: wlan0: IAID 55:e6:73:f7 Apr 18 16:16:00 volumio dhcpcd[1764]: control command: /sbin/dhcpcd Apr 18 16:16:00 volumio dhcpcd[1764]: wlan0: carrier lost Apr 18 16:16:00 volumio systemd[1]: apt-daily.service: Succeeded. Apr 18 16:16:00 volumio systemd[1]: Started Daily apt download activities. Apr 18 16:16:00 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Apr 18 16:16:00 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 18 16:16:00 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 18 16:16:01 volumio ntpd[1784]: Soliciting pool server 149.22.220.130 Apr 18 16:16:01 volumio ntpd[1784]: Soliciting pool server 162.159.200.123 Apr 18 16:16:01 volumio wireless.js[1769]: trying... Apr 18 16:16:01 volumio sudo[2125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:01 volumio sudo[2125]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:01 volumio sudo[2125]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:01 volumio volumio[1004]: info: Discovery: Started advertising with name: Volumio Apr 18 16:16:01 volumio ntpd[1784]: Soliciting pool server 77.104.162.218 Apr 18 16:16:02 volumio volumio[1004]: info: Discovery: adding 200d5760-cff0-46cf-ba89-017052be2795 Apr 18 16:16:02 volumio volumio[1004]: info: Discovery: Found device Volumio Apr 18 16:16:02 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Apr 18 16:16:02 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Apr 18 16:16:02 volumio volumio[1004]: verbose: New Socket.io Connection to 10.0.0.28:3000 from 10.0.0.22 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 62 Apr 18 16:16:02 volumio volumio[1004]: verbose: New Socket.io Connection to 10.0.0.28:3000 from 10.0.0.19 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 63 Apr 18 16:16:02 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 18 16:16:02 volumio ntpd[1784]: Soliciting pool server 178.62.250.107 Apr 18 16:16:02 volumio ntpd[1784]: Soliciting pool server 185.103.117.60 Apr 18 16:16:02 volumio ntpd[1784]: Soliciting pool server 176.58.115.34 Apr 18 16:16:02 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 18 16:16:02 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Apr 18 16:16:02 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Apr 18 16:16:02 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Apr 18 16:16:02 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Apr 18 16:16:02 volumio wireless.js[1769]: trying... Apr 18 16:16:02 volumio sudo[2138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:02 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:02 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:02 volumio ntpd[1784]: Soliciting pool server 51.89.151.183 Apr 18 16:16:02 volumio volumio[1004]: info: Discovery: Started advertising with name: Volumio Apr 18 16:16:02 volumio volumio[1004]: info: Volumio Network Manager: Network status updated: 1 Apr 18 16:16:03 volumio volumio[1004]: info: Discovery: Started advertising with name: Volumio Apr 18 16:16:03 volumio volumio[1004]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 18 16:16:03 volumio sudo[2164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 16:16:03 volumio sudo[2166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 16:16:03 volumio sudo[2164]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:03 volumio sudo[2164]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:03 volumio sudo[2166]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:03 volumio sudo[2172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 18 16:16:03 volumio sudo[2166]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:03 volumio sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:03 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 18 16:16:03 volumio ntpd[1784]: Soliciting pool server 93.93.131.118 Apr 18 16:16:03 volumio ntpd[1784]: Soliciting pool server 131.111.8.61 Apr 18 16:16:03 volumio ntpd[1784]: Soliciting pool server 131.111.8.63 Apr 18 16:16:03 volumio sudo[2172]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:03 volumio volumio[1004]: info: Upmpdcli Daemon Started Apr 18 16:16:03 volumio wireless.js[1769]: trying... Apr 18 16:16:03 volumio sudo[2208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:03 volumio sudo[2208]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:03 volumio volumio[2190]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 18 16:16:03 volumio sudo[2208]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:03 volumio volumio[2190]: ....++++ Apr 18 16:16:03 volumio volumio[2190]: .++++ Apr 18 16:16:03 volumio volumio[2190]: e is 65537 (0x010001) Apr 18 16:16:03 volumio volumio[2190]: writing RSA key Apr 18 16:16:04 volumio ntpd[1784]: Soliciting pool server 129.250.35.250 Apr 18 16:16:04 volumio ntpd[1784]: Soliciting pool server 178.62.18.76 Apr 18 16:16:04 volumio wireless.js[1769]: trying... Apr 18 16:16:04 volumio sudo[2239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:04 volumio sudo[2239]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:04 volumio sudo[2239]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:05 volumio ntpd[1784]: Soliciting pool server 131.111.8.60 Apr 18 16:16:05 volumio ntpd[1784]: Soliciting pool server 2a01:7e00::f03c:91ff:fe89:410f Apr 18 16:16:05 volumio wireless.js[1769]: trying... Apr 18 16:16:05 volumio sudo[2250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:05 volumio sudo[2250]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:05 volumio sudo[2250]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:05 volumio volumio[1004]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=tinkerboard&variante=volumio&version=3.799&uuid=7524d0943cd5063cc2dc2b67ae807c03" http://updates.volumio.org/downloader-v1/track-device Apr 18 16:16:05 volumio volumio[1004]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 18 16:16:05 volumio volumio[1004]: Dload Upload Total Spent Left Speed Apr 18 16:16:05 volumio volumio[1004]: [132B blob data] Apr 18 16:16:05 volumio volumio[1004]: retrying in 5 seconds, trial 0 Apr 18 16:16:05 volumio volumio[1004]: info: Volumio Calling Home Apr 18 16:16:05 volumio volumio[1004]: info: Volumio called home Apr 18 16:16:06 volumio ntpd[1784]: receive: Unexpected origin timestamp 0xebacf946.48a84627 does not match aorg 0000000000.00000000 from server@77.104.162.218 xmt 0xebacf946.837ee558 Apr 18 16:16:06 volumio wireless.js[1769]: trying... Apr 18 16:16:06 volumio sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:06 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:06 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 18 16:16:06 volumio volumio[1004]: info: Adding plugin bluetooth to MyMusic Plugins Apr 18 16:16:06 volumio volumio[1004]: info: Adding plugin multiroom to MyMusic Plugins Apr 18 16:16:06 volumio volumio[1004]: info: Adding plugin metavolumio to MyMusic Plugins Apr 18 16:16:06 volumio volumio[1004]: info: Adding plugin cd_controller to MyMusic Plugins Apr 18 16:16:06 volumio volumio[1004]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 18 16:16:06 volumio volumio[1004]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 18 16:16:06 volumio volumio[1004]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.362431, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 18 16:16:07 volumio nmbd[787]: Packet send failed to 192.168.211.255(138) ERRNO=Invalid argument Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.362723, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 18 16:16:07 volumio nmbd[787]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.362800, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 18 16:16:07 volumio nmbd[787]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.362868, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 18 16:16:07 volumio nmbd[787]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.362934, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 18 16:16:07 volumio nmbd[787]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.363005, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 18 16:16:07 volumio nmbd[787]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.363072, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 18 16:16:07 volumio nmbd[787]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.363135, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 18 16:16:07 volumio nmbd[787]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.363201, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 18 16:16:07 volumio nmbd[787]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.363271, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 18 16:16:07 volumio nmbd[787]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Apr 18 16:16:07 volumio nmbd[787]: [2025/04/18 16:16:07.363340, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 18 16:16:07 volumio nmbd[787]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 18 16:16:07 volumio wireless.js[1769]: trying... Apr 18 16:16:07 volumio sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:07 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:07 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:07 volumio volumio[1004]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 18 16:16:07 volumio volumio[1004]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 18 16:16:07 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 16:16:07 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 16:16:07 volumio volumio[1004]: info: Starting MyVolumio Remote Streaming Endpoints Apr 18 16:16:07 volumio volumio[1004]: info: MyVolumio not started Apr 18 16:16:07 volumio volumio[1004]: info: Initializing device activation check Apr 18 16:16:07 volumio volumio[1004]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 18 16:16:07 volumio volumio[1004]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 18 16:16:08 volumio volumio[1004]: info: Starting Streaming Service Transparent Proxy Apr 18 16:16:08 volumio volumio[1004]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 18 16:16:08 volumio volumio[1004]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 18 16:16:08 volumio volumio[1004]: info: Streaming services startup Apr 18 16:16:08 volumio volumio[1004]: info: Starting Streaming Daemon Apr 18 16:16:08 volumio sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 18 16:16:08 volumio sudo[2314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:08 volumio volumio[1004]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 18 16:16:08 volumio sudo[2314]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:08 volumio volumio[1004]: error: Cannot start Volumio Streaming Daemon Apr 18 16:16:08 volumio volumio[1004]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 18 16:16:08 volumio volumio[1004]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 18 16:16:08 volumio wireless.js[1769]: trying... Apr 18 16:16:08 volumio sudo[2330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:08 volumio sudo[2330]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:08 volumio sudo[2330]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:09 volumio volumio[1004]: STREAMING PROXY: Starting server on port 3245 Apr 18 16:16:09 volumio volumio[1004]: Node JS runtime: 14 Apr 18 16:16:09 volumio wireless.js[1769]: trying... Apr 18 16:16:09 volumio sudo[2340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:09 volumio sudo[2340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:09 volumio sudo[2340]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:10 volumio wireless.js[1769]: trying... Apr 18 16:16:10 volumio sudo[2355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:10 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:10 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:11 volumio wireless.js[1769]: trying... Apr 18 16:16:11 volumio sudo[2375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:11 volumio sudo[2375]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:11 volumio sudo[2375]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:12 volumio wireless.js[1769]: trying... Apr 18 16:16:12 volumio sudo[2400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:12 volumio sudo[2400]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:12 volumio sudo[2400]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:13 volumio wireless.js[1769]: trying... Apr 18 16:16:13 volumio sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:13 volumio sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:13 volumio sudo[2438]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:14 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 18 16:16:14 volumio wireless.js[1769]: trying... Apr 18 16:16:14 volumio sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:14 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:14 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:15 volumio wireless.js[1769]: trying... Apr 18 16:16:15 volumio sudo[2488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:15 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:15 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:15 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 18 16:16:16 volumio wireless.js[1769]: trying... Apr 18 16:16:16 volumio sudo[2536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:16 volumio sudo[2536]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:16 volumio sudo[2536]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:17 volumio wireless.js[1769]: trying... Apr 18 16:16:17 volumio sudo[2598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:17 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:17 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:18 volumio wireless.js[1769]: trying... Apr 18 16:16:18 volumio sudo[2617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:18 volumio sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:18 volumio sudo[2617]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:19 volumio volumio[1004]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 64 Apr 18 16:16:19 volumio wireless.js[1769]: trying... Apr 18 16:16:19 volumio sudo[2628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:19 volumio sudo[2628]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:19 volumio sudo[2628]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 18 16:16:20 volumio volumio[1004]: info: Received Get System Info Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 16:16:20 volumio volumio[1004]: info: Discovery: Getting this device information Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Apr 18 16:16:20 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 16:16:20 volumio volumio-remote-updater[570]: No test mode Apr 18 16:16:20 volumio volumio-remote-updater[570]: No alpha test mode Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 16:16:20 volumio volumio[1004]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 18 16:16:20 volumio wireless.js[1769]: trying... Apr 18 16:16:20 volumio sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 18 16:16:20 volumio sudo[2643]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:20 volumio sudo[2643]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:20 volumio volumio[1004]: info: Retrieving Cloud Streaming UI Apr 18 16:16:20 volumio volumio[1004]: info: Getting Tidal Cloud Configuration Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:20 volumio volumio[1004]: info: Getting Qobuz Cloud Configuration Apr 18 16:16:20 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:20 volumio volumio[1004]: info: Getting Spotify Cloud Configuration Apr 18 16:16:20 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:20 volumio volumio[1004]: info: Saving Spotify Acccount Apr 18 16:16:20 volumio volumio[1004]: info: Got Tidal Cloud Configuration Apr 18 16:16:20 volumio volumio[1004]: info: Got it Apr 18 16:16:20 volumio volumio[1004]: info: Got it Apr 18 16:16:20 volumio volumio[1004]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 16:16:20 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:20 volumio volumio[1004]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 18 16:16:21 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 16:16:21 volumio wireless.js[1769]: Overtime, starting plan B Apr 18 16:16:21 volumio wireless.js[1769]: STARTING HOTSPOT Apr 18 16:16:21 volumio wpa_supplicant[2061]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Apr 18 16:16:21 volumio wpa_supplicant[2061]: nl80211: Failed to set IPv4 unicast in multicast filter Apr 18 16:16:21 volumio wpa_supplicant[2061]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Apr 18 16:16:21 volumio wpa_supplicant[2061]: nl80211: Failed to set IPv4 unicast in multicast filter Apr 18 16:16:21 volumio wpa_supplicant[2061]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Apr 18 16:16:21 volumio dhcpcd[1764]: wlan0: carrier acquired Apr 18 16:16:21 volumio wpa_supplicant[2061]: wlan0: CTRL-EVENT-TERMINATING Apr 18 16:16:21 volumio dhcpcd[1764]: wlan0: IAID 55:e6:73:f7 Apr 18 16:16:21 volumio dhcpcd[1764]: wlan0: carrier lost Apr 18 16:16:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 18 16:16:22 volumio volumio[1004]: info: Loading i18n strings for locale en Apr 18 16:16:22 volumio volumio[1004]: Updating browse sources language Apr 18 16:16:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 16:16:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 18 16:16:22 volumio volumio[1004]: info: Setting timezone to UTC Apr 18 16:16:22 volumio sudo[2674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 18 16:16:22 volumio sudo[2674]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:22 volumio sudo[2674]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:22 volumio sudo[2684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Apr 18 16:16:22 volumio sudo[2684]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:22 volumio sudo[2684]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:22 volumio sudo[2694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 18 16:16:22 volumio sudo[2694]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:22 volumio sudo[2694]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:22 volumio sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Apr 18 16:16:22 volumio sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:22 volumio systemd-timedated[1924]: Failed to set time zone: Permission denied Apr 18 16:16:22 volumio sudo[2704]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:22 volumio volumio[1004]: Failed to set time zone: Access denied Apr 18 16:16:22 volumio volumio[1004]: info: Could not set timezone, retrying Apr 18 16:16:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 16:16:22 volumio volumio[1004]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 65 Apr 18 16:16:22 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Apr 18 16:16:22 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Apr 18 16:16:22 volumio volumio[1004]: info: Fetching Streaming Services browse cache Apr 18 16:16:23 volumio sudo[2728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Apr 18 16:16:23 volumio sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:23 volumio sudo[2728]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:24 volumio sudo[2740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 18 16:16:24 volumio sudo[2740]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:24 volumio sudo[2740]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:24 volumio sudo[2749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 18 16:16:24 volumio sudo[2749]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:24 volumio sudo[2749]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:24 volumio avahi-daemon[589]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 18 16:16:24 volumio dhcpcd[1764]: wlan0: carrier acquired Apr 18 16:16:24 volumio avahi-daemon[589]: New relevant interface wlan0.IPv4 for mDNS. Apr 18 16:16:24 volumio avahi-daemon[589]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 18 16:16:24 volumio dhcpcd[1764]: wlan0: IAID 55:e6:73:f7 Apr 18 16:16:24 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 18 16:16:24 volumio hostapd[2763]: Configuration file: /etc/hostapd/hostapd.conf Apr 18 16:16:24 volumio hostapd[2763]: Using interface wlan0 with hwaddr 70:66:55:e6:73:f7 and ssid "Volumio-7524D" Apr 18 16:16:24 volumio hostapd[2763]: wlan0: interface state UNINITIALIZED->ENABLED Apr 18 16:16:24 volumio hostapd[2763]: wlan0: AP-ENABLED Apr 18 16:16:24 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 18 16:16:24 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Apr 18 16:16:24 volumio dhcpcd[1764]: wlan0: soliciting an IPv6 router Apr 18 16:16:24 volumio dnsmasq[2765]: dnsmasq: syntax check OK. Apr 18 16:16:24 volumio iwconfig[2768]: Error for wireless request "Set Power Management" (8B2C) : Apr 18 16:16:24 volumio iwconfig[2768]: SET failed on device wlan0 ; Operation not permitted. Apr 18 16:16:24 volumio systemd[1]: wireless.service: Succeeded. Apr 18 16:16:24 volumio systemd[1]: Started Wireless Services. Apr 18 16:16:24 volumio sudo[1709]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:24 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:24 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:24 volumio systemd[1]: Reached target Multi-User System. Apr 18 16:16:24 volumio systemd[1]: Reached target Graphical Interface. Apr 18 16:16:24 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 18 16:16:24 volumio dnsmasq[2784]: started, version 2.80 cachesize 150 Apr 18 16:16:24 volumio dnsmasq[2784]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Apr 18 16:16:24 volumio dnsmasq-dhcp[2784]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Apr 18 16:16:24 volumio dnsmasq[2784]: reading /etc/resolv.conf Apr 18 16:16:24 volumio dnsmasq[2784]: using nameserver 8.8.8.8#53 Apr 18 16:16:24 volumio dnsmasq[2784]: using nameserver 8.8.4.4#53 Apr 18 16:16:24 volumio dnsmasq[2784]: using nameserver 10.0.0.1#53 Apr 18 16:16:24 volumio dnsmasq[2784]: using nameserver 208.67.222.222#53 Apr 18 16:16:24 volumio dnsmasq[2784]: using nameserver 208.67.220.220#53 Apr 18 16:16:24 volumio dnsmasq[2784]: read /etc/hosts - 5 addresses Apr 18 16:16:24 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 18 16:16:24 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 18 16:16:24 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Apr 18 16:16:24 volumio systemd[1]: Startup finished in 14.075s (kernel) + 42.972s (userspace) = 57.048s. Apr 18 16:16:25 volumio dhcpcd[1764]: wlan0: soliciting a DHCP lease Apr 18 16:16:25 volumio volumio[1004]: info: Discovery: this is already registered, 200d5760-cff0-46cf-ba89-017052be2795 Apr 18 16:16:25 volumio volumio[1004]: info: Discovery: Found device Volumio Apr 18 16:16:25 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Apr 18 16:16:25 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Apr 18 16:16:25 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 16:16:25 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 16:16:25 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 16:16:25 volumio volumio-remote-updater[570]: No test mode Apr 18 16:16:25 volumio volumio-remote-updater[570]: No alpha test mode Apr 18 16:16:26 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 18 16:16:26 volumio volumio[1004]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 18 16:16:26 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 18 16:16:26 volumio ntpd[1784]: Listen normally on 4 wlan0 192.168.211.1:123 Apr 18 16:16:26 volumio ntpd[1784]: new interface(s) found: waking up resolver Apr 18 16:16:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 16:16:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 16:16:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 18 16:16:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 18 16:16:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 18 16:16:27 volumio volumio[1004]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 16:16:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 16:16:28 volumio volumio[1004]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 18 16:16:28 volumio volumio[1004]: info: Completed starting MyVolumio Plugin Apr 18 16:16:28 volumio volumio[1004]: [Metrics] CommandRouter: 37s 495.62ms Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::volumiosetStartupVolume Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::Close All Modals sent Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::Close All Modals sent Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: info: Retrieving Cloud Streaming UI Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: info: Retrieving Cloud Streaming UI Apr 18 16:16:28 volumio volumio[1004]: info: Getting Tidal Cloud Configuration Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: info: Getting Qobuz Cloud Configuration Apr 18 16:16:28 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: info: Getting Spotify Cloud Configuration Apr 18 16:16:28 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: info: Saving Spotify Acccount Apr 18 16:16:28 volumio volumio[1004]: info: Got Tidal Cloud Configuration Apr 18 16:16:28 volumio volumio[1004]: info: Got it Apr 18 16:16:28 volumio volumio[1004]: info: Got it Apr 18 16:16:28 volumio volumio[1004]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 16:16:28 volumio kernel: dwhdmi-rockchip ff980000.hdmi: Rate 0 missing; compute N dynamically Apr 18 16:16:28 volumio volumio[1004]: info: Getting Tidal Cloud Configuration Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: info: Getting Qobuz Cloud Configuration Apr 18 16:16:28 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: info: Getting Spotify Cloud Configuration Apr 18 16:16:28 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: info: Saving Spotify Acccount Apr 18 16:16:28 volumio volumio[1004]: info: Got Tidal Cloud Configuration Apr 18 16:16:28 volumio volumio[1004]: info: Got it Apr 18 16:16:28 volumio volumio[1004]: info: Got it Apr 18 16:16:28 volumio volumio[1004]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 18 16:16:28 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:28 volumio volumio[1004]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 18 16:16:29 volumio volumio[1004]: info: Version has changed, forcing UI Reload Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::Reload Ui Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 18 16:16:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 18 16:16:29 volumio sudo[2808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 16:16:29 volumio sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 16:16:29 volumio sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:29 volumio sudo[2815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 16:16:29 volumio sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:29 volumio sudo[2808]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:30 volumio sudo[2815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:30 volumio sudo[2811]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:30 volumio dhcpcd[1764]: wlan0: probing for an IPv4LL address Apr 18 16:16:30 volumio sudo[2815]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:30 volumio sudo[2838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 16:16:30 volumio sudo[2840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 16:16:30 volumio sudo[2838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:30 volumio sudo[2838]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:30 volumio sudo[2840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:30 volumio sudo[2840]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:30 volumio volumio[1004]: verbose: New Socket.io Connection to 10.0.0.28 from 10.0.0.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 6 Apr 18 16:16:30 volumio volumio[1004]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 7 Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 18 16:16:30 volumio volumio[1004]: info: Received Get System Info Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 16:16:30 volumio volumio[1004]: info: Discovery: Getting this device information Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Apr 18 16:16:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 16:16:30 volumio volumio-remote-updater[570]: No test mode Apr 18 16:16:30 volumio volumio-remote-updater[570]: No alpha test mode Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:30 volumio volumio[1004]: info: Retrieving Cloud Streaming UI Apr 18 16:16:30 volumio volumio[1004]: info: Getting Tidal Cloud Configuration Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:30 volumio volumio[1004]: info: Getting Qobuz Cloud Configuration Apr 18 16:16:30 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:30 volumio volumio[1004]: info: Getting Spotify Cloud Configuration Apr 18 16:16:30 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:30 volumio volumio[1004]: info: Saving Spotify Acccount Apr 18 16:16:30 volumio volumio[1004]: info: Got Tidal Cloud Configuration Apr 18 16:16:30 volumio volumio[1004]: info: Got it Apr 18 16:16:30 volumio volumio[1004]: info: Got it Apr 18 16:16:30 volumio volumio[1004]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 16:16:30 volumio volumio[1004]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:30 volumio volumio[1004]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 18 16:16:30 volumio volumio[1004]: info: Received Get System Info Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 16:16:30 volumio volumio[1004]: info: Discovery: Getting this device information Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Apr 18 16:16:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 16:16:30 volumio volumio-remote-updater[570]: No test mode Apr 18 16:16:30 volumio volumio-remote-updater[570]: No alpha test mode Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 16:16:30 volumio volumio[1004]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 18 16:16:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 18 16:16:31 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:31 volumio volumio[1004]: info: Retrieving Cloud Streaming UI Apr 18 16:16:31 volumio volumio[1004]: info: Getting Tidal Cloud Configuration Apr 18 16:16:31 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:31 volumio volumio[1004]: info: Getting Qobuz Cloud Configuration Apr 18 16:16:31 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:31 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:31 volumio volumio[1004]: info: Getting Spotify Cloud Configuration Apr 18 16:16:31 volumio volumio[1004]: info: Asking plugin for UI Config Apr 18 16:16:31 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:31 volumio volumio[1004]: info: Saving Spotify Acccount Apr 18 16:16:31 volumio volumio[1004]: info: Got Tidal Cloud Configuration Apr 18 16:16:31 volumio volumio[1004]: info: Got it Apr 18 16:16:31 volumio volumio[1004]: info: Got it Apr 18 16:16:31 volumio volumio[1004]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 16:16:31 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 16:16:31 volumio volumio[1004]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 18 16:16:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 18 16:16:31 volumio volumio[1004]: info: Loading i18n strings for locale en Apr 18 16:16:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 18 16:16:31 volumio volumio[1004]: info: Setting timezone to Europe/London Apr 18 16:16:31 volumio sudo[2866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 18 16:16:31 volumio sudo[2866]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:31 volumio sudo[2866]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:31 volumio sudo[2876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Apr 18 16:16:31 volumio sudo[2876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:31 volumio sudo[2876]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:31 volumio sudo[2886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 18 16:16:31 volumio sudo[2886]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:31 volumio sudo[2886]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:31 volumio sudo[2896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Apr 18 16:16:31 volumio sudo[2896]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:31 volumio systemd-timedated[1924]: Failed to set time zone: Permission denied Apr 18 16:16:31 volumio sudo[2896]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:31 volumio volumio[1004]: Failed to set time zone: Access denied Apr 18 16:16:31 volumio volumio[1004]: info: Could not set timezone, retrying Apr 18 16:16:31 volumio volumio[1004]: Updating browse sources language Apr 18 16:16:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 16:16:32 volumio volumio[1004]: info: Fetching Streaming Services browse cache Apr 18 16:16:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 18 16:16:32 volumio volumio[1004]: info: Loading i18n strings for locale en Apr 18 16:16:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 18 16:16:32 volumio volumio[1004]: info: Setting timezone to UTC Apr 18 16:16:32 volumio sudo[2906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 18 16:16:32 volumio sudo[2906]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:32 volumio sudo[2906]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:32 volumio sudo[2916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Apr 18 16:16:32 volumio sudo[2916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:32 volumio sudo[2916]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:32 volumio sudo[2926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 18 16:16:32 volumio sudo[2926]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:32 volumio sudo[2926]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:32 volumio sudo[2936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Apr 18 16:16:32 volumio sudo[2936]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:32 volumio systemd-timedated[1924]: Failed to set time zone: Permission denied Apr 18 16:16:32 volumio sudo[2936]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:32 volumio volumio[1004]: Failed to set time zone: Access denied Apr 18 16:16:32 volumio volumio[1004]: info: Could not set timezone, retrying Apr 18 16:16:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 16:16:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 16:16:32 volumio volumio[1004]: Updating browse sources language Apr 18 16:16:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 16:16:32 volumio volumio[1004]: info: Fetching Streaming Services browse cache Apr 18 16:16:32 volumio sudo[2946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Apr 18 16:16:32 volumio sudo[2946]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 16:16:32 volumio systemd-timedated[1924]: Failed to set time zone: Permission denied Apr 18 16:16:32 volumio sudo[2946]: pam_unix(sudo:session): session closed for user root Apr 18 16:16:32 volumio volumio[1004]: Failed to set time zone: Access denied Apr 18 16:16:32 volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 16:16:32 volumio volumio[1004]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Europe/London' Apr 18 16:16:32 volumio volumio[1004]: Failed to set time zone: Access denied Apr 18 16:16:32 volumio volumio[1004]: at checkExecSyncError (child_process.js:790:11) Apr 18 16:16:32 volumio volumio[1004]: at execSync (child_process.js:863:15) Apr 18 16:16:32 volumio volumio[1004]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Apr 18 16:16:32 volumio volumio[1004]: at listOnTimeout (internal/timers.js:557:17) Apr 18 16:16:32 volumio volumio[1004]: at processTimers (internal/timers.js:500:7) { Apr 18 16:16:32 volumio volumio[1004]: status: 1, Apr 18 16:16:32 volumio volumio[1004]: signal: null, Apr 18 16:16:32 volumio volumio[1004]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Apr 18 16:16:32 volumio volumio[1004]: pid: 2945, Apr 18 16:16:32 volumio volumio[1004]: stdout: '', Apr 18 16:16:32 volumio volumio[1004]: stderr: 'Failed to set time zone: Access denied\n' Apr 18 16:16:32 volumio volumio[1004]: } Apr 18 16:16:32 volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 16:16:33 volumio sudo[2964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 16:15 Apr 18 16:16:33 volumio sudo[2964]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:14:40 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="a9bbde8819c9c7e405795a261e2a2f93"