-- Logs begin at Fri 2025-01-10 21:32:18 UTC, end at Fri 2025-01-10 21:36:27 UTC. -- Jan 10 21:35:20 volumio systemd-timedated[1230]: Changed local time to Fri Jan 10 21:35:20 2025 Jan 10 21:35:20 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:20 volumio volumio-time-update[902]: volumio-time-update-util: System time updated successfully. Jan 10 21:35:20 volumio systemd[1]: Started Volumio Time Update Utility. Jan 10 21:35:20 volumio ntpd[1195]: ntpd exiting on signal 15 (Terminated) Jan 10 21:35:20 volumio ntpd[1195]: 109.68.160.220 local addr 192.168.129.53 -> Jan 10 21:35:20 volumio ntpd[1195]: 162.159.200.123 local addr 192.168.129.53 -> Jan 10 21:35:20 volumio ntpd[1195]: 193.190.253.214 local addr 192.168.129.53 -> Jan 10 21:35:20 volumio ntpd[1195]: 45.87.76.3 local addr 192.168.129.53 -> Jan 10 21:35:20 volumio ntpd[1195]: 185.111.204.220 local addr 192.168.129.53 -> Jan 10 21:35:20 volumio systemd[1]: Stopping Network Time Service... Jan 10 21:35:20 volumio ntpd[1195]: 193.104.37.238 local addr 192.168.129.53 -> Jan 10 21:35:20 volumio systemd[1]: ntp.service: Succeeded. Jan 10 21:35:20 volumio systemd[1]: Stopped Network Time Service. Jan 10 21:35:20 volumio systemd[1]: Starting Network Time Service... Jan 10 21:35:20 volumio ntpd[1267]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 10 21:35:20 volumio ntpd[1267]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 10 21:35:20 volumio systemd[1]: Started Network Time Service. Jan 10 21:35:20 volumio ntpd[1273]: proto: precision = 0.250 usec (-22) Jan 10 21:35:20 volumio ntpd[1273]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 10 21:35:20 volumio ntpd[1273]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 10 21:35:20 volumio ntpd[1273]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 745 days ago Jan 10 21:35:20 volumio ntpd[1273]: Listen and drop on 0 v6wildcard [::]:123 Jan 10 21:35:20 volumio ntpd[1273]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 10 21:35:20 volumio ntpd[1273]: Listen normally on 2 lo 127.0.0.1:123 Jan 10 21:35:20 volumio ntpd[1273]: Listen normally on 3 eth0 192.168.129.53:123 Jan 10 21:35:20 volumio ntpd[1273]: Listening on routing socket on fd #20 for interface updates Jan 10 21:35:20 volumio ntpd[1273]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 10 21:35:20 volumio ntpd[1273]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 10 21:35:21 volumio ntpd[1273]: Soliciting pool server 45.87.78.35 Jan 10 21:35:21 volumio wireless.js[893]: Cleaning previous... Jan 10 21:35:22 volumio ntpd[1273]: Soliciting pool server 45.87.76.3 Jan 10 21:35:22 volumio ntpd[1273]: Soliciting pool server 162.159.200.1 Jan 10 21:35:22 volumio sudo[1282]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 10 21:35:22 volumio sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:22 volumio sudo[1282]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:23 volumio sudo[1284]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 10 21:35:23 volumio sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:23 volumio winbindd[1232]: [2025/01/10 21:35:23.225176, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 10 21:35:23 volumio winbindd[1232]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 10 21:35:23 volumio sudo[1284]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:23 volumio ntpd[1273]: Soliciting pool server 185.111.204.220 Jan 10 21:35:23 volumio ntpd[1273]: Soliciting pool server 162.159.200.123 Jan 10 21:35:23 volumio ntpd[1273]: Soliciting pool server 45.87.77.15 Jan 10 21:35:23 volumio wireless.js[893]: Stopped aP Jan 10 21:35:24 volumio winbindd[1232]: [2025/01/10 21:35:24.117065, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 10 21:35:24 volumio winbindd[1232]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 10 21:35:24 volumio systemd[1]: Started Samba Winbind Daemon. Jan 10 21:35:24 volumio sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 21:35:24 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:24 volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:24 volumio systemd[1]: Starting Samba SMB Daemon... Jan 10 21:35:24 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:24 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring Jan 10 21:35:24 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:24 volumio sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 10 21:35:24 volumio sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:24 volumio sudo[1296]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:24 volumio ntpd[1273]: Soliciting pool server 109.68.160.220 Jan 10 21:35:24 volumio ntpd[1273]: Soliciting pool server 193.190.253.214 Jan 10 21:35:24 volumio ntpd[1273]: Soliciting pool server 109.68.160.220 Jan 10 21:35:24 volumio ntpd[1273]: Soliciting pool server 81.82.227.219 Jan 10 21:35:24 volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 21:35:24 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:24 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:24 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 10 21:35:24 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:25 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:25 volumio wireless.js[893]: SETTING APPROPRIATE REG DOMAIN: DE Jan 10 21:35:25 volumio sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 21:35:25 volumio sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:25 volumio sudo[1311]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:25 volumio sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jan 10 21:35:25 volumio sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:25 volumio sudo[1313]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:25 volumio wireless.js[893]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jan 10 21:35:25 volumio ntpd[1273]: Soliciting pool server 91.182.44.189 Jan 10 21:35:25 volumio ntpd[1273]: Soliciting pool server 193.104.37.238 Jan 10 21:35:25 volumio sudo[1320]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 10 21:35:25 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:25 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:25 volumio sudo[1322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 10 21:35:25 volumio sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:25 volumio sudo[1322]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:25 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:25 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:25 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring Jan 10 21:35:25 volumio avahi-daemon[892]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:25 volumio avahi-daemon[892]: New relevant interface wlan0.IPv4 for mDNS. Jan 10 21:35:25 volumio avahi-daemon[892]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 10 21:35:25 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 10 21:35:26 volumio ntpd[1273]: Soliciting pool server 2606:4700:f1::1 Jan 10 21:35:26 volumio hostapd[1328]: Configuration file: /etc/hostapd/hostapd.conf Jan 10 21:35:26 volumio avahi-daemon[892]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 10 21:35:26 volumio avahi-daemon[892]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:26 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:26 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:26 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring Jan 10 21:35:26 volumio avahi-daemon[892]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 10 21:35:26 volumio avahi-daemon[892]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:26 volumio avahi-daemon[892]: New relevant interface wlan0.IPv4 for mDNS. Jan 10 21:35:26 volumio avahi-daemon[892]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 10 21:35:26 volumio hostapd[1328]: Using interface wlan0 with hwaddr 34:02:86:ea:72:89 and ssid "Volumio" Jan 10 21:35:26 volumio dhcpcd[1020]: wlan0: carrier acquired Jan 10 21:35:26 volumio dhcpcd[1020]: wlan0: IAID 86:ea:72:89 Jan 10 21:35:27 volumio dhcpcd[1020]: wlan0: soliciting an IPv6 router Jan 10 21:35:27 volumio hostapd[1328]: wlan0: interface state UNINITIALIZED->ENABLED Jan 10 21:35:27 volumio hostapd[1328]: wlan0: AP-ENABLED Jan 10 21:35:27 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 10 21:35:27 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 10 21:35:27 volumio iwconfig[1349]: Error for wireless request "Set Power Management" (8B2C) : Jan 10 21:35:27 volumio iwconfig[1349]: SET failed on device wlan0 ; Invalid argument. Jan 10 21:35:27 volumio systemd[1]: wireless.service: Succeeded. Jan 10 21:35:27 volumio systemd[1]: Started Wireless Services. Jan 10 21:35:27 volumio systemd[1]: Started Volumio Backend Module. Jan 10 21:35:27 volumio systemd[1]: Started Start Volumio Kiosk. Jan 10 21:35:27 volumio systemd[1]: Started First Start. Jan 10 21:35:27 volumio systemd[1]: Started Volumio Cpu Tweaker. Jan 10 21:35:27 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Jan 10 21:35:27 volumio firststart.sh[1352]: Volumio first start configuration script Jan 10 21:35:27 volumio firststart.sh[1352]: Configuring unconfigured packages Jan 10 21:35:27 volumio dhcpcd[1020]: wlan0: soliciting a DHCP lease Jan 10 21:35:27 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 10 21:35:28 volumio dnsmasq[1347]: dnsmasq: syntax check OK. Jan 10 21:35:28 volumio ntpd[1273]: Listen normally on 4 wlan0 192.168.211.1:123 Jan 10 21:35:28 volumio ntpd[1273]: new interface(s) found: waking up resolver Jan 10 21:35:31 volumio ntpd[1273]: receive: Unexpected origin timestamp 0xeb2c1120.5ce96d2b does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeb2c1123.4a5aa082 Jan 10 21:35:31 volumio ntpd[1273]: receive: Unexpected origin timestamp 0xeb2c1120.5ce56b4b does not match aorg 0000000000.00000000 from server@45.87.77.15 xmt 0xeb2c1123.4ab769e2 Jan 10 21:35:31 volumio ntpd[1273]: receive: Unexpected origin timestamp 0xeb2c1120.5ce7add6 does not match aorg 0000000000.00000000 from server@185.111.204.220 xmt 0xeb2c1123.4aa77930 Jan 10 21:35:31 volumio ntpd[1273]: receive: Unexpected origin timestamp 0xeb2c1120.5cdf64f5 does not match aorg 0000000000.00000000 from server@193.104.37.238 xmt 0xeb2c1123.4aa935a7 Jan 10 21:35:32 volumio volumio-remote-updater[890]: [2025-01-10 21:35:32] [info] asio async_connect error: system:111 (Connection refused) Jan 10 21:35:32 volumio volumio-remote-updater[890]: [2025-01-10 21:35:32] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 10 21:35:32 volumio volumio-remote-updater[890]: [2025-01-10 21:35:32] [error] handle_connect error: Underlying Transport Error Jan 10 21:35:32 volumio volumio-cpu-tweak[1353]: Setting RT Priority for mpd Jan 10 21:35:33 volumio volumio-cpu-tweak[1353]: pid 35's current scheduling policy: SCHED_FIFO Jan 10 21:35:33 volumio volumio-cpu-tweak[1353]: pid 35's current scheduling priority: 99 Jan 10 21:35:33 volumio volumio-cpu-tweak[1353]: Setting MPD Affinity Jan 10 21:35:33 volumio volumio-cpu-tweak[1353]: pid 3's current affinity mask: f Jan 10 21:35:33 volumio volumio-cpu-tweak[1353]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 10 21:35:34 volumio startx[1351]: xauth: file /root/.Xauthority does not exist Jan 10 21:35:35 volumio dhcpcd[1020]: wlan0: probing for an IPv4LL address Jan 10 21:35:36 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 10 21:35:36 volumio dnsmasq[1445]: started, version 2.80 cachesize 150 Jan 10 21:35:36 volumio dnsmasq[1445]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 10 21:35:36 volumio dnsmasq-dhcp[1445]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 10 21:35:36 volumio dnsmasq[1445]: reading /etc/resolv.conf Jan 10 21:35:36 volumio dnsmasq[1445]: using nameserver 192.168.128.1#53 Jan 10 21:35:36 volumio dnsmasq[1445]: using nameserver 208.67.222.222#53 Jan 10 21:35:36 volumio dnsmasq[1445]: using nameserver 208.67.220.220#53 Jan 10 21:35:36 volumio dnsmasq[1445]: read /etc/hosts - 5 addresses Jan 10 21:35:36 volumio smbd[1304]: [2025/01/10 21:35:36.886217, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 10 21:35:36 volumio smbd[1304]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 10 21:35:36 volumio systemd[1]: Started Samba SMB Daemon. Jan 10 21:35:36 volumio systemd[1]: Reached target Multi-User System. Jan 10 21:35:36 volumio systemd[1]: Reached target Graphical Interface. Jan 10 21:35:36 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 10 21:35:36 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 10 21:35:36 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 10 21:35:36 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 10 21:35:36 volumio systemd[1]: Reached target Host and Network Name Lookups. Jan 10 21:35:37 volumio startx[1351]: X.Org X Server 1.20.4 Jan 10 21:35:37 volumio startx[1351]: X Protocol Version 11, Revision 0 Jan 10 21:35:37 volumio startx[1351]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Jan 10 21:35:37 volumio startx[1351]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Jan 10 21:35:37 volumio startx[1351]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 bootpart=UUID=79FB-2BA3 datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Jan 10 21:35:37 volumio startx[1351]: Build Date: 18 December 2021 09:05:36AM Jan 10 21:35:37 volumio startx[1351]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jan 10 21:35:37 volumio startx[1351]: Current version of pixman: 0.36.0 Jan 10 21:35:37 volumio startx[1351]: Before reporting problems, check http://wiki.x.org Jan 10 21:35:37 volumio startx[1351]: to make sure that you have the latest version. Jan 10 21:35:37 volumio startx[1351]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 10 21:35:37 volumio startx[1351]: (++) from command line, (!!) notice, (II) informational, Jan 10 21:35:37 volumio startx[1351]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 10 21:35:37 volumio startx[1351]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Jan 10 21:35:37 2025 Jan 10 21:35:37 volumio startx[1351]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 10 21:35:37 volumio firststart.sh[1352]: Creating /var/log/samba/cores folder Jan 10 21:35:37 volumio firststart.sh[1352]: Removing default SSH host keys Jan 10 21:35:37 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_ecdsa_key' Jan 10 21:35:37 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Jan 10 21:35:37 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_ed25519_key' Jan 10 21:35:37 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Jan 10 21:35:37 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_rsa_key' Jan 10 21:35:37 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_rsa_key.pub' Jan 10 21:35:37 volumio firststart.sh[1352]: Generating SSH host keys Jan 10 21:35:37 volumio soundcard-init.sh[1359]: Simple mixer control 'Master',0 Jan 10 21:35:37 volumio soundcard-init.sh[1359]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Jan 10 21:35:37 volumio soundcard-init.sh[1359]: Playback channels: Mono Jan 10 21:35:37 volumio soundcard-init.sh[1359]: Limits: Playback 0 - 87 Jan 10 21:35:37 volumio soundcard-init.sh[1359]: Mono: Playback 66 [76%] [-15.75dB] [on] Jan 10 21:35:37 volumio systemd[1]: soundcard-init.service: Succeeded. Jan 10 21:35:38 volumio kernel: broken atomic modeset userspace detected, disabling atomic Jan 10 21:35:39 volumio startx[1351]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jan 10 21:35:39 volumio mpd[1138]: Jan 10 21:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 10 21:35:39 volumio mpd[1138]: Jan 10 21:35 : exception: Database corrupted Jan 10 21:35:39 volumio systemd[1]: Started Music Player Daemon. Jan 10 21:35:39 volumio mpd_monitor.sh[897]: MPD restarted due to no mpc output. Jan 10 21:35:39 volumio systemd[1]: Startup finished in 13.021s (firmware) + 2.708s (loader) + 3min 2.109s (kernel) + 46.972s (userspace) = 4min 4.811s. Jan 10 21:35:39 volumio firststart.sh[1352]: debconf: unable to initialize frontend: Dialog Jan 10 21:35:39 volumio firststart.sh[1352]: debconf: (TERM is not set, so the dialog frontend is not usable.) Jan 10 21:35:39 volumio firststart.sh[1352]: debconf: falling back to frontend: Readline Jan 10 21:35:40 volumio firststart.sh[1352]: debconf: unable to initialize frontend: Readline Jan 10 21:35:40 volumio firststart.sh[1352]: debconf: (This frontend requires a controlling tty.) Jan 10 21:35:40 volumio firststart.sh[1352]: debconf: falling back to frontend: Teletype Jan 10 21:35:40 volumio dhcpcd[1020]: wlan0: using IPv4LL address 169.254.24.169 Jan 10 21:35:40 volumio avahi-daemon[892]: Registering new address record for 169.254.24.169 on wlan0.IPv4. Jan 10 21:35:40 volumio dhcpcd[1020]: wlan0: adding route to 169.254.0.0/16 Jan 10 21:35:41 volumio volumio[1350]: info: ------------------------------------------- Jan 10 21:35:41 volumio volumio[1350]: info: ----- Volumio3 ---- Jan 10 21:35:41 volumio volumio[1350]: info: ------------------------------------------- Jan 10 21:35:41 volumio volumio[1350]: info: ----- System startup ---- Jan 10 21:35:41 volumio volumio[1350]: info: ------------------------------------------- Jan 10 21:35:42 volumio firststart.sh[1352]: Creating SSH2 RSA key; this may take some time ... Jan 10 21:35:42 volumio firststart.sh[1352]: 2048 SHA256:1AuHOnTIVMm53O9bAZwloLeqP4xg7auKVkPLxNcaXQw root@volumio (RSA) Jan 10 21:35:42 volumio volumio-remote-updater[890]: [2025-01-10 21:35:42] [connect] Successful connection Jan 10 21:35:42 volumio ntpd[1273]: Listen normally on 5 wlan0 169.254.24.169:123 Jan 10 21:35:42 volumio ntpd[1273]: new interface(s) found: waking up resolver Jan 10 21:35:42 volumio firststart.sh[1352]: Creating SSH2 DSA key; this may take some time ... Jan 10 21:35:42 volumio firststart.sh[1352]: 1024 SHA256:kyvcsivfAboNomhCbYuqW7a56qmOGQGEMj7QwCTFx/Q root@volumio (DSA) Jan 10 21:35:42 volumio firststart.sh[1352]: Creating SSH2 ECDSA key; this may take some time ... Jan 10 21:35:42 volumio firststart.sh[1352]: 256 SHA256:W/zHgHQ0CzcUBUtvNQaHRF+EBjyTiBnl0NvSQ/KUun8 root@volumio (ECDSA) Jan 10 21:35:42 volumio volumio[1350]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Jan 10 21:35:42 volumio volumio[1350]: info: MYVOLUMIO Environment detected Jan 10 21:35:43 volumio volumio[1350]: info: Plugin folders cleanup Jan 10 21:35:43 volumio volumio[1350]: info: Scanning into folder /volumio/app/plugins/ Jan 10 21:35:43 volumio volumio[1350]: info: Scanning category audio_interface Jan 10 21:35:43 volumio volumio[1350]: info: Scanning category miscellanea Jan 10 21:35:43 volumio volumio[1350]: info: Scanning category music_service Jan 10 21:35:43 volumio volumio[1350]: info: Scanning category plugins.json Jan 10 21:35:43 volumio volumio[1350]: info: Scanning category system_controller Jan 10 21:35:43 volumio volumio[1350]: info: Scanning category user_interface Jan 10 21:35:43 volumio volumio[1350]: info: Scanning into folder /data/plugins/ Jan 10 21:35:43 volumio volumio[1350]: info: Plugin folders cleanup completed Jan 10 21:35:43 volumio volumio[1350]: info: ------------------------------------------- Jan 10 21:35:43 volumio volumio[1350]: info: ----- Core plugins startup ---- Jan 10 21:35:43 volumio volumio[1350]: info: ------------------------------------------- Jan 10 21:35:43 volumio volumio[1350]: info: Loading plugins from folder /volumio/app/plugins/ Jan 10 21:35:43 volumio volumio[1350]: info: Adding plugin upnp to MyMusic Plugins Jan 10 21:35:43 volumio volumio[1350]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 10 21:35:43 volumio volumio[1350]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 10 21:35:43 volumio volumio[1350]: info: Loading plugins from folder /data/plugins/ Jan 10 21:35:43 volumio volumio[1350]: info: Loading plugin "system"... Jan 10 21:35:43 volumio volumio[1350]: info: No id defined. Creating one Jan 10 21:35:43 volumio volumio[1350]: info: Loading plugin "appearance"... Jan 10 21:35:43 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 10 21:35:44 volumio systemd[1]: Reloading. Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "network"... Jan 10 21:35:44 volumio volumio[1350]: info: Refreshing Cached IP Addresses Jan 10 21:35:44 volumio sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 21:35:44 volumio sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 21:35:44 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:44 volumio sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:44 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:44 volumio sudo[1730]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "services"... Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "alsa_controller"... Jan 10 21:35:44 volumio sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 10 21:35:44 volumio sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:44 volumio sudo[1743]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:44 volumio volumio[1350]: warn: The ALSA output card is not set, defaulting to card [object Object] Jan 10 21:35:44 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 10 21:35:44 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 21:35:44 volumio volumio[1350]: info: Found match in Cards Database: setting mixer for card Analog Out Jan 10 21:35:44 volumio volumio[1350]: info: Setting mixer Master for card Analog Out Jan 10 21:35:44 volumio volumio[1350]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 10 21:35:44 volumio volumio[1350]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 10 21:35:44 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 10 21:35:44 volumio volumio[1350]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 10 21:35:44 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "wizard"... Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "networkfs"... Jan 10 21:35:44 volumio volumio[1350]: info: Starting Udev Watcher for removable devices Jan 10 21:35:44 volumio volumio[1350]: info: Ignoring mount for partition: boot Jan 10 21:35:44 volumio volumio[1350]: info: Ignoring mount for partition: volumio Jan 10 21:35:44 volumio volumio[1350]: info: Ignoring mount for partition: volumio_data Jan 10 21:35:44 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "volumio_command_line_client"... Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "upnp"... Jan 10 21:35:44 volumio volumio[1350]: info: [1736544944980] Starting Upmpd Daemon Jan 10 21:35:44 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "my_music"... Jan 10 21:35:44 volumio volumio[1350]: info: Loading plugin "mpd"... Jan 10 21:35:45 volumio firststart.sh[1352]: rescue-ssh.target is a disabled or a static unit, not starting it. Jan 10 21:35:45 volumio firststart.sh[1352]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Jan 10 21:35:45 volumio firststart.sh[1352]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Jan 10 21:35:45 volumio systemd[1]: Reloading. Jan 10 21:35:45 volumio volumio[1350]: info: Loading plugin "upnp_browser"... Jan 10 21:35:45 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 10 21:35:45 volumio volumio[1350]: info: Loading plugin "alarm-clock"... Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "airplay_emulation"... Jan 10 21:35:46 volumio volumio[1350]: info: Starting Shairport Sync Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "last_100"... Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "webradio"... Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "i2s_dacs"... Jan 10 21:35:46 volumio volumio[1350]: info: I2S DAC not set, start Auto-detection Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "volumiodiscovery"... Jan 10 21:35:46 volumio firststart.sh[1352]: Enabling SSH for first boot Jan 10 21:35:46 volumio volumio[1350]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 10 21:35:46 volumio volumio[1350]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 10 21:35:46 volumio volumio[1350]: *** WARNING *** For more information see Jan 10 21:35:46 volumio volumio[1350]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 10 21:35:46 volumio volumio[1350]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 10 21:35:46 volumio volumio[1350]: *** WARNING *** For more information see Jan 10 21:35:46 volumio volumio[1350]: info: Discovery: Started advertising with name: Volumio Jan 10 21:35:46 volumio node[1350]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 10 21:35:46 volumio node[1350]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 10 21:35:46 volumio systemd[1]: Starting OpenBSD Secure Shell server... Jan 10 21:35:46 volumio node[1350]: *** WARNING *** For more information see Jan 10 21:35:46 volumio node[1350]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 10 21:35:46 volumio node[1350]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 10 21:35:46 volumio node[1350]: *** WARNING *** For more information see Jan 10 21:35:46 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "outputs"... Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "albumart"... Jan 10 21:35:46 volumio volumio[1350]: info: Plugin example_plugin is not enabled Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "inputs"... Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "updater_comm"... Jan 10 21:35:46 volumio sshd[1810]: Server listening on 0.0.0.0 port 22. Jan 10 21:35:46 volumio sshd[1810]: Server listening on :: port 22. Jan 10 21:35:46 volumio systemd[1]: Started OpenBSD Secure Shell server. Jan 10 21:35:46 volumio firststart.sh[1352]: Disabling firststart service Jan 10 21:35:46 volumio firststart.sh[1352]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Jan 10 21:35:46 volumio systemd[1]: Reloading. Jan 10 21:35:46 volumio volumio[1350]: Forking 3 albumart workers Jan 10 21:35:46 volumio volumio[1350]: info: Plugin mpdemulation is not enabled Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "rest_api"... Jan 10 21:35:46 volumio volumio[1350]: info: Loading plugin "websocket"... Jan 10 21:35:46 volumio volumio[1350]: info: Starting Socket.io Server version 2.3.0 Jan 10 21:35:46 volumio volumio[1350]: info: Loading i18n strings for locale en Jan 10 21:35:47 volumio volumio[1350]: Updating browse sources language Jan 10 21:35:47 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:35:47 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 10 21:35:47 volumio volumio[1350]: Starting albumart workers Jan 10 21:35:47 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:35:47 volumio volumio[1350]: Starting albumart workers Jan 10 21:35:47 volumio volumio[1350]: info: CoreCommandRouter::initPlayerControls Jan 10 21:35:47 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:47 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:35:47 volumio volumio[1350]: Express server listening on port 3000 Jan 10 21:35:47 volumio volumio[1350]: [Metrics] WebUI: 6s 940.62ms Jan 10 21:35:47 volumio firststart.sh[1352]: Finalizing Jan 10 21:35:47 volumio volumio[1350]: info: Setting Device type: x86 Jan 10 21:35:47 volumio volumio[1350]: info: CoreStateMachine::resetVolumioState Jan 10 21:35:47 volumio volumio[1350]: info: CoreStateMachine::getcurrentVolume Jan 10 21:35:47 volumio volumio[1350]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 21:35:47 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:35:47 volumio volumio[1350]: Starting albumart workers Jan 10 21:35:47 volumio volumio[1350]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 10 21:35:47 volumio volumio[1350]: info: Completed loading Core Plugins Jan 10 21:35:47 volumio volumio[1350]: info: Preparing to generate the ALSA configuration file Jan 10 21:35:47 volumio volumio[1350]: info: Cannot read play queue from file Jan 10 21:35:47 volumio volumio[1350]: info: Volumio Network Manager: Network status updated: 1 Jan 10 21:35:47 volumio volumio[1350]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 10 21:35:47 volumio volumio[1350]: wlan0 Interface doesn't support scanning : Operation not supported Jan 10 21:35:47 volumio volumio[1350]: info: Cannot use regular scanning, forcing with ap-force Jan 10 21:35:47 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 10 21:35:47 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:47 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:47 volumio systemd[1]: firststart.service: Succeeded. Jan 10 21:35:47 volumio volumio[1350]: command failed: Operation not supported (-95) Jan 10 21:35:47 volumio volumio[1350]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 10 21:35:47 volumio volumio[1350]: command failed: Operation not supported (-95) Jan 10 21:35:47 volumio volumio[1350]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 10 21:35:48 volumio volumio[1350]: info: Asound.conf file written Jan 10 21:35:48 volumio sudo[1887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 10 21:35:48 volumio sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:48 volumio sudo[1887]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:48 volumio volumio[1350]: No state is present for card PCH Jan 10 21:35:48 volumio volumio[1350]: Found hardware: "HDA-Intel" "Realtek ALC283" "HDA:10ec0283,80862060,00100003 HDA:80862883,80860101,00100000" "0x8086" "0x2060" Jan 10 21:35:48 volumio volumio[1350]: Hardware is initialized using a generic method Jan 10 21:35:48 volumio volumio[1350]: No state is present for card PCH Jan 10 21:35:48 volumio volumio[1350]: info: Output device has changed, restarting MPD Jan 10 21:35:48 volumio sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 21:35:48 volumio sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:48 volumio volumio[1350]: info: Output device has changed, restarting Shairport Sync Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:35:48 volumio sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 21:35:48 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:48 volumio systemd[1]: Stopping Music Player Daemon... Jan 10 21:35:48 volumio volumio[1350]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 10 21:35:48 volumio volumio[1350]: info: ___________ START PLUGINS ___________ Jan 10 21:35:48 volumio volumio[1350]: info: Setting Hotspot Unique name on first start: Volumio-44268 Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::Reload Ui Jan 10 21:35:48 volumio volumio[1350]: info: ControllerMpd::onStart: Initializing MPD Jan 10 21:35:48 volumio volumio[1350]: info: Creating MPD Configuration file Jan 10 21:35:48 volumio sudo[1892]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:48 volumio sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 21:35:48 volumio sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 10 21:35:48 volumio volumio[1350]: info: [1736544948391] CoreMusicLibrary::Adding element Media Servers Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:35:48 volumio sudo[1901]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:48 volumio sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 21:35:48 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 10 21:35:48 volumio volumio[1350]: info: [1736544948515] CoreMusicLibrary::Adding element Last_100 Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 10 21:35:48 volumio volumio[1350]: info: [1736544948518] CoreMusicLibrary::Adding element Webradio Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 21:35:48 volumio volumio[1350]: info: Initializing BBC Radios Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: Volumio Calling Home Jan 10 21:35:48 volumio systemd[1]: mpd.service: Succeeded. Jan 10 21:35:48 volumio systemd[1]: Stopped Music Player Daemon. Jan 10 21:35:48 volumio systemd[1]: Starting Music Player Daemon... Jan 10 21:35:48 volumio sudo[1924]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 10 21:35:48 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:48 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:48 volumio volumio[1350]: info: VolumeController:: Volume=51 Mute =false Jan 10 21:35:48 volumio volumio[1350]: info: CoreStateMachine::pushState Jan 10 21:35:48 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::volumioPushState Jan 10 21:35:48 volumio volumio[1350]: info: CoreStateMachine::updateTrackBlock Jan 10 21:35:48 volumio volumio[1350]: info: CorePlayQueue::getTrackBlock Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 10 21:35:48 volumio volumio[1350]: info: CoreStateMachine::getcurrentVolume Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 21:35:48 volumio volumio[1350]: info: MPD Permissions set Jan 10 21:35:48 volumio volumio[1350]: info: MPD Permissions set Jan 10 21:35:48 volumio volumio[1350]: info: Discovery: adding f3df6796-b123-41b6-8239-58c79955952d Jan 10 21:35:48 volumio volumio[1350]: info: Discovery: Found device Volumio Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:35:48 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:48 volumio volumio[1350]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 10 21:35:48 volumio volumio[1350]: info: Volumio called home Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:35:48 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:35:49 volumio volumio[1350]: info: VolumeController:: Volume=41 Mute =false Jan 10 21:35:49 volumio volumio[1350]: info: CoreStateMachine::pushState Jan 10 21:35:49 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::volumioPushState Jan 10 21:35:49 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:49 volumio volumio[1350]: info: Discovery: this is already registered, f3df6796-b123-41b6-8239-58c79955952d Jan 10 21:35:49 volumio volumio[1350]: info: Discovery: Found device Volumio Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:35:49 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:49 volumio volumio[1350]: info: VolumeController:: Volume=41 Mute =false Jan 10 21:35:49 volumio volumio[1350]: info: CoreStateMachine::pushState Jan 10 21:35:49 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::volumioPushState Jan 10 21:35:49 volumio volumio[1350]: info: CoreStateMachine::updateTrackBlock Jan 10 21:35:49 volumio volumio[1350]: info: CorePlayQueue::getTrackBlock Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:35:49 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:49 volumio volumio[1350]: info: Starting Shairport Sync Jan 10 21:35:49 volumio volumio[1350]: info: Starting Shairport Sync Jan 10 21:35:49 volumio volumio[1350]: info: Starting Shairport Sync Jan 10 21:35:49 volumio sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 10 21:35:49 volumio sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:49 volumio sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 10 21:35:49 volumio sudo[1947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 10 21:35:49 volumio sudo[1947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:49 volumio sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 10 21:35:49 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 10 21:35:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 10 21:35:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 10 21:35:49 volumio sudo[1951]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 10 21:35:49 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 10 21:35:49 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 10 21:35:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 10 21:35:49 volumio sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Jan 10 21:35:49 volumio sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 10 21:35:49 volumio sudo[1949]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:49 volumio sudo[1947]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:49 volumio volumio[1350]: info: VolumeController:: Volume=41 Mute =false Jan 10 21:35:49 volumio volumio[1350]: info: CoreStateMachine::pushState Jan 10 21:35:49 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::volumioPushState Jan 10 21:35:49 volumio volumio[1350]: info: Shairport-Sync Started Jan 10 21:35:49 volumio volumio[1350]: Error adding Membership: Error: addMembership EINVAL Jan 10 21:35:49 volumio volumio[1350]: info: Shairport-Sync Started Jan 10 21:35:49 volumio volumio[1350]: info: Shairport-Sync Started Jan 10 21:35:49 volumio sudo[1958]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:49 volumio volumio[1350]: info: Permissions for /etc/hostapd/hostapd.conf Jan 10 21:35:49 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jan 10 21:35:49 volumio volumio[1350]: info: Discovery: Restarting Advertising due to device name change Jan 10 21:35:49 volumio sudo[1967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jan 10 21:35:49 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:49 volumio systemd[1]: Starting Wireless Services... Jan 10 21:35:49 volumio mpd[1927]: Jan 10 21:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 10 21:35:49 volumio systemd[1]: Started Music Player Daemon. Jan 10 21:35:49 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:49 volumio sudo[1903]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:49 volumio volumio[1350]: info: Completed starting Core Plugins Jan 10 21:35:49 volumio volumio[1350]: info: ------------------------------------------- Jan 10 21:35:49 volumio volumio[1350]: info: ----- MyVolumio plugins startup ---- Jan 10 21:35:49 volumio volumio[1350]: info: ------------------------------------------- Jan 10 21:35:49 volumio volumio[1350]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 10 21:35:49 volumio volumio[1350]: error: MPD error: The expression evaluated to a falsy value: Jan 10 21:35:49 volumio volumio[1350]: assert.ok(self.idling) Jan 10 21:35:49 volumio volumio[1350]: error: The expression evaluated to a falsy value: Jan 10 21:35:49 volumio volumio[1350]: assert.ok(self.idling) Jan 10 21:35:49 volumio volumio[1350]: error: updateQueue error: null Jan 10 21:35:49 volumio volumio[1350]: info: MPD running with PID1927 Jan 10 21:35:49 volumio volumio[1350]: ,establishing connection Jan 10 21:35:49 volumio volumio[1350]: error: updateQueue error: null Jan 10 21:35:50 volumio wireless.js[1969]: Cleaning previous... Jan 10 21:35:50 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 10 21:35:50 volumio dnsmasq[1445]: exiting on receipt of SIGTERM Jan 10 21:35:50 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 10 21:35:50 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 10 21:35:50 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 10 21:35:50 volumio dhcpcd[1020]: wlan0: carrier lost Jan 10 21:35:50 volumio dhcpcd[1020]: wlan0: deleting route to 169.254.0.0/16 Jan 10 21:35:50 volumio avahi-daemon[892]: Withdrawing address record for 169.254.24.169 on wlan0. Jan 10 21:35:50 volumio avahi-daemon[892]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 10 21:35:50 volumio avahi-daemon[892]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:50 volumio avahi-daemon[892]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 10 21:35:50 volumio volumio[1350]: info: Discovery: A device disappeared from network Jan 10 21:35:50 volumio volumio[1350]: info: Discovery: Device volumio disappeared from network Jan 10 21:35:50 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:50 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:50 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring Jan 10 21:35:50 volumio avahi-daemon[892]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:50 volumio avahi-daemon[892]: New relevant interface wlan0.IPv4 for mDNS. Jan 10 21:35:50 volumio avahi-daemon[892]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 10 21:35:50 volumio systemd[1]: hostapd.service: Succeeded. Jan 10 21:35:50 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 10 21:35:50 volumio sudo[2037]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 10 21:35:50 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:50 volumio avahi-daemon[892]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 10 21:35:50 volumio avahi-daemon[892]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:50 volumio avahi-daemon[892]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 10 21:35:50 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:50 volumio sudo[2039]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 10 21:35:50 volumio sudo[2039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:50 volumio sudo[2039]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:50 volumio wireless.js[1969]: Stopped aP Jan 10 21:35:50 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 21:35:50 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:50 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:50 volumio volumio[1350]: info: Discovery: A device disappeared from network Jan 10 21:35:50 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:50 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring Jan 10 21:35:50 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:50 volumio sudo[2048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 10 21:35:50 volumio sudo[2048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:50 volumio sudo[2048]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:50 volumio sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 21:35:50 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:50 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:50 volumio sudo[2057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 10 21:35:50 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:51 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:51 volumio wireless.js[1969]: SETTING APPROPRIATE REG DOMAIN: DE Jan 10 21:35:51 volumio sudo[2062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 21:35:51 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:51 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:51 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jan 10 21:35:51 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:51 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:51 volumio wireless.js[1969]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jan 10 21:35:51 volumio sudo[2071]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 10 21:35:51 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:51 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:51 volumio sudo[2073]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 10 21:35:51 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:51 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:51 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:51 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:51 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring Jan 10 21:35:51 volumio avahi-daemon[892]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:51 volumio avahi-daemon[892]: New relevant interface wlan0.IPv4 for mDNS. Jan 10 21:35:51 volumio avahi-daemon[892]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 10 21:35:51 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 10 21:35:51 volumio hostapd[2079]: Configuration file: /etc/hostapd/hostapd.conf Jan 10 21:35:51 volumio avahi-daemon[892]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 10 21:35:51 volumio avahi-daemon[892]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:51 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:51 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Jan 10 21:35:51 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring Jan 10 21:35:51 volumio avahi-daemon[892]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 10 21:35:51 volumio avahi-daemon[892]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 10 21:35:51 volumio avahi-daemon[892]: New relevant interface wlan0.IPv4 for mDNS. Jan 10 21:35:51 volumio avahi-daemon[892]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 10 21:35:51 volumio hostapd[2079]: Using interface wlan0 with hwaddr 34:02:86:ea:72:89 and ssid "Volumio-44268" Jan 10 21:35:51 volumio dhcpcd[1020]: wlan0: carrier acquired Jan 10 21:35:51 volumio hostapd[2079]: wlan0: interface state UNINITIALIZED->ENABLED Jan 10 21:35:51 volumio hostapd[2079]: wlan0: AP-ENABLED Jan 10 21:35:51 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 10 21:35:52 volumio dhcpcd[1020]: wlan0: IAID 86:ea:72:89 Jan 10 21:35:52 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 10 21:35:52 volumio dnsmasq[2082]: dnsmasq: syntax check OK. Jan 10 21:35:52 volumio iwconfig[2085]: Error for wireless request "Set Power Management" (8B2C) : Jan 10 21:35:52 volumio iwconfig[2085]: SET failed on device wlan0 ; Invalid argument. Jan 10 21:35:52 volumio systemd[1]: wireless.service: Succeeded. Jan 10 21:35:52 volumio systemd[1]: Started Wireless Services. Jan 10 21:35:52 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:52 volumio dhcpcd[1020]: wlan0: soliciting an IPv6 router Jan 10 21:35:52 volumio dnsmasq[2090]: started, version 2.80 cachesize 150 Jan 10 21:35:52 volumio dnsmasq[2090]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 10 21:35:52 volumio dnsmasq-dhcp[2090]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 10 21:35:52 volumio dnsmasq[2090]: reading /etc/resolv.conf Jan 10 21:35:52 volumio dnsmasq[2090]: using nameserver 192.168.128.1#53 Jan 10 21:35:52 volumio dnsmasq[2090]: using nameserver 208.67.222.222#53 Jan 10 21:35:52 volumio dnsmasq[2090]: using nameserver 208.67.220.220#53 Jan 10 21:35:52 volumio dnsmasq[2090]: read /etc/hosts - 5 addresses Jan 10 21:35:52 volumio dhcpcd[1020]: wlan0: soliciting a DHCP lease Jan 10 21:35:52 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 10 21:35:52 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 10 21:35:53 volumio ntpd[1273]: Deleting interface #5 wlan0, 169.254.24.169#123, interface stats: received=0, sent=0, dropped=0, active_time=11 secs Jan 10 21:35:54 volumio volumio[1350]: info: Discovery: Started advertising with name: Volumio Jan 10 21:35:54 volumio volumio[1350]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 10 21:35:55 volumio sudo[2114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 21:35:55 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:55 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:55 volumio sudo[2116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 21:35:55 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:55 volumio sudo[2116]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:55 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 10 21:35:55 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:55 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 10 21:35:55 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:55 volumio volumio[1350]: info: Upmpdcli Daemon Started Jan 10 21:35:55 volumio volumio[2122]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 10 21:35:55 volumio volumio[1350]: info: Discovery: adding f3df6796-b123-41b6-8239-58c79955952d Jan 10 21:35:55 volumio volumio[1350]: info: Discovery: Found device Volumio Jan 10 21:35:55 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:35:55 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:55 volumio volumio[1350]: info: Discovery: this is already registered, f3df6796-b123-41b6-8239-58c79955952d Jan 10 21:35:55 volumio volumio[1350]: info: Discovery: Found device Volumio Jan 10 21:35:55 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:35:55 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:35:55 volumio volumio[2122]: ....................++++ Jan 10 21:35:57 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jan 10 21:35:57 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jan 10 21:35:57 volumio sudo[2162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 21:35:57 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:35:57 volumio sudo[2162]: pam_unix(sudo:session): session closed for user root Jan 10 21:35:57 volumio dhcpcd[1020]: wlan0: probing for an IPv4LL address Jan 10 21:35:57 volumio volumio-remote-updater[890]: [2025-01-10 21:35:57] [connect] Successful connection Jan 10 21:35:57 volumio volumio-remote-updater[890]: [2025-01-10 21:35:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736544957 101 Jan 10 21:35:57 volumio volumio[1350]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 10 21:35:58 volumio volumio[1350]: info: Adding plugin bluetooth to MyMusic Plugins Jan 10 21:35:58 volumio volumio[1350]: info: Adding plugin multiroom to MyMusic Plugins Jan 10 21:35:58 volumio volumio[1350]: info: Adding plugin metavolumio to MyMusic Plugins Jan 10 21:35:58 volumio volumio[1350]: info: Adding plugin cd_controller to MyMusic Plugins Jan 10 21:35:58 volumio volumio[1350]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 10 21:35:58 volumio volumio[1350]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 10 21:35:58 volumio volumio[1350]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 10 21:35:59 volumio volumio[2122]: ........................................................................................................++++ Jan 10 21:35:59 volumio volumio[2122]: e is 65537 (0x010001) Jan 10 21:35:59 volumio volumio[2122]: writing RSA key Jan 10 21:36:00 volumio volumio[1350]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 10 21:36:00 volumio volumio[1350]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 10 21:36:00 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:00 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:00 volumio volumio[1350]: info: Starting MyVolumio Remote Streaming Endpoints Jan 10 21:36:00 volumio volumio[1350]: info: MyVolumio not started Jan 10 21:36:00 volumio volumio[1350]: info: Initializing device activation check Jan 10 21:36:00 volumio volumio[1350]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 10 21:36:00 volumio volumio[1350]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 10 21:36:02 volumio dhcpcd[1020]: wlan0: using IPv4LL address 169.254.24.169 Jan 10 21:36:02 volumio avahi-daemon[892]: Registering new address record for 169.254.24.169 on wlan0.IPv4. Jan 10 21:36:02 volumio dhcpcd[1020]: wlan0: adding route to 169.254.0.0/16 Jan 10 21:36:02 volumio volumio[1350]: info: Starting Streaming Service Transparent Proxy Jan 10 21:36:02 volumio volumio[1350]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 10 21:36:02 volumio volumio[1350]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 10 21:36:02 volumio volumio[1350]: info: Streaming services startup Jan 10 21:36:02 volumio volumio[1350]: info: Starting Streaming Daemon Jan 10 21:36:02 volumio sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 10 21:36:02 volumio sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:02 volumio volumio[1350]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 10 21:36:02 volumio sudo[2218]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:02 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jan 10 21:36:02 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jan 10 21:36:02 volumio volumio[1350]: info: Refreshing Cached IP Addresses Jan 10 21:36:02 volumio sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jan 10 21:36:02 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:02 volumio sudo[2225]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:02 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 21:36:02 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:02 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:02 volumio sudo[2230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 21:36:02 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:02 volumio sudo[2230]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:02 volumio volumio[1350]: error: Cannot start Volumio Streaming Daemon Jan 10 21:36:02 volumio volumio[1350]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 10 21:36:02 volumio volumio[1350]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 10 21:36:02 volumio systemd[1]: upmpdcli.service: Succeeded. Jan 10 21:36:03 volumio volumio[1350]: STREAMING PROXY: Starting server on port 3245 Jan 10 21:36:03 volumio volumio[1350]: Node JS runtime: 14 Jan 10 21:36:03 volumio ntpd[1273]: Listen normally on 6 wlan0 169.254.24.169:123 Jan 10 21:36:03 volumio ntpd[1273]: new interface(s) found: waking up resolver Jan 10 21:36:10 volumio sudo[2391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 21:36:10 volumio sudo[2389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 21:36:10 volumio sudo[2391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:10 volumio sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:10 volumio sudo[2391]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:10 volumio sudo[2389]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:11 volumio volumio[1350]: 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: 3 Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jan 10 21:36:11 volumio volumio[1350]: info: Received Get System Info Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 21:36:11 volumio volumio[1350]: info: Discovery: Getting this device information Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:36:11 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 21:36:11 volumio volumio-remote-updater[890]: No test mode Jan 10 21:36:11 volumio volumio-remote-updater[890]: No alpha test mode Jan 10 21:36:11 volumio volumio[1350]: verbose: New Socket.io Connection to 192.168.129.53 from 192.168.129.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 OPR/115.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 10 21:36:11 volumio volumio[1350]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 21:36:11 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:12 volumio volumio[1350]: info: Retrieving Cloud Streaming UI Jan 10 21:36:12 volumio volumio[1350]: info: Getting Tidal Cloud Configuration Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:12 volumio volumio[1350]: info: Getting Qobuz Cloud Configuration Jan 10 21:36:12 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:12 volumio volumio[1350]: info: Getting Spotify Cloud Configuration Jan 10 21:36:12 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:12 volumio volumio[1350]: info: Saving Spotify Acccount Jan 10 21:36:12 volumio volumio[1350]: info: Got Tidal Cloud Configuration Jan 10 21:36:12 volumio volumio[1350]: info: Got it Jan 10 21:36:12 volumio volumio[1350]: info: Got it Jan 10 21:36:12 volumio volumio[1350]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:12 volumio volumio[1350]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 21:36:12 volumio sudo[2399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 21:36:12 volumio sudo[2399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:12 volumio sudo[2399]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:12 volumio sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 21:36:12 volumio sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:12 volumio sudo[2401]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:12 volumio sudo[2405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 10 21:36:12 volumio sudo[2405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 10 21:36:12 volumio sudo[2405]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:12 volumio volumio[1350]: info: Upmpdcli Daemon Started Jan 10 21:36:12 volumio volumio[2407]: writing RSA key Jan 10 21:36:12 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 10 21:36:12 volumio volumio[1350]: info: MyVolumio login type: Token Jan 10 21:36:13 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jan 10 21:36:13 volumio volumio[1350]: info: Loading i18n strings for locale en Jan 10 21:36:13 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jan 10 21:36:13 volumio volumio[1350]: info: Setting timezone to UTC Jan 10 21:36:13 volumio sudo[2439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jan 10 21:36:13 volumio sudo[2439]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:13 volumio sudo[2439]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:13 volumio sudo[2442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Jan 10 21:36:13 volumio sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:13 volumio sudo[2442]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:13 volumio sudo[2445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jan 10 21:36:13 volumio sudo[2445]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:13 volumio sudo[2445]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:13 volumio sudo[2448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jan 10 21:36:13 volumio sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:13 volumio dbus-daemon[894]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.21' (uid=0 pid=2449 comm="/usr/bin/timedatectl set-timezone UTC ") Jan 10 21:36:13 volumio systemd[1]: Starting Time & Date Service... Jan 10 21:36:13 volumio dbus-daemon[894]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 10 21:36:13 volumio systemd[1]: Started Time & Date Service. Jan 10 21:36:13 volumio sudo[2448]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:13 volumio volumio[1350]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 10 21:36:13 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 21:36:13 volumio volumio[1350]: info: Fetching Streaming Services browse cache Jan 10 21:36:13 volumio volumio[1350]: Updating browse sources language Jan 10 21:36:13 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:36:15 volumio volumio[1350]: info: MyVolumio token set successfully Jan 10 21:36:15 volumio volumio[1350]: info: MYVOLUMIO: Adding device Jan 10 21:36:15 volumio volumio[1350]: info: MYVOLUMIO: Evaluating Server Jan 10 21:36:15 volumio volumio[1350]: info: MyVolumio status changed Jan 10 21:36:15 volumio volumio[1350]: info: Streaming services startup Jan 10 21:36:15 volumio volumio[1350]: info: Starting Streaming Daemon Jan 10 21:36:15 volumio volumio[1350]: info: Removing browser output: myVolumio user plan is not superstar Jan 10 21:36:15 volumio volumio[1350]: info: Removing audio output: Jan 10 21:36:15 volumio volumio[1350]: info: Stoppping Tunnel 1 Jan 10 21:36:15 volumio sudo[2483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 10 21:36:15 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:15 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 21:36:15 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:15 volumio sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 10 21:36:15 volumio volumio[1350]: error: Cannot start Volumio Streaming Daemon Jan 10 21:36:15 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:15 volumio volumio[1350]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 10 21:36:15 volumio volumio[1350]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 10 21:36:15 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:15 volumio volumio[1350]: info: Remote SSH Stopped Jan 10 21:36:15 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 21:36:15 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 21:36:15 volumio volumio[1350]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 10 21:36:15 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:36:15 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:36:15 volumio volumio[1350]: info: Setting Geolocation for MyVolumio to eu5 Jan 10 21:36:15 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:15 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:16 volumio volumio[1350]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 10 21:36:16 volumio volumio[1350]: info: Updating MyVolumio device info Jan 10 21:36:16 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:16 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:16 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:17 volumio volumio[1350]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 10 21:36:18 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 21:36:18 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:18 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 10 21:36:18 volumio volumio-remote-updater[890]: No test mode Jan 10 21:36:18 volumio volumio-remote-updater[890]: No alpha test mode Jan 10 21:36:18 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 10 21:36:18 volumio volumio[1350]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 10 21:36:18 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 10 21:36:20 volumio volumio[1350]: info: MYVOLUMIO: Adding device Jan 10 21:36:20 volumio volumio[1350]: info: MYVOLUMIO: Evaluating Server Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 21:36:20 volumio volumio[1350]: info: Setting Geolocation for MyVolumio to eu8 Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:20 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:21 volumio volumio[1350]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 10 21:36:21 volumio volumio[1350]: info: Updating MyVolumio device info Jan 10 21:36:21 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:22 volumio volumio[1350]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 10 21:36:22 volumio volumio[1350]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 10 21:36:22 volumio volumio[1350]: info: Completed starting MyVolumio Plugin Jan 10 21:36:22 volumio volumio[1350]: [Metrics] CommandRouter: 40s 644.82ms Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::volumiosetStartupVolume Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::Close All Modals sent Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::Close All Modals sent Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: info: Retrieving Cloud Streaming UI Jan 10 21:36:22 volumio volumio[1350]: info: Getting Tidal Cloud Configuration Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: info: Getting Qobuz Cloud Configuration Jan 10 21:36:22 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: info: Getting Spotify Cloud Configuration Jan 10 21:36:22 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: info: Saving Spotify Acccount Jan 10 21:36:22 volumio volumio[1350]: info: Got Tidal Cloud Configuration Jan 10 21:36:22 volumio volumio[1350]: info: Got it Jan 10 21:36:22 volumio volumio[1350]: info: Got it Jan 10 21:36:22 volumio volumio[1350]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: info: Retrieving Cloud Streaming UI Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 10 21:36:22 volumio volumio[1350]: info: Getting Tidal Cloud Configuration Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: info: Getting Qobuz Cloud Configuration Jan 10 21:36:22 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: info: Getting Spotify Cloud Configuration Jan 10 21:36:22 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: info: Saving Spotify Acccount Jan 10 21:36:22 volumio volumio[1350]: info: Got Tidal Cloud Configuration Jan 10 21:36:22 volumio volumio[1350]: info: Got it Jan 10 21:36:22 volumio volumio[1350]: info: Got it Jan 10 21:36:22 volumio volumio[1350]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 10 21:36:22 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:22 volumio volumio[1350]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 10 21:36:23 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 10 21:36:23 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 21:36:23 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 10 21:36:23 volumio volumio[1350]: info: Version has changed, forcing UI Reload Jan 10 21:36:23 volumio volumio[1350]: info: CoreCommandRouter::Reload Ui Jan 10 21:36:24 volumio sudo[2685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 21:36:24 volumio sudo[2685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:24 volumio sudo[2685]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:24 volumio sudo[2687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 21:36:24 volumio sudo[2687]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:24 volumio sudo[2687]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:24 volumio volumio[1350]: verbose: New Socket.io Connection to 192.168.129.53 from 192.168.129.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 OPR/115.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 10 21:36:24 volumio volumio[1350]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jan 10 21:36:24 volumio volumio[1350]: info: Received Get System Info Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 21:36:24 volumio volumio[1350]: info: Discovery: Getting this device information Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:36:24 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 21:36:24 volumio volumio-remote-updater[890]: No test mode Jan 10 21:36:24 volumio volumio-remote-updater[890]: No alpha test mode Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: info: Retrieving Cloud Streaming UI Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jan 10 21:36:24 volumio volumio[1350]: info: Received Get System Info Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 21:36:24 volumio volumio[1350]: info: Discovery: Getting this device information Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::volumioGetState Jan 10 21:36:24 volumio volumio[1350]: info: CorePlayQueue::getTrack 0 Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 21:36:24 volumio volumio-remote-updater[890]: No test mode Jan 10 21:36:24 volumio volumio-remote-updater[890]: No alpha test mode Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: info: Retrieving Cloud Streaming UI Jan 10 21:36:24 volumio volumio[1350]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 10 21:36:24 volumio volumio[1350]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 10 21:36:24 volumio volumio[1350]: info: Getting Tidal Cloud Configuration Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: info: Getting Qobuz Cloud Configuration Jan 10 21:36:24 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: info: Getting Spotify Cloud Configuration Jan 10 21:36:24 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: info: Saving Spotify Acccount Jan 10 21:36:24 volumio volumio[1350]: info: Got Tidal Cloud Configuration Jan 10 21:36:24 volumio volumio[1350]: info: Got it Jan 10 21:36:24 volumio volumio[1350]: info: Got it Jan 10 21:36:24 volumio volumio[1350]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 10 21:36:24 volumio volumio[1350]: info: Getting Tidal Cloud Configuration Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: info: Getting Qobuz Cloud Configuration Jan 10 21:36:24 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: info: Getting Spotify Cloud Configuration Jan 10 21:36:24 volumio volumio[1350]: info: Asking plugin for UI Config Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: info: Saving Spotify Acccount Jan 10 21:36:24 volumio volumio[1350]: info: Got Tidal Cloud Configuration Jan 10 21:36:24 volumio volumio[1350]: info: Got it Jan 10 21:36:24 volumio volumio[1350]: info: Got it Jan 10 21:36:24 volumio volumio[1350]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 10 21:36:24 volumio volumio[1350]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:36:24 volumio volumio[1350]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 10 21:36:25 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jan 10 21:36:25 volumio volumio[1350]: info: Loading i18n strings for locale fr Jan 10 21:36:25 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jan 10 21:36:25 volumio volumio[1350]: info: Setting timezone to Europe/Brussels Jan 10 21:36:25 volumio sudo[2713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jan 10 21:36:25 volumio sudo[2713]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:25 volumio sudo[2713]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:26 volumio sudo[2716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Brussels /etc/localtime Jan 10 21:36:26 volumio sudo[2716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:26 volumio sudo[2716]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:26 volumio sudo[2719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jan 10 21:36:26 volumio sudo[2719]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:26 volumio sudo[2719]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:26 volumio sudo[2722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Brussels Jan 10 21:36:26 volumio sudo[2722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:26 volumio systemd-timedated[2450]: Failed to set time zone: Permission denied Jan 10 21:36:26 volumio sudo[2722]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:26 volumio volumio[1350]: Failed to set time zone: Access denied Jan 10 21:36:26 volumio volumio[1350]: info: Could not set timezone, retrying Jan 10 21:36:26 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 21:36:26 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 21:36:26 volumio volumio[1350]: Updating browse sources language Jan 10 21:36:26 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:36:26 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jan 10 21:36:26 volumio volumio[1350]: info: Loading i18n strings for locale en Jan 10 21:36:26 volumio volumio[1350]: info: Fetching Streaming Services browse cache Jan 10 21:36:26 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jan 10 21:36:26 volumio volumio[1350]: info: Setting timezone to UTC Jan 10 21:36:26 volumio sudo[2725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jan 10 21:36:26 volumio sudo[2725]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:26 volumio sudo[2725]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:26 volumio sudo[2728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Jan 10 21:36:26 volumio sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:26 volumio sudo[2728]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:26 volumio sudo[2731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jan 10 21:36:26 volumio sudo[2731]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:26 volumio sudo[2731]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:26 volumio sudo[2734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jan 10 21:36:26 volumio sudo[2734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:26 volumio systemd-timedated[2450]: Failed to set time zone: Permission denied Jan 10 21:36:26 volumio sudo[2734]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:26 volumio volumio[1350]: Failed to set time zone: Access denied Jan 10 21:36:26 volumio volumio[1350]: info: Could not set timezone, retrying Jan 10 21:36:26 volumio volumio[1350]: Updating browse sources language Jan 10 21:36:26 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 21:36:26 volumio volumio[1350]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 21:36:26 volumio volumio[1350]: info: Fetching Streaming Services browse cache Jan 10 21:36:27 volumio sudo[2737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Brussels Jan 10 21:36:27 volumio sudo[2737]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:36:27 volumio systemd-timedated[2450]: Failed to set time zone: Permission denied Jan 10 21:36:27 volumio sudo[2737]: pam_unix(sudo:session): session closed for user root Jan 10 21:36:27 volumio volumio[1350]: Failed to set time zone: Access denied Jan 10 21:36:27 volumio volumio[1350]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 21:36:27 volumio volumio[1350]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Europe/Brussels' Jan 10 21:36:27 volumio volumio[1350]: Failed to set time zone: Access denied Jan 10 21:36:27 volumio volumio[1350]: at checkExecSyncError (child_process.js:790:11) Jan 10 21:36:27 volumio volumio[1350]: at execSync (child_process.js:863:15) Jan 10 21:36:27 volumio volumio[1350]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Jan 10 21:36:27 volumio volumio[1350]: at listOnTimeout (internal/timers.js:557:17) Jan 10 21:36:27 volumio volumio[1350]: at processTimers (internal/timers.js:500:7) { Jan 10 21:36:27 volumio volumio[1350]: status: 1, Jan 10 21:36:27 volumio volumio[1350]: signal: null, Jan 10 21:36:27 volumio volumio[1350]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Jan 10 21:36:27 volumio volumio[1350]: pid: 2736, Jan 10 21:36:27 volumio volumio[1350]: stdout: '', Jan 10 21:36:27 volumio volumio[1350]: stderr: 'Failed to set time zone: Access denied\n' Jan 10 21:36:27 volumio volumio[1350]: } Jan 10 21:36:27 volumio volumio[1350]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 21:36:27 volumio sudo[2748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-10 21:35 Jan 10 21:36:27 volumio sudo[2748]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"