-- Logs begin at Fri 2025-10-31 17:18:27 EET, end at Fri 2025-10-31 17:20:40 EET. --
Oct 31 17:19:00 volumio sudo[864]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 31 17:19:00 volumio sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:00 volumio sudo[864]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Oct 31 17:19:00 volumio sudo[864]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:00 volumio ntpd[927]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
Oct 31 17:19:00 volumio dhcpcd[851]: eth0: carrier acquired
Oct 31 17:19:00 volumio kernel: r8169 0000:06:00.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx
Oct 31 17:19:00 volumio sh[642]: eth0: carrier acquired
Oct 31 17:19:00 volumio dhcpcd[692]: eth0: carrier acquired
Oct 31 17:19:00 volumio sh[642]: eth0: IAID a1:c5:24:d4
Oct 31 17:19:00 volumio dhcpcd[692]: eth0: IAID a1:c5:24:d4
Oct 31 17:19:00 volumio dhcpcd[851]: eth0: IAID a1:c5:24:d4
Oct 31 17:19:00 volumio sh[642]: eth0: soliciting an IPv6 router
Oct 31 17:19:00 volumio dhcpcd[692]: eth0: soliciting an IPv6 router
Oct 31 17:19:01 volumio ntpd[927]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
Oct 31 17:19:01 volumio ifplugd(eth0)[934]: Link beat detected.
Oct 31 17:19:01 volumio sh[642]: eth0: soliciting a DHCP lease
Oct 31 17:19:01 volumio dhcpcd[692]: eth0: soliciting a DHCP lease
Oct 31 17:19:01 volumio ifplugd(eth0)[934]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Oct 31 17:19:01 volumio dhcpcd[851]: eth0: soliciting an IPv6 router
Oct 31 17:19:01 volumio volumio-time-update[714]: volumio-time-update-util: Fetching time from Volumio...
Oct 31 17:19:01 volumio volumio-time-update[714]: volumio-time-update-util: Date not found in response
Oct 31 17:19:01 volumio volumio-time-update[714]: volumio-time-update-util: Retrying in 5 seconds...
Oct 31 17:19:01 volumio dhcpcd[851]: eth0: soliciting a DHCP lease
Oct 31 17:19:01 volumio volumio-remote-updater[728]: [2025-10-31 15:19:01] [info] asio async_connect error: system:111 (Connection refused)
Oct 31 17:19:01 volumio volumio-remote-updater[728]: [2025-10-31 15:19:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Oct 31 17:19:01 volumio volumio-remote-updater[728]: [2025-10-31 15:19:01] [error] handle_connect error: Underlying Transport Error
Oct 31 17:19:02 volumio ntpd[927]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Oct 31 17:19:03 volumio nmbd[871]: [2025/10/31 15:19:03.071472, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
Oct 31 17:19:03 volumio nmbd[871]: started asyncdns process 962
Oct 31 17:19:03 volumio nmbd[871]: [2025/10/31 15:19:03.079919, 0] ../lib/util/become_daemon.c:149(daemon_status)
Oct 31 17:19:03 volumio nmbd[871]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
Oct 31 17:19:03 volumio nmbd[871]: [2025/10/31 15:19:03.080267, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets)
Oct 31 17:19:03 volumio nmbd[871]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
Oct 31 17:19:03 volumio ifplugd(eth0)[934]: client: ifup: waiting for lock on /run/network/ifstate.eth0
Oct 31 17:19:03 volumio ntpd[927]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Oct 31 17:19:03 volumio wireless.js[722]: Cleaning previous...
Oct 31 17:19:03 volumio sudo[973]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Oct 31 17:19:03 volumio sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:03 volumio sudo[973]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:04 volumio sudo[975]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Oct 31 17:19:04 volumio sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:04 volumio sudo[975]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:04 volumio wireless.js[722]: Stopped aP
Oct 31 17:19:04 volumio sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Oct 31 17:19:04 volumio sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:04 volumio sudo[982]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:04 volumio sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Oct 31 17:19:04 volumio sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:04 volumio sudo[984]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:04 volumio sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Oct 31 17:19:04 volumio sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:04 volumio sudo[991]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:04 volumio sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Oct 31 17:19:04 volumio sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:05 volumio sh[642]: eth0: offered 192.168.0.206 from 192.168.0.1
Oct 31 17:19:05 volumio dhcpcd[692]: eth0: offered 192.168.0.206 from 192.168.0.1
Oct 31 17:19:05 volumio dhcpcd[851]: eth0: offered 192.168.0.206 from 192.168.0.1
Oct 31 17:19:05 volumio sh[642]: eth0: probing address 192.168.0.206/24
Oct 31 17:19:05 volumio dhcpcd[692]: eth0: probing address 192.168.0.206/24
Oct 31 17:19:05 volumio dhcpcd[851]: eth0: probing address 192.168.0.206/24
Oct 31 17:19:05 volumio sudo[993]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:05 volumio wireless.js[722]: SETTING APPROPRIATE REG DOMAIN: GR
Oct 31 17:19:05 volumio sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Oct 31 17:19:05 volumio sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:05 volumio sudo[998]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:05 volumio sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GR
Oct 31 17:19:05 volumio sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:05 volumio sudo[1000]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:05 volumio wireless.js[722]: SUCCESSFULLY SET NEW REGDOMAIN: GR
Oct 31 17:19:05 volumio sudo[1007]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Oct 31 17:19:05 volumio sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:05 volumio sudo[1007]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:05 volumio sudo[1009]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Oct 31 17:19:05 volumio sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:05 volumio sudo[1009]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:05 volumio avahi-daemon[721]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:05 volumio avahi-daemon[721]: New relevant interface wlan0.IPv4 for mDNS.
Oct 31 17:19:05 volumio avahi-daemon[721]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
Oct 31 17:19:05 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
Oct 31 17:19:06 volumio hostapd[1015]: Configuration file: /etc/hostapd/hostapd.conf
Oct 31 17:19:06 volumio avahi-daemon[721]: Interface wlan0.IPv4 no longer relevant for mDNS.
Oct 31 17:19:06 volumio avahi-daemon[721]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:06 volumio hostapd[1015]: Using interface wlan0 with hwaddr 94:39:e5:2d:0b:9e and ssid "Volumio"
Oct 31 17:19:06 volumio avahi-daemon[721]: Withdrawing address record for 192.168.211.1 on wlan0.
Oct 31 17:19:06 volumio avahi-daemon[721]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:06 volumio avahi-daemon[721]: New relevant interface wlan0.IPv4 for mDNS.
Oct 31 17:19:06 volumio avahi-daemon[721]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
Oct 31 17:19:06 volumio dhcpcd[851]: wlan0: carrier acquired
Oct 31 17:19:06 volumio kernel: cryptd: max_cpu_qlen set to 1000
Oct 31 17:19:06 volumio hostapd[1015]: wlan0: interface state UNINITIALIZED->ENABLED
Oct 31 17:19:06 volumio hostapd[1015]: wlan0: AP-ENABLED
Oct 31 17:19:06 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
Oct 31 17:19:06 volumio dhcpcd[851]: wlan0: IAID e5:2d:0b:9e
Oct 31 17:19:06 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server...
Oct 31 17:19:06 volumio iwconfig[1039]: Error for wireless request "Set Power Management" (8B2C) :
Oct 31 17:19:06 volumio iwconfig[1039]: SET failed on device wlan0 ; Invalid argument.
Oct 31 17:19:06 volumio systemd[1]: wireless.service: Succeeded.
Oct 31 17:19:06 volumio systemd[1]: Started Wireless Services.
Oct 31 17:19:06 volumio dnsmasq[1037]: dnsmasq: syntax check OK.
Oct 31 17:19:06 volumio systemd[1]: Started Volumio Backend Module.
Oct 31 17:19:06 volumio systemd[1]: Started First Start.
Oct 31 17:19:06 volumio systemd[1]: Started Intel SST and HDA soundcard init service.
Oct 31 17:19:06 volumio firststart.sh[1041]: Volumio first start configuration script
Oct 31 17:19:06 volumio firststart.sh[1041]: Configuring unconfigured packages
Oct 31 17:19:06 volumio systemd[1]: Started Start Volumio Kiosk.
Oct 31 17:19:06 volumio systemd[1]: Started Volumio Cpu Tweaker.
Oct 31 17:19:06 volumio dhcpcd[851]: wlan0: soliciting an IPv6 router
Oct 31 17:19:06 volumio volumio-time-update[714]: volumio-time-update-util: Fetching time from Volumio...
Oct 31 17:19:06 volumio volumio-cpu-tweak[1052]: Setting RT Priority for mpd
Oct 31 17:19:07 volumio volumio-cpu-tweak[1052]: Not enough cores to set MPD affinity
Oct 31 17:19:07 volumio volumio-cpu-tweak[1052]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Oct 31 17:19:07 volumio volumio-time-update[714]: volumio-time-update-util: Date not found in response
Oct 31 17:19:07 volumio volumio-time-update[714]: volumio-time-update-util: Retrying in 5 seconds...
Oct 31 17:19:07 volumio mpd[946]: Oct 31 15:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 31 17:19:07 volumio mpd[946]: Oct 31 15:19 : exception: Database corrupted
Oct 31 17:19:07 volumio dhcpcd[851]: wlan0: soliciting a DHCP lease
Oct 31 17:19:07 volumio systemd[1]: Started Music Player Daemon.
Oct 31 17:19:07 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded.
Oct 31 17:19:07 volumio mpd_monitor.sh[703]: MPD Monitor Service: MPD restarted due to no mpc output.
Oct 31 17:19:07 volumio startx[1049]: xauth: file /root/.Xauthority does not exist
Oct 31 17:19:07 volumio dnsmasq[1109]: started, version 2.80 cachesize 150
Oct 31 17:19:07 volumio dnsmasq[1109]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile
Oct 31 17:19:07 volumio dnsmasq-dhcp[1109]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d
Oct 31 17:19:07 volumio dnsmasq[1109]: reading /etc/resolv.conf
Oct 31 17:19:07 volumio dnsmasq[1109]: using nameserver 208.67.222.222#53
Oct 31 17:19:07 volumio dnsmasq[1109]: using nameserver 208.67.220.220#53
Oct 31 17:19:07 volumio dnsmasq[1109]: read /etc/hosts - 5 addresses
Oct 31 17:19:07 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server.
Oct 31 17:19:07 volumio ntpd[927]: Listen normally on 3 wlan0 192.168.211.1:123
Oct 31 17:19:07 volumio ntpd[927]: new interface(s) found: waking up resolver
Oct 31 17:19:07 volumio systemd[1]: Reached target Host and Network Name Lookups.
Oct 31 17:19:08 volumio nmbd[871]: [2025/10/31 15:19:08.991386, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 31 17:19:08 volumio nmbd[871]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Oct 31 17:19:08 volumio systemd[1]: Started Samba NMB Daemon.
Oct 31 17:19:09 volumio systemd[1]: Starting Samba Winbind Daemon...
Oct 31 17:19:09 volumio firststart.sh[1041]: Creating /var/log/samba/cores folder
Oct 31 17:19:09 volumio firststart.sh[1041]: Removing default SSH host keys
Oct 31 17:19:09 volumio firststart.sh[1041]: removed '/etc/ssh/ssh_host_ecdsa_key'
Oct 31 17:19:09 volumio firststart.sh[1041]: removed '/etc/ssh/ssh_host_ecdsa_key.pub'
Oct 31 17:19:09 volumio firststart.sh[1041]: removed '/etc/ssh/ssh_host_ed25519_key'
Oct 31 17:19:09 volumio firststart.sh[1041]: removed '/etc/ssh/ssh_host_ed25519_key.pub'
Oct 31 17:19:09 volumio firststart.sh[1041]: removed '/etc/ssh/ssh_host_rsa_key'
Oct 31 17:19:09 volumio firststart.sh[1041]: removed '/etc/ssh/ssh_host_rsa_key.pub'
Oct 31 17:19:09 volumio firststart.sh[1041]: Generating SSH host keys
Oct 31 17:19:09 volumio volumio-remote-updater[728]: [2025-10-31 15:19:09] [info] asio async_connect error: system:111 (Connection refused)
Oct 31 17:19:09 volumio volumio-remote-updater[728]: [2025-10-31 15:19:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Oct 31 17:19:09 volumio volumio-remote-updater[728]: [2025-10-31 15:19:09] [error] handle_connect error: Underlying Transport Error
Oct 31 17:19:09 volumio startx[1049]: X.Org X Server 1.20.4
Oct 31 17:19:09 volumio startx[1049]: X Protocol Version 11, Revision 0
Oct 31 17:19:09 volumio startx[1049]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian
Oct 31 17:19:09 volumio startx[1049]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64
Oct 31 17:19:09 volumio startx[1049]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=52bdc0be-4dde-4be0-9cc0-f9fa8e84ca72 bootpart=UUID=B2D3-9A1B datapart=UUID=77dea117-29e9-4c52-af7a-36ea724babc0 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 initrd=volumio.initrd
Oct 31 17:19:09 volumio startx[1049]: Build Date: 18 December 2021 09:05:36AM
Oct 31 17:19:09 volumio startx[1049]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support)
Oct 31 17:19:09 volumio startx[1049]: Current version of pixman: 0.36.0
Oct 31 17:19:09 volumio startx[1049]: Before reporting problems, check http://wiki.x.org
Oct 31 17:19:09 volumio startx[1049]: to make sure that you have the latest version.
Oct 31 17:19:09 volumio startx[1049]: Markers: (--) probed, (**) from config file, (==) default setting,
Oct 31 17:19:09 volumio startx[1049]: (++) from command line, (!!) notice, (II) informational,
Oct 31 17:19:09 volumio startx[1049]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Oct 31 17:19:09 volumio startx[1049]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Oct 31 15:19:09 2025
Oct 31 17:19:09 volumio startx[1049]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Oct 31 17:19:10 volumio winbindd[1121]: [2025/10/31 15:19:10.192611, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Oct 31 17:19:10 volumio winbindd[1121]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Oct 31 17:19:10 volumio sh[642]: eth0: leased 192.168.0.206 for 43200 seconds
Oct 31 17:19:10 volumio dhcpcd[692]: eth0: leased 192.168.0.206 for 43200 seconds
Oct 31 17:19:10 volumio sh[642]: eth0: adding route to 192.168.0.0/24
Oct 31 17:19:10 volumio sh[642]: eth0: adding default route via 192.168.0.1
Oct 31 17:19:10 volumio avahi-daemon[721]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.206.
Oct 31 17:19:10 volumio avahi-daemon[721]: New relevant interface eth0.IPv4 for mDNS.
Oct 31 17:19:10 volumio avahi-daemon[721]: Registering new address record for 192.168.0.206 on eth0.IPv4.
Oct 31 17:19:10 volumio dhcpcd[692]: eth0: adding route to 192.168.0.0/24
Oct 31 17:19:10 volumio dhcpcd[692]: eth0: adding default route via 192.168.0.1
Oct 31 17:19:10 volumio dnsmasq[1109]: reading /etc/resolv.conf
Oct 31 17:19:10 volumio dnsmasq[1109]: using nameserver 192.168.0.1#53
Oct 31 17:19:10 volumio dnsmasq[1109]: using nameserver 208.67.222.222#53
Oct 31 17:19:10 volumio dnsmasq[1109]: using nameserver 208.67.220.220#53
Oct 31 17:19:10 volumio dhcpcd[851]: eth0: leased 192.168.0.206 for 43200 seconds
Oct 31 17:19:10 volumio dhcpcd[851]: eth0: adding route to 192.168.0.0/24
Oct 31 17:19:10 volumio dhcpcd[851]: eth0: adding default route via 192.168.0.1
Oct 31 17:19:10 volumio winbindd[1121]: [2025/10/31 15:19:10.407302, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 31 17:19:10 volumio winbindd[1121]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Oct 31 17:19:10 volumio systemd[1]: Started Samba Winbind Daemon.
Oct 31 17:19:10 volumio systemd[1]: Starting Samba SMB Daemon...
Oct 31 17:19:10 volumio startx[1049]: (II) [KMS] Kernel modesetting enabled.
Oct 31 17:19:10 volumio ntpd[927]: ntpd exiting on signal 15 (Terminated)
Oct 31 17:19:10 volumio systemd[1]: Stopping Network Time Service...
Oct 31 17:19:10 volumio systemd[1]: ntp.service: Succeeded.
Oct 31 17:19:10 volumio systemd[1]: Stopped Network Time Service.
Oct 31 17:19:10 volumio systemd[1]: Starting Network Time Service...
Oct 31 17:19:10 volumio systemd[1]: ntp.service: Control process exited, code=killed, status=15/TERM
Oct 31 17:19:10 volumio systemd[1]: ntp.service: Succeeded.
Oct 31 17:19:10 volumio systemd[1]: Stopped Network Time Service.
Oct 31 17:19:11 volumio systemd[1]: Starting Network Time Service...
Oct 31 17:19:11 volumio ntpd[1252]: ntpd 4.2.8p12@1.3728-o (1): Starting
Oct 31 17:19:11 volumio systemd[1]: Started Network Time Service.
Oct 31 17:19:11 volumio ntpd[1252]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Oct 31 17:19:11 volumio ntpd[1261]: proto: precision = 0.308 usec (-22)
Oct 31 17:19:11 volumio ntpd[1261]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Oct 31 17:19:11 volumio ntpd[1261]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Oct 31 17:19:11 volumio ntpd[1261]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1039 days ago
Oct 31 17:19:11 volumio ntpd[1261]: Listen and drop on 0 v6wildcard [::]:123
Oct 31 17:19:11 volumio ntpd[1261]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 31 17:19:11 volumio ntpd[1261]: Listen normally on 2 lo 127.0.0.1:123
Oct 31 17:19:11 volumio ntpd[1261]: Listen normally on 3 eth0 192.168.0.206:123
Oct 31 17:19:11 volumio ntpd[1261]: Listen normally on 4 wlan0 192.168.211.1:123
Oct 31 17:19:11 volumio sh[642]: forked to background, child pid 1262
Oct 31 17:19:11 volumio ntpd[1261]: Listening on routing socket on fd #21 for interface updates
Oct 31 17:19:11 volumio ntpd[1261]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 31 17:19:11 volumio ntpd[1261]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 31 17:19:11 volumio dhcpcd[692]: forked to background, child pid 1262
Oct 31 17:19:11 volumio ifplugd(eth0)[934]: client: ifup: interface eth0 already configured
Oct 31 17:19:11 volumio sh[642]: eth0=eth0
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: amixer: Unable to find simple control 'Surround',0
Oct 31 17:19:11 volumio ifplugd(eth0)[934]: Program executed successfully.
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: amixer: Unable to find simple control 'Center',0
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: amixer: Unable to find simple control 'LFE',0
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: amixer: Unable to find simple control 'IEC958',16
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: amixer: Unable to find simple control 'Front',0
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: Simple mixer control 'Headphone',0
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: Capabilities: pvolume pswitch
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: Playback channels: Front Left - Front Right
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: Limits: Playback 0 - 87
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: Mono:
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: Front Left: Playback 81 [93%] [-4.50dB] [on]
Oct 31 17:19:11 volumio soundcard-init.sh[1042]: Front Right: Playback 81 [93%] [-4.50dB] [on]
Oct 31 17:19:12 volumio soundcard-init.sh[1042]: Simple mixer control 'Master',0
Oct 31 17:19:12 volumio soundcard-init.sh[1042]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined
Oct 31 17:19:12 volumio soundcard-init.sh[1042]: Playback channels: Mono
Oct 31 17:19:12 volumio soundcard-init.sh[1042]: Limits: Playback 0 - 87
Oct 31 17:19:12 volumio soundcard-init.sh[1042]: Mono: Playback 66 [76%] [-15.75dB] [on]
Oct 31 17:19:12 volumio volumio-time-update[714]: volumio-time-update-util: Fetching time from Volumio...
Oct 31 17:19:12 volumio ntpd[1261]: Soliciting pool server 83.212.108.245
Oct 31 17:19:12 volumio systemd[1]: soundcard-init.service: Succeeded.
Oct 31 17:19:12 volumio dhcpcd[851]: wlan0: probing for an IPv4LL address
Oct 31 17:19:12 volumio volumio-time-update[714]: volumio-time-update-util: Setting system time to: 2025-10-31 15:19:12
Oct 31 17:19:12 volumio sudo[1328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-10-31 15:19:12
Oct 31 17:19:12 volumio sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:12 volumio dbus-daemon[705]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=1330 comm="timedatectl set-time 2025-10-31 15:19:12 ")
Oct 31 17:19:12 volumio systemd[1]: Starting Time & Date Service...
Oct 31 17:19:13 volumio ntpd[1261]: Soliciting pool server 185.201.254.21
Oct 31 17:19:13 volumio ntpd[1261]: Soliciting pool server 147.52.190.11
Oct 31 17:19:14 volumio ntpd[1261]: Soliciting pool server 91.217.155.60
Oct 31 17:19:14 volumio ntpd[1261]: Soliciting pool server 62.1.105.163
Oct 31 17:19:14 volumio ntpd[1261]: Soliciting pool server 91.217.155.60
Oct 31 17:19:14 volumio dbus-daemon[705]: [system] Successfully activated service 'org.freedesktop.timedate1'
Oct 31 17:19:14 volumio systemd[1]: Started Time & Date Service.
Oct 31 17:19:12 volumio systemd-timedated[1331]: Changed local time to Fri Oct 31 15:19:12 2025
Oct 31 17:19:12 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:12 volumio volumio-time-update[714]: volumio-time-update-util: System time updated successfully.
Oct 31 17:19:12 volumio smbd[1228]: [2025/10/31 15:19:12.023869, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 31 17:19:12 volumio systemd[1]: Started Samba SMB Daemon.
Oct 31 17:19:12 volumio smbd[1228]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Oct 31 17:19:12 volumio systemd[1]: Started Volumio Time Update Utility.
Oct 31 17:19:12 volumio systemd[1]: Reached target Multi-User System.
Oct 31 17:19:12 volumio systemd[1]: Reached target Graphical Interface.
Oct 31 17:19:12 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Oct 31 17:19:12 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Oct 31 17:19:12 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Oct 31 17:19:12 volumio systemd[1]: Startup finished in 3min 32.113s (kernel) + 52.626s (userspace) = 4min 24.740s.
Oct 31 17:19:12 volumio ntpd[1261]: Soliciting pool server 193.93.164.194
Oct 31 17:19:12 volumio ntpd[1261]: Soliciting pool server 162.159.200.123
Oct 31 17:19:13 volumio firststart.sh[1041]: debconf: unable to initialize frontend: Dialog
Oct 31 17:19:13 volumio firststart.sh[1041]: debconf: (TERM is not set, so the dialog frontend is not usable.)
Oct 31 17:19:13 volumio firststart.sh[1041]: debconf: falling back to frontend: Readline
Oct 31 17:19:13 volumio firststart.sh[1041]: debconf: unable to initialize frontend: Readline
Oct 31 17:19:13 volumio firststart.sh[1041]: debconf: (This frontend requires a controlling tty.)
Oct 31 17:19:13 volumio firststart.sh[1041]: debconf: falling back to frontend: Teletype
Oct 31 17:19:13 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Oct 31 17:19:14 volumio ntpd[1261]: Soliciting pool server 162.159.200.1
Oct 31 17:19:14 volumio dhcpcd[851]: wlan0: using IPv4LL address 169.254.128.247
Oct 31 17:19:14 volumio avahi-daemon[721]: Registering new address record for 169.254.128.247 on wlan0.IPv4.
Oct 31 17:19:14 volumio dhcpcd[851]: wlan0: adding route to 169.254.0.0/16
Oct 31 17:19:15 volumio ntpd[1261]: Soliciting pool server 83.212.109.72
Oct 31 17:19:16 volumio ntpd[1261]: Soliciting pool server 194.177.210.54
Oct 31 17:19:16 volumio ntpd[1261]: Listen normally on 5 wlan0 169.254.128.247:123
Oct 31 17:19:16 volumio ntpd[1261]: new interface(s) found: waking up resolver
Oct 31 17:19:17 volumio volumio[1040]: info: -------------------------------------------
Oct 31 17:19:17 volumio volumio[1040]: info: ----- Volumio3 ----
Oct 31 17:19:17 volumio volumio[1040]: info: -------------------------------------------
Oct 31 17:19:17 volumio volumio[1040]: info: ----- System startup ----
Oct 31 17:19:17 volumio volumio[1040]: info: -------------------------------------------
Oct 31 17:19:18 volumio firststart.sh[1041]: Creating SSH2 RSA key; this may take some time ...
Oct 31 17:19:18 volumio firststart.sh[1041]: 2048 SHA256:wqroRIPrXwkN6Hn12Lrzjbx9uGtQEQ7DkJrjT/d87wg root@volumio (RSA)
Oct 31 17:19:21 volumio ntpd[1261]: receive: Unexpected origin timestamp 0xecaf51f6.b36babd2 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xecaf51f9.75eb486c
Oct 31 17:19:21 volumio ntpd[1261]: receive: Unexpected origin timestamp 0xecaf51f6.b3795eb8 does not match aorg 0000000000.00000000 from server@62.1.105.163 xmt 0xecaf51f9.765ce595
Oct 31 17:19:21 volumio ntpd[1261]: receive: Unexpected origin timestamp 0xecaf51f6.b37e4114 does not match aorg 0000000000.00000000 from server@83.212.108.245 xmt 0xecaf51f9.76e4782e
Oct 31 17:19:21 volumio ntpd[1261]: receive: Unexpected origin timestamp 0xecaf51f6.b375b28d does not match aorg 0000000000.00000000 from server@91.217.155.60 xmt 0xecaf51f9.78288fff
Oct 31 17:19:21 volumio ntpd[1261]: receive: Unexpected origin timestamp 0xecaf51f6.b3732a95 does not match aorg 0000000000.00000000 from server@193.93.164.194 xmt 0xecaf51f9.84a34b9b
Oct 31 17:19:22 volumio firststart.sh[1041]: Creating SSH2 DSA key; this may take some time ...
Oct 31 17:19:22 volumio firststart.sh[1041]: 1024 SHA256:sICHuB1yw5kUkSGKuXGcNeshA8s4Tgydu0bTTEZabS4 root@volumio (DSA)
Oct 31 17:19:22 volumio firststart.sh[1041]: Creating SSH2 ECDSA key; this may take some time ...
Oct 31 17:19:22 volumio firststart.sh[1041]: 256 SHA256:IWI3cFHrWMgQLR5064c1ZDD8Vegw7vw3y+VY4ChnSOY root@volumio (ECDSA)
Oct 31 17:19:22 volumio volumio-remote-updater[728]: [2025-10-31 15:19:22] [connect] Successful connection
Oct 31 17:19:22 volumio volumio[1040]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio
Oct 31 17:19:22 volumio volumio[1040]: info: MYVOLUMIO Environment detected
Oct 31 17:19:22 volumio volumio[1040]: info: Plugin folders cleanup
Oct 31 17:19:22 volumio volumio[1040]: info: Scanning into folder /volumio/app/plugins/
Oct 31 17:19:22 volumio volumio[1040]: info: Scanning category audio_interface
Oct 31 17:19:22 volumio volumio[1040]: info: Scanning category miscellanea
Oct 31 17:19:22 volumio volumio[1040]: info: Scanning category music_service
Oct 31 17:19:22 volumio volumio[1040]: info: Scanning category plugins.json
Oct 31 17:19:22 volumio volumio[1040]: info: Scanning category system_controller
Oct 31 17:19:22 volumio volumio[1040]: info: Scanning category user_interface
Oct 31 17:19:22 volumio volumio[1040]: info: Scanning into folder /data/plugins/
Oct 31 17:19:22 volumio volumio[1040]: info: Plugin folders cleanup completed
Oct 31 17:19:22 volumio volumio[1040]: info: -------------------------------------------
Oct 31 17:19:22 volumio volumio[1040]: info: ----- Core plugins startup ----
Oct 31 17:19:22 volumio volumio[1040]: info: -------------------------------------------
Oct 31 17:19:22 volumio volumio[1040]: info: Loading plugins from folder /volumio/app/plugins/
Oct 31 17:19:22 volumio volumio[1040]: info: Adding plugin upnp to MyMusic Plugins
Oct 31 17:19:22 volumio volumio[1040]: info: Adding plugin airplay_emulation to MyMusic Plugins
Oct 31 17:19:22 volumio volumio[1040]: info: Adding plugin upnp_browser to MyMusic Plugins
Oct 31 17:19:22 volumio volumio[1040]: info: Loading plugins from folder /data/plugins/
Oct 31 17:19:22 volumio volumio[1040]: info: Loading plugin "system"...
Oct 31 17:19:22 volumio volumio[1040]: info: No id defined. Creating one
Oct 31 17:19:22 volumio volumio[1040]: info: Loading plugin "appearance"...
Oct 31 17:19:24 volumio systemd[1]: Reloading.
Oct 31 17:19:24 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.
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "network"...
Oct 31 17:19:25 volumio volumio[1040]: info: Refreshing Cached IP Addresses
Oct 31 17:19:25 volumio sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 31 17:19:25 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:25 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:25 volumio sudo[1549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 31 17:19:25 volumio sudo[1549]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "services"...
Oct 31 17:19:25 volumio sudo[1549]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "alsa_controller"...
Oct 31 17:19:25 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Oct 31 17:19:25 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Oct 31 17:19:25 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:25 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:25 volumio volumio[1040]: warn: The ALSA output card is not set, defaulting to card [object Object]
Oct 31 17:19:25 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 31 17:19:25 volumio volumio[1040]: info: Found match in Cards Database: setting mixer for card Analog Out
Oct 31 17:19:25 volumio volumio[1040]: info: Setting mixer Master for card Analog Out
Oct 31 17:19:25 volumio firststart.sh[1041]: rescue-ssh.target is a disabled or a static unit, not starting it.
Oct 31 17:19:25 volumio volumio[1040]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Oct 31 17:19:25 volumio volumio[1040]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Oct 31 17:19:25 volumio volumio[1040]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Oct 31 17:19:25 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "wizard"...
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "networkfs"...
Oct 31 17:19:25 volumio volumio[1040]: info: Starting Udev Watcher for removable devices
Oct 31 17:19:25 volumio volumio[1040]: info: Ignoring mount for partition: boot
Oct 31 17:19:25 volumio volumio[1040]: info: Ignoring mount for partition: volumio
Oct 31 17:19:25 volumio volumio[1040]: info: Ignoring mount for partition: volumio_data
Oct 31 17:19:25 volumio volumio[1040]: info: Ignoring mount for partition: boot
Oct 31 17:19:25 volumio volumio[1040]: info: Ignoring mount for partition: volumio
Oct 31 17:19:25 volumio volumio[1040]: info: Ignoring mount for partition: volumio_data
Oct 31 17:19:25 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "volumio_command_line_client"...
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "upnp"...
Oct 31 17:19:25 volumio volumio[1040]: info: [1761923965893] Starting Upmpd Daemon
Oct 31 17:19:25 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "my_music"...
Oct 31 17:19:25 volumio firststart.sh[1041]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5).
Oct 31 17:19:25 volumio firststart.sh[1041]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty).
Oct 31 17:19:25 volumio volumio[1040]: info: Loading plugin "mpd"...
Oct 31 17:19:25 volumio systemd[1]: Reloading.
Oct 31 17:19:26 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.
Oct 31 17:19:26 volumio volumio[1040]: info: Loading plugin "upnp_browser"...
Oct 31 17:19:27 volumio volumio[1040]: info: Loading plugin "alarm-clock"...
Oct 31 17:19:27 volumio volumio[1040]: info: Loading plugin "airplay_emulation"...
Oct 31 17:19:27 volumio volumio[1040]: info: Starting Shairport Sync
Oct 31 17:19:27 volumio volumio[1040]: info: Loading plugin "last_100"...
Oct 31 17:19:27 volumio volumio[1040]: info: Loading plugin "webradio"...
Oct 31 17:19:27 volumio firststart.sh[1041]: Enabling SSH for first boot
Oct 31 17:19:27 volumio systemd[1]: Starting OpenBSD Secure Shell server...
Oct 31 17:19:27 volumio sshd[1630]: Server listening on 0.0.0.0 port 22.
Oct 31 17:19:27 volumio sshd[1630]: Server listening on :: port 22.
Oct 31 17:19:27 volumio systemd[1]: Started OpenBSD Secure Shell server.
Oct 31 17:19:27 volumio firststart.sh[1041]: Disabling firststart service
Oct 31 17:19:27 volumio volumio[1040]: info: Loading plugin "i2s_dacs"...
Oct 31 17:19:27 volumio volumio[1040]: info: I2S DAC not set, start Auto-detection
Oct 31 17:19:27 volumio firststart.sh[1041]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service.
Oct 31 17:19:27 volumio systemd[1]: Reloading.
Oct 31 17:19:27 volumio volumio[1040]: info: Loading plugin "volumiodiscovery"...
Oct 31 17:19:28 volumio volumio[1040]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 31 17:19:28 volumio volumio[1040]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 31 17:19:28 volumio node[1040]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 31 17:19:28 volumio volumio[1040]: *** WARNING *** For more information see
Oct 31 17:19:28 volumio volumio[1040]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 31 17:19:28 volumio volumio[1040]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 31 17:19:28 volumio volumio[1040]: *** WARNING *** For more information see
Oct 31 17:19:28 volumio node[1040]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 31 17:19:28 volumio node[1040]: *** WARNING *** For more information see
Oct 31 17:19:28 volumio node[1040]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 31 17:19:28 volumio node[1040]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 31 17:19:28 volumio node[1040]: *** WARNING *** For more information see
Oct 31 17:19:28 volumio volumio[1040]: info: Discovery: Started advertising with name: Volumio
Oct 31 17:19:28 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 31 17:19:28 volumio volumio[1040]: info: Loading plugin "outputs"...
Oct 31 17:19:28 volumio volumio[1040]: info: Loading plugin "albumart"...
Oct 31 17:19:28 volumio volumio[1040]: info: Plugin example_plugin is not enabled
Oct 31 17:19:28 volumio volumio[1040]: info: Loading plugin "inputs"...
Oct 31 17:19:28 volumio volumio[1040]: info: Loading plugin "updater_comm"...
Oct 31 17:19:28 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.
Oct 31 17:19:28 volumio volumio[1040]: Forking 1 albumart workers
Oct 31 17:19:28 volumio volumio[1040]: info: Plugin mpdemulation is not enabled
Oct 31 17:19:28 volumio volumio[1040]: info: Loading plugin "rest_api"...
Oct 31 17:19:28 volumio volumio[1040]: info: Loading plugin "websocket"...
Oct 31 17:19:29 volumio volumio[1040]: info: Starting Socket.io Server version 2.3.0
Oct 31 17:19:29 volumio volumio[1040]: info: Loading i18n strings for locale en
Oct 31 17:19:29 volumio firststart.sh[1041]: Finalizing
Oct 31 17:19:29 volumio volumio[1040]: Updating browse sources language
Oct 31 17:19:29 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:19:29 volumio volumio[1040]: Starting albumart workers
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::initPlayerControls
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:35 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 31 17:19:35 volumio volumio[1040]: Express server listening on port 3000
Oct 31 17:19:35 volumio volumio[1040]: [Metrics] WebUI: 18s 856.33ms
Oct 31 17:19:35 volumio volumio[1040]: info: Setting Device type: x86
Oct 31 17:19:35 volumio volumio[1040]: info: CoreStateMachine::resetVolumioState
Oct 31 17:19:35 volumio volumio[1040]: info: CoreStateMachine::getcurrentVolume
Oct 31 17:19:35 volumio volumio[1040]: info: CoreCommandRouter::volumioRetrievevolume
Oct 31 17:19:35 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:19:37 volumio volumio-remote-updater[728]: [2025-10-31 15:19:37] [connect] Successful connection
Oct 31 17:19:38 volumio volumio[1040]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Oct 31 17:19:38 volumio volumio[1040]: info: CoreStateMachine::getcurrentVolume
Oct 31 17:19:38 volumio volumio[1040]: info: CoreCommandRouter::volumioRetrievevolume
Oct 31 17:19:38 volumio volumio[1040]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Oct 31 17:19:38 volumio volumio[1040]: info: Completed loading Core Plugins
Oct 31 17:19:38 volumio volumio[1040]: info: Preparing to generate the ALSA configuration file
Oct 31 17:19:38 volumio volumio[1040]: info: Cannot read play queue from file
Oct 31 17:19:38 volumio volumio[1040]: info: Volumio Network Manager: Network status updated: 1
Oct 31 17:19:38 volumio volumio[1040]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Oct 31 17:19:38 volumio volumio[1040]: wlan0 Interface doesn't support scanning : Operation not supported
Oct 31 17:19:38 volumio volumio[1040]: info: Cannot use regular scanning, forcing with ap-force
Oct 31 17:19:40 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Oct 31 17:19:40 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:41 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:41 volumio volumio[1040]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Oct 31 17:19:43 volumio sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 31 17:19:43 volumio sudo[1713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 31 17:19:43 volumio sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:43 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:43 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:43 volumio sudo[1711]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:44 volumio systemd[1]: systemd-timedated.service: Succeeded.
Oct 31 17:19:44 volumio systemd[1]: firststart.service: Succeeded.
Oct 31 17:19:45 volumio volumio[1040]: info: VolumeController:: Volume=51 Mute =false
Oct 31 17:19:45 volumio volumio[1040]: info: CoreStateMachine::pushState
Oct 31 17:19:45 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState
Oct 31 17:19:45 volumio volumio[1040]: info: CoreStateMachine::updateTrackBlock
Oct 31 17:19:45 volumio volumio[1040]: info: CorePlayQueue::getTrackBlock
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::volumioRetrievevolume
Oct 31 17:19:45 volumio volumio[1040]: info: VolumeController:: Volume=51 Mute =false
Oct 31 17:19:45 volumio volumio[1040]: info: CoreStateMachine::pushState
Oct 31 17:19:45 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState
Oct 31 17:19:45 volumio volumio[1040]: info: CoreStateMachine::updateTrackBlock
Oct 31 17:19:45 volumio volumio[1040]: info: CorePlayQueue::getTrackBlock
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::volumioRetrievevolume
Oct 31 17:19:45 volumio volumio[1040]: info: Asound.conf file written
Oct 31 17:19:45 volumio sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Oct 31 17:19:45 volumio sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:45 volumio sudo[1732]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:45 volumio volumio[1040]: No state is present for card SB
Oct 31 17:19:45 volumio volumio[1040]: Found hardware: "HDA-Intel" "Realtek ALC270" "HDA:10ec0270,103c3577,00100100" "0x103c" "0x3577"
Oct 31 17:19:45 volumio volumio[1040]: Hardware is initialized using a generic method
Oct 31 17:19:45 volumio volumio[1040]: No state is present for card SB
Oct 31 17:19:45 volumio volumio[1040]: No state is present for card S3
Oct 31 17:19:45 volumio volumio[1040]: Found hardware: "USB-Audio" "USB Mixer" "USB041e:324d" "" ""
Oct 31 17:19:45 volumio volumio[1040]: Hardware is initialized using a generic method
Oct 31 17:19:45 volumio volumio[1040]: No state is present for card S3
Oct 31 17:19:45 volumio volumio[1040]: info: Output device has changed, restarting MPD
Oct 31 17:19:45 volumio volumio[1040]: info: Output device has changed, restarting Shairport Sync
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:19:45 volumio sudo[1748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 31 17:19:45 volumio sudo[1748]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:45 volumio sudo[1750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 31 17:19:45 volumio sudo[1750]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:45 volumio systemd[1]: Stopping Music Player Daemon...
Oct 31 17:19:45 volumio volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 31 17:19:45 volumio volumio[1040]: info: ___________ START PLUGINS ___________
Oct 31 17:19:45 volumio volumio[1040]: info: Setting Hotspot Unique name on first start: Volumio-9D01C
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam
Oct 31 17:19:45 volumio volumio[1040]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::Reload Ui
Oct 31 17:19:45 volumio volumio[1040]: info: ControllerMpd::onStart: Initializing MPD
Oct 31 17:19:45 volumio volumio[1040]: info: Creating MPD Configuration file
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 31 17:19:45 volumio volumio[1040]: info: [1761923985919] CoreMusicLibrary::Adding element Media Servers
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:19:48 volumio sudo[1748]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:48 volumio volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 31 17:19:48 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:48 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:19:48 volumio sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 31 17:19:48 volumio sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:48 volumio sudo[1759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 31 17:19:48 volumio sudo[1759]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:48 volumio sudo[1757]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:48 volumio volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 31 17:19:48 volumio volumio[1040]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 31 17:19:48 volumio volumio[1040]: info: [1761923988936] CoreMusicLibrary::Adding element Last_100
Oct 31 17:19:48 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:19:48 volumio volumio[1040]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 31 17:19:48 volumio volumio[1040]: info: [1761923988941] CoreMusicLibrary::Adding element Webradio
Oct 31 17:19:48 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:19:48 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 31 17:19:48 volumio volumio[1040]: info: Initializing BBC Radios
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:19:49 volumio volumio[1040]: info: Volumio Calling Home
Oct 31 17:19:49 volumio systemd[1]: mpd.service: Succeeded.
Oct 31 17:19:49 volumio systemd[1]: Stopped Music Player Daemon.
Oct 31 17:19:49 volumio systemd[1]: Starting Music Player Daemon...
Oct 31 17:19:49 volumio sudo[1782]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 31 17:19:49 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:49 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:49 volumio sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf
Oct 31 17:19:49 volumio sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:49 volumio volumio[1040]: info: MPD Permissions set
Oct 31 17:19:49 volumio volumio[1040]: info: MPD Permissions set
Oct 31 17:19:49 volumio volumio[1040]: info: VolumeController:: Volume=51 Mute =false
Oct 31 17:19:49 volumio volumio[1040]: info: CoreStateMachine::pushState
Oct 31 17:19:49 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState
Oct 31 17:19:49 volumio sudo[1788]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:49 volumio volumio[1040]: info: Discovery: adding 834465a3-7406-40ac-8c5b-366d83b51d16
Oct 31 17:19:49 volumio volumio[1040]: info: Discovery: Found device Volumio
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState
Oct 31 17:19:49 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:19:49 volumio volumio[1040]: info: Discovery: this is already registered, 834465a3-7406-40ac-8c5b-366d83b51d16
Oct 31 17:19:49 volumio volumio[1040]: info: Discovery: Found device Volumio
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState
Oct 31 17:19:49 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:19:49 volumio volumio[1040]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
Oct 31 17:19:49 volumio volumio[1040]: info: Permissions for /etc/hostapd/hostapd.conf
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Oct 31 17:19:49 volumio volumio[1040]: info: Discovery: Restarting Advertising due to device name change
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:49 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:50 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:19:50 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 31 17:19:51 volumio mpd[1785]: Oct 31 15:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 31 17:19:51 volumio systemd[1]: Started Music Player Daemon.
Oct 31 17:19:51 volumio sudo[1750]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:51 volumio sudo[1759]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:51 volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Oct 31 17:19:51 volumio sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Oct 31 17:19:51 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:51 volumio sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Oct 31 17:19:51 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:51 volumio systemd[1]: Starting Wireless Services...
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:19:51 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:19:51 volumio volumio[1040]: info: Upmpdcli Daemon Started
Oct 31 17:19:51 volumio volumio[1040]: info: VolumeController:: Volume=51 Mute =false
Oct 31 17:19:51 volumio volumio[1040]: info: CoreStateMachine::pushState
Oct 31 17:19:51 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:19:51 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState
Oct 31 17:19:51 volumio volumio[1040]: info: Completed starting Core Plugins
Oct 31 17:19:51 volumio volumio[1040]: info: -------------------------------------------
Oct 31 17:19:51 volumio volumio[1040]: info: ----- MyVolumio plugins startup ----
Oct 31 17:19:51 volumio volumio[1040]: info: -------------------------------------------
Oct 31 17:19:51 volumio volumio[1040]: info: [MyVolumio PluginManager] Fetching plans data....
Oct 31 17:19:52 volumio volumio[1040]: info: Volumio called home
Oct 31 17:19:52 volumio wireless.js[1807]: Cleaning previous...
Oct 31 17:19:52 volumio volumio-remote-updater[728]: [2025-10-31 15:19:52] [connect] Successful connection
Oct 31 17:19:52 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server...
Oct 31 17:19:52 volumio volumio[1040]: info: MPD running with PID1785
Oct 31 17:19:52 volumio volumio[1040]: ,establishing connection
Oct 31 17:19:52 volumio volumio[1040]: info: Starting Shairport Sync
Oct 31 17:19:52 volumio volumio[1040]: info: Starting Shairport Sync
Oct 31 17:19:52 volumio volumio[1040]: info: Starting Shairport Sync
Oct 31 17:19:52 volumio dnsmasq[1109]: exiting on receipt of SIGTERM
Oct 31 17:19:52 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 31 17:19:52 volumio systemd[1]: dnsmasq.service: Succeeded.
Oct 31 17:19:52 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:52 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server.
Oct 31 17:19:52 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
Oct 31 17:19:52 volumio dhcpcd[851]: wlan0: carrier lost
Oct 31 17:19:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 31 17:19:52 volumio systemd[1]: shairport-sync.service: Succeeded.
Oct 31 17:19:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 31 17:19:52 volumio sudo[1834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 31 17:19:52 volumio avahi-daemon[721]: Interface wlan0.IPv4 no longer relevant for mDNS.
Oct 31 17:19:52 volumio avahi-daemon[721]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:52 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 31 17:19:52 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:52 volumio avahi-daemon[721]: Withdrawing address record for 169.254.128.247 on wlan0.
Oct 31 17:19:52 volumio avahi-daemon[721]: Withdrawing address record for 192.168.211.1 on wlan0.
Oct 31 17:19:52 volumio avahi-daemon[721]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.128.247.
Oct 31 17:19:52 volumio avahi-daemon[721]: New relevant interface wlan0.IPv4 for mDNS.
Oct 31 17:19:52 volumio avahi-daemon[721]: Registering new address record for 169.254.128.247 on wlan0.IPv4.
Oct 31 17:19:52 volumio avahi-daemon[721]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
Oct 31 17:19:52 volumio systemd[1]: hostapd.service: Succeeded.
Oct 31 17:19:52 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
Oct 31 17:19:52 volumio sudo[1838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 31 17:19:52 volumio sudo[1838]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:52 volumio dhcpcd[851]: wlan0: deleting route to 169.254.0.0/16
Oct 31 17:19:52 volumio avahi-daemon[721]: Withdrawing address record for 169.254.128.247 on wlan0.
Oct 31 17:19:52 volumio avahi-daemon[721]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.128.247.
Oct 31 17:19:52 volumio avahi-daemon[721]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 31 17:19:52 volumio systemd[1]: shairport-sync.service: Succeeded.
Oct 31 17:19:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 31 17:19:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 31 17:19:52 volumio sudo[1838]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:52 volumio sudo[1834]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:52 volumio sudo[1868]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Oct 31 17:19:52 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:52 volumio avahi-daemon[721]: Withdrawing address record for 192.168.211.1 on wlan0.
Oct 31 17:19:52 volumio avahi-daemon[721]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:52 volumio avahi-daemon[721]: Interface wlan0.IPv4 no longer relevant for mDNS.
Oct 31 17:19:52 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:52 volumio sudo[1876]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Oct 31 17:19:52 volumio sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:52 volumio sudo[1876]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:53 volumio volumio[1040]: error: MPD error: The expression evaluated to a falsy value:
Oct 31 17:19:53 volumio volumio[1040]: assert.ok(self.idling)
Oct 31 17:19:53 volumio volumio[1040]: error: The expression evaluated to a falsy value:
Oct 31 17:19:53 volumio volumio[1040]: assert.ok(self.idling)
Oct 31 17:19:53 volumio volumio[1040]: error: MPD error: The expression evaluated to a falsy value:
Oct 31 17:19:53 volumio volumio[1040]: assert.ok(self.idling)
Oct 31 17:19:53 volumio volumio[1040]: error: The expression evaluated to a falsy value:
Oct 31 17:19:53 volumio volumio[1040]: assert.ok(self.idling)
Oct 31 17:19:53 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState
Oct 31 17:19:53 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:19:53 volumio wireless.js[1807]: Stopped aP
Oct 31 17:19:53 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Oct 31 17:19:53 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:53 volumio volumio-remote-updater[728]: [2025-10-31 15:19:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761923992 101
Oct 31 17:19:53 volumio volumio[1040]: 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
Oct 31 17:19:53 volumio volumio[1040]: info: Shairport-Sync Started
Oct 31 17:19:53 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:53 volumio volumio[1040]: Error adding Membership: Error: addMembership EINVAL
Oct 31 17:19:53 volumio volumio[1040]: info: Shairport-Sync Started
Oct 31 17:19:53 volumio volumio[1040]: error: updateQueue error: null
Oct 31 17:19:53 volumio sudo[1902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Oct 31 17:19:53 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:53 volumio volumio[1040]: info: Discovery: A device disappeared from network
Oct 31 17:19:53 volumio volumio[1040]: info: Discovery: Device volumio disappeared from network
Oct 31 17:19:53 volumio volumio[1040]: info: Discovery: A device disappeared from network
Oct 31 17:19:53 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:53 volumio sudo[1911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Oct 31 17:19:53 volumio sudo[1911]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:53 volumio sudo[1911]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:53 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Oct 31 17:19:53 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:53 volumio volumio[1806]: Generating RSA private key, 4096 bit long modulus (2 primes)
Oct 31 17:19:54 volumio ntpd[1261]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=43 secs
Oct 31 17:19:54 volumio ntpd[1261]: Deleting interface #5 wlan0, 169.254.128.247#123, interface stats: received=0, sent=0, dropped=0, active_time=35 secs
Oct 31 17:19:54 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:54 volumio sudo[1932]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Oct 31 17:19:54 volumio sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:54 volumio sudo[1932]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:54 volumio sudo[1934]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Oct 31 17:19:54 volumio sudo[1934]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:19:54 volumio sudo[1934]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:54 volumio avahi-daemon[721]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:54 volumio avahi-daemon[721]: New relevant interface wlan0.IPv4 for mDNS.
Oct 31 17:19:54 volumio avahi-daemon[721]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
Oct 31 17:19:54 volumio volumio[1040]: info: Discovery: Started advertising with name: Volumio
Oct 31 17:19:55 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
Oct 31 17:19:55 volumio hostapd[1941]: Configuration file: /etc/hostapd/hostapd.conf
Oct 31 17:19:55 volumio avahi-daemon[721]: Interface wlan0.IPv4 no longer relevant for mDNS.
Oct 31 17:19:55 volumio avahi-daemon[721]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:55 volumio avahi-daemon[721]: Withdrawing address record for 192.168.211.1 on wlan0.
Oct 31 17:19:55 volumio avahi-daemon[721]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Oct 31 17:19:55 volumio avahi-daemon[721]: New relevant interface wlan0.IPv4 for mDNS.
Oct 31 17:19:55 volumio avahi-daemon[721]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
Oct 31 17:19:55 volumio hostapd[1941]: Using interface wlan0 with hwaddr 94:39:e5:2d:0b:9e and ssid "Volumio-9D01C"
Oct 31 17:19:55 volumio dhcpcd[851]: wlan0: carrier acquired
Oct 31 17:19:55 volumio hostapd[1941]: wlan0: interface state UNINITIALIZED->ENABLED
Oct 31 17:19:55 volumio hostapd[1941]: wlan0: AP-ENABLED
Oct 31 17:19:55 volumio systemd[1]: hostapd.service: Can't open PID file /run/hostapd.pid (yet?) after start: No such file or directory
Oct 31 17:19:55 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
Oct 31 17:19:55 volumio dhcpcd[851]: wlan0: IAID e5:2d:0b:9e
Oct 31 17:19:55 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server...
Oct 31 17:19:55 volumio volumio[1806]: .........................................++++
Oct 31 17:19:55 volumio dnsmasq[1944]: dnsmasq: syntax check OK.
Oct 31 17:19:55 volumio iwconfig[1947]: Error for wireless request "Set Power Management" (8B2C) :
Oct 31 17:19:55 volumio iwconfig[1947]: SET failed on device wlan0 ; Invalid argument.
Oct 31 17:19:55 volumio systemd[1]: wireless.service: Succeeded.
Oct 31 17:19:55 volumio systemd[1]: Started Wireless Services.
Oct 31 17:19:55 volumio sudo[1794]: pam_unix(sudo:session): session closed for user root
Oct 31 17:19:55 volumio dnsmasq[1953]: started, version 2.80 cachesize 150
Oct 31 17:19:55 volumio dnsmasq[1953]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile
Oct 31 17:19:55 volumio dnsmasq-dhcp[1953]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d
Oct 31 17:19:55 volumio dnsmasq[1953]: reading /etc/resolv.conf
Oct 31 17:19:55 volumio dnsmasq[1953]: using nameserver 192.168.0.1#53
Oct 31 17:19:55 volumio dnsmasq[1953]: using nameserver 208.67.222.222#53
Oct 31 17:19:55 volumio dnsmasq[1953]: using nameserver 208.67.220.220#53
Oct 31 17:19:55 volumio dnsmasq[1953]: read /etc/hosts - 5 addresses
Oct 31 17:19:55 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server.
Oct 31 17:19:55 volumio dhcpcd[851]: wlan0: soliciting a DHCP lease
Oct 31 17:19:56 volumio dhcpcd[851]: wlan0: soliciting an IPv6 router
Oct 31 17:19:56 volumio ntpd[1261]: Listen normally on 6 wlan0 192.168.211.1:123
Oct 31 17:19:56 volumio ntpd[1261]: new interface(s) found: waking up resolver
Oct 31 17:19:56 volumio volumio[1806]: .................................................++++
Oct 31 17:19:56 volumio volumio[1806]: e is 65537 (0x010001)
Oct 31 17:19:57 volumio volumio[1806]: writing RSA key
Oct 31 17:19:57 volumio volumio[1040]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Oct 31 17:20:00 volumio volumio[1040]: info: Discovery: Browse raised the following error Error: dns service error: unknown
Oct 31 17:20:00 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection
Oct 31 17:20:00 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo
Oct 31 17:20:00 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 31 17:20:00 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:00 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Oct 31 17:20:00 volumio volumio[1040]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Oct 31 17:20:00 volumio volumio[1040]: info: Adding plugin bluetooth to MyMusic Plugins
Oct 31 17:20:00 volumio volumio[1040]: info: Adding plugin multiroom to MyMusic Plugins
Oct 31 17:20:00 volumio volumio[1040]: info: Adding plugin metavolumio to MyMusic Plugins
Oct 31 17:20:00 volumio dhcpcd[851]: wlan0: probing for an IPv4LL address
Oct 31 17:20:01 volumio volumio[1040]: info: Adding plugin cd_controller to MyMusic Plugins
Oct 31 17:20:01 volumio volumio[1040]: info: Adding plugin qobuzconnect to MyMusic Plugins
Oct 31 17:20:01 volumio volumio[1040]: info: Adding plugin smart_inputs to MyMusic Plugins
Oct 31 17:20:01 volumio volumio[1040]: info: Adding plugin tidalconnect to MyMusic Plugins
Oct 31 17:20:01 volumio volumio[1040]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Oct 31 17:20:04 volumio volumio[1040]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Oct 31 17:20:04 volumio volumio[1040]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Oct 31 17:20:04 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:20:04 volumio volumio[1040]: info: Starting MyVolumio Remote Streaming Endpoints
Oct 31 17:20:04 volumio volumio[1040]: info: MyVolumio not started
Oct 31 17:20:04 volumio volumio[1040]: info: Initializing device activation check
Oct 31 17:20:04 volumio volumio[1040]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Oct 31 17:20:04 volumio volumio[1040]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Oct 31 17:20:05 volumio dhcpcd[851]: wlan0: using IPv4LL address 169.254.128.247
Oct 31 17:20:05 volumio avahi-daemon[721]: Registering new address record for 169.254.128.247 on wlan0.IPv4.
Oct 31 17:20:05 volumio dhcpcd[851]: wlan0: adding route to 169.254.0.0/16
Oct 31 17:20:06 volumio dbus-daemon[705]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.18' (uid=0 pid=1691 comm="/usr/lib/chromium/chromium --show-component-extens")
Oct 31 17:20:06 volumio systemd[1]: Starting Bluetooth service...
Oct 31 17:20:07 volumio kernel: Bluetooth: Core ver 2.22
Oct 31 17:20:07 volumio kernel: NET: Registered PF_BLUETOOTH protocol family
Oct 31 17:20:07 volumio kernel: Bluetooth: HCI device and connection manager initialized
Oct 31 17:20:07 volumio kernel: Bluetooth: HCI socket layer initialized
Oct 31 17:20:07 volumio kernel: Bluetooth: L2CAP socket layer initialized
Oct 31 17:20:07 volumio kernel: Bluetooth: SCO socket layer initialized
Oct 31 17:20:07 volumio bluetoothd[2084]: Bluetooth daemon 5.50
Oct 31 17:20:07 volumio bluetoothd[2084]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Oct 31 17:20:07 volumio dbus-daemon[705]: [system] Successfully activated service 'org.bluez'
Oct 31 17:20:07 volumio systemd[1]: Started Bluetooth service.
Oct 31 17:20:07 volumio bluetoothd[2084]: Starting SDP server
Oct 31 17:20:07 volumio bluetoothd[2084]: Excluding (cli) sap
Oct 31 17:20:07 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Oct 31 17:20:07 volumio kernel: Bluetooth: BNEP filters: protocol multicast
Oct 31 17:20:07 volumio kernel: Bluetooth: BNEP socket layer initialized
Oct 31 17:20:07 volumio bluetoothd[2084]: Bluetooth management interface 1.22 initialized
Oct 31 17:20:07 volumio dbus-daemon[705]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.19' (uid=0 pid=2084 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Oct 31 17:20:07 volumio systemd[1]: Starting Hostname Service...
Oct 31 17:20:07 volumio ntpd[1261]: Listen normally on 7 wlan0 169.254.128.247:123
Oct 31 17:20:07 volumio ntpd[1261]: new interface(s) found: waking up resolver
Oct 31 17:20:07 volumio dbus-daemon[705]: [system] Successfully activated service 'org.freedesktop.hostname1'
Oct 31 17:20:07 volumio systemd[1]: Started Hostname Service.
Oct 31 17:20:10 volumio volumio[1040]: info: Starting Streaming Service Transparent Proxy
Oct 31 17:20:10 volumio volumio[1040]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Oct 31 17:20:10 volumio volumio[1040]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Oct 31 17:20:10 volumio volumio[1040]: info: Streaming services startup
Oct 31 17:20:10 volumio volumio[1040]: info: Starting Streaming Daemon
Oct 31 17:20:10 volumio sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Oct 31 17:20:10 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:10 volumio sudo[2225]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:10 volumio volumio[1040]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Oct 31 17:20:10 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart
Oct 31 17:20:10 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart
Oct 31 17:20:10 volumio volumio[1040]: info: Refreshing Cached IP Addresses
Oct 31 17:20:10 volumio sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Oct 31 17:20:10 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:10 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:10 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 31 17:20:10 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:10 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:10 volumio sudo[2237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 31 17:20:10 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:11 volumio sudo[2237]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:11 volumio volumio[1040]: error: Cannot start Volumio Streaming Daemon
Oct 31 17:20:11 volumio volumio[1040]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Oct 31 17:20:11 volumio volumio[1040]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Oct 31 17:20:11 volumio volumio[1040]: info: Discovery: Browse raised the following error Error: dns service error: unknown
Oct 31 17:20:11 volumio systemd[1]: upmpdcli.service: Succeeded.
Oct 31 17:20:12 volumio volumio[1040]: STREAMING PROXY: Starting server on port 3245
Oct 31 17:20:12 volumio volumio[1040]: Node JS runtime: 14
Oct 31 17:20:13 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 31 17:20:13 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:13 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:13 volumio sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 31 17:20:13 volumio sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:13 volumio sudo[2248]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:14 volumio volumio[1040]: verbose: New Socket.io Connection to 192.168.0.206 from 192.168.0.204 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Oct 31 17:20:14 volumio volumio[1040]: info: Received Get System Info
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 31 17:20:14 volumio volumio[1040]: info: Discovery: Getting this device information
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState
Oct 31 17:20:14 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 31 17:20:14 volumio volumio-remote-updater[728]: No test mode
Oct 31 17:20:14 volumio volumio-remote-updater[728]: No alpha test mode
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:14 volumio volumio[1040]: info: Retrieving Cloud Streaming UI
Oct 31 17:20:14 volumio volumio[1040]: info: Getting Tidal Cloud Configuration
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:14 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration
Oct 31 17:20:14 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:14 volumio volumio[1040]: info: Getting Spotify Cloud Configuration
Oct 31 17:20:14 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:14 volumio volumio[1040]: info: Saving Spotify Acccount
Oct 31 17:20:14 volumio volumio[1040]: info: Got Tidal Cloud Configuration
Oct 31 17:20:14 volumio volumio[1040]: info: Got it
Oct 31 17:20:14 volumio volumio[1040]: info: Got it
Oct 31 17:20:14 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:14 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 31 17:20:14 volumio volumio[1040]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Oct 31 17:20:14 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 31 17:20:14 volumio volumio[1040]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Oct 31 17:20:15 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Oct 31 17:20:15 volumio volumio[1040]: info: Loading i18n strings for locale en
Oct 31 17:20:15 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Oct 31 17:20:15 volumio volumio[1040]: info: Setting timezone to Europe/Athens
Oct 31 17:20:15 volumio sudo[2257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Oct 31 17:20:15 volumio sudo[2257]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:15 volumio sudo[2257]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:15 volumio sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Athens /etc/localtime
Oct 31 17:20:15 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:15 volumio sudo[2260]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:15 volumio sudo[2263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Oct 31 17:20:15 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:15 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:15 volumio sudo[2266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Athens
Oct 31 17:20:15 volumio sudo[2266]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:15 volumio dbus-daemon[705]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=2267 comm="/usr/bin/timedatectl set-timezone Europe/Athens ")
Oct 31 17:20:15 volumio systemd[1]: Starting Time & Date Service...
Oct 31 17:20:15 volumio dbus-daemon[705]: [system] Successfully activated service 'org.freedesktop.timedate1'
Oct 31 17:20:15 volumio systemd[1]: Started Time & Date Service.
Oct 31 17:20:15 volumio sudo[2266]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:15 volumio volumio[1040]: info: Fetching Streaming Services browse cache
Oct 31 17:20:15 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 31 17:20:15 volumio volumio[1040]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 31 17:20:15 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:20:15 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Oct 31 17:20:15 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Oct 31 17:20:16 volumio volumio[1040]: info: Received Get System Info
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 31 17:20:16 volumio volumio[1040]: info: Discovery: Getting this device information
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState
Oct 31 17:20:16 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 31 17:20:16 volumio volumio-remote-updater[728]: No test mode
Oct 31 17:20:16 volumio volumio-remote-updater[728]: No alpha test mode
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:16 volumio volumio[1040]: info: Retrieving Cloud Streaming UI
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 31 17:20:16 volumio volumio[1040]: Updating browse sources language
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:20:16 volumio volumio[1040]: info: Getting Tidal Cloud Configuration
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:16 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration
Oct 31 17:20:16 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:16 volumio volumio[1040]: info: Getting Spotify Cloud Configuration
Oct 31 17:20:16 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:16 volumio volumio[1040]: info: Saving Spotify Acccount
Oct 31 17:20:16 volumio volumio[1040]: info: Got Tidal Cloud Configuration
Oct 31 17:20:16 volumio volumio[1040]: info: Got it
Oct 31 17:20:16 volumio volumio[1040]: info: Got it
Oct 31 17:20:16 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 31 17:20:16 volumio volumio[1040]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 31 17:20:16 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:16 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 31 17:20:17 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Oct 31 17:20:17 volumio volumio[1040]: info: Loading i18n strings for locale en
Oct 31 17:20:18 volumio volumio[1040]: Updating browse sources language
Oct 31 17:20:18 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:20:18 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Oct 31 17:20:18 volumio volumio[1040]: info: Setting timezone to UTC
Oct 31 17:20:18 volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Oct 31 17:20:18 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:18 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:18 volumio sudo[2291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Oct 31 17:20:18 volumio sudo[2291]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:18 volumio sudo[2291]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:18 volumio sudo[2294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Oct 31 17:20:18 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:18 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:18 volumio sudo[2297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Oct 31 17:20:18 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:18 volumio systemd-timedated[2282]: Failed to set time zone: Permission denied
Oct 31 17:20:18 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:18 volumio volumio[1040]: Failed to set time zone: Access denied
Oct 31 17:20:18 volumio volumio[1040]: info: Could not set timezone, retrying
Oct 31 17:20:18 volumio volumio[1040]: info: Fetching Streaming Services browse cache
Oct 31 17:20:19 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 31 17:20:19 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:20:19 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 31 17:20:19 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 31 17:20:19 volumio volumio[1040]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Oct 31 17:20:19 volumio volumio-remote-updater[728]: No test mode
Oct 31 17:20:19 volumio volumio-remote-updater[728]: No alpha test mode
Oct 31 17:20:19 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Oct 31 17:20:19 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState
Oct 31 17:20:19 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:20:19 volumio volumio[1040]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Oct 31 17:20:19 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 31 17:20:19 volumio sudo[2384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Oct 31 17:20:19 volumio sudo[2384]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:19 volumio sudo[2384]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:21 volumio sudo[2477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 31 17:20:21 volumio sudo[2477]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:21 volumio sudo[2477]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:21 volumio sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 31 17:20:21 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:21 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:21 volumio sudo[2483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Oct 31 17:20:21 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:21 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Oct 31 17:20:21 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:21 volumio volumio[1040]: info: Upmpdcli Daemon Started
Oct 31 17:20:21 volumio volumio[2485]: writing RSA key
Oct 31 17:20:24 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:20:24 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:20:24 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Oct 31 17:20:24 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 31 17:20:24 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Oct 31 17:20:24 volumio volumio[1040]: info: CoreCommandRouter::volumioGetBrowseSources
Oct 31 17:20:24 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 31 17:20:30 volumio volumio[1040]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Oct 31 17:20:30 volumio volumio[1040]: info: Completed starting MyVolumio Plugin
Oct 31 17:20:30 volumio volumio[1040]: [Metrics] CommandRouter: 70s 250.65ms
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::volumiosetStartupVolume
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::Close All Modals sent
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::Close All Modals sent
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Retrieving Cloud Streaming UI
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Retrieving Cloud Streaming UI
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Retrieving Cloud Streaming UI
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Retrieving Cloud Streaming UI
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Tidal Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Spotify Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Saving Spotify Acccount
Oct 31 17:20:30 volumio volumio[1040]: info: Got Tidal Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Got it
Oct 31 17:20:30 volumio volumio[1040]: info: Got it
Oct 31 17:20:30 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Tidal Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Spotify Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Saving Spotify Acccount
Oct 31 17:20:30 volumio volumio[1040]: info: Got Tidal Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Got it
Oct 31 17:20:30 volumio volumio[1040]: info: Got it
Oct 31 17:20:30 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Tidal Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Spotify Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Saving Spotify Acccount
Oct 31 17:20:30 volumio volumio[1040]: info: Got Tidal Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Got it
Oct 31 17:20:30 volumio volumio[1040]: info: Got it
Oct 31 17:20:30 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Tidal Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Getting Spotify Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: info: Saving Spotify Acccount
Oct 31 17:20:30 volumio volumio[1040]: info: Got Tidal Cloud Configuration
Oct 31 17:20:30 volumio volumio[1040]: info: Got it
Oct 31 17:20:30 volumio volumio[1040]: info: Got it
Oct 31 17:20:30 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 31 17:20:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:30 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 31 17:20:31 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Oct 31 17:20:31 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 31 17:20:31 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Oct 31 17:20:31 volumio volumio[1040]: info: Version has changed, forcing UI Reload
Oct 31 17:20:31 volumio volumio[1040]: info: CoreCommandRouter::Reload Ui
Oct 31 17:20:32 volumio sudo[2538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 31 17:20:32 volumio sudo[2538]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:32 volumio sudo[2538]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:32 volumio sudo[2540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 31 17:20:32 volumio sudo[2540]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:32 volumio sudo[2540]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:32 volumio volumio[1040]: verbose: New Socket.io Connection to 192.168.0.206 from 192.168.0.204 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Oct 31 17:20:32 volumio volumio[1040]: info: Received Get System Info
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 31 17:20:32 volumio volumio[1040]: info: Discovery: Getting this device information
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState
Oct 31 17:20:32 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 31 17:20:32 volumio volumio-remote-updater[728]: No test mode
Oct 31 17:20:32 volumio volumio-remote-updater[728]: No alpha test mode
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:32 volumio volumio[1040]: info: Retrieving Cloud Streaming UI
Oct 31 17:20:32 volumio volumio[1040]: info: Getting Tidal Cloud Configuration
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:32 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration
Oct 31 17:20:32 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:32 volumio volumio[1040]: info: Getting Spotify Cloud Configuration
Oct 31 17:20:32 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:32 volumio volumio[1040]: info: Saving Spotify Acccount
Oct 31 17:20:32 volumio volumio[1040]: info: Got Tidal Cloud Configuration
Oct 31 17:20:32 volumio volumio[1040]: info: Got it
Oct 31 17:20:32 volumio volumio[1040]: info: Got it
Oct 31 17:20:32 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:32 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 31 17:20:32 volumio volumio[1040]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Oct 31 17:20:32 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 31 17:20:33 volumio volumio[1040]: 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
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Oct 31 17:20:33 volumio volumio[1040]: info: Loading i18n strings for locale en
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Oct 31 17:20:33 volumio volumio[1040]: info: Setting timezone to Europe/Athens
Oct 31 17:20:33 volumio sudo[2548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Oct 31 17:20:33 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:33 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:33 volumio sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Athens /etc/localtime
Oct 31 17:20:33 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:33 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:33 volumio sudo[2554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Oct 31 17:20:33 volumio sudo[2554]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:33 volumio sudo[2554]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:33 volumio sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Athens
Oct 31 17:20:33 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:33 volumio systemd-timedated[2282]: Failed to set time zone: Permission denied
Oct 31 17:20:33 volumio sudo[2557]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:33 volumio volumio[1040]: Failed to set time zone: Access denied
Oct 31 17:20:33 volumio volumio[1040]: info: Could not set timezone, retrying
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Oct 31 17:20:33 volumio volumio[1040]: info: Received Get System Info
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 31 17:20:33 volumio volumio[1040]: info: Discovery: Getting this device information
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState
Oct 31 17:20:33 volumio volumio[1040]: info: CorePlayQueue::getTrack 0
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 31 17:20:33 volumio volumio-remote-updater[728]: No test mode
Oct 31 17:20:33 volumio volumio-remote-updater[728]: No alpha test mode
Oct 31 17:20:33 volumio volumio[1040]: info: Fetching Streaming Services browse cache
Oct 31 17:20:33 volumio volumio[1040]: Updating browse sources language
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:20:33 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 31 17:20:34 volumio volumio[1040]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Oct 31 17:20:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 31 17:20:34 volumio sudo[2564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Athens
Oct 31 17:20:34 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:34 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:35 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 31 17:20:35 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 31 17:20:35 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:35 volumio volumio[1040]: info: Retrieving Cloud Streaming UI
Oct 31 17:20:35 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 31 17:20:35 volumio volumio[1040]: info: Getting Tidal Cloud Configuration
Oct 31 17:20:35 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:35 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration
Oct 31 17:20:35 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:35 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:35 volumio volumio[1040]: info: Getting Spotify Cloud Configuration
Oct 31 17:20:35 volumio volumio[1040]: info: Asking plugin for UI Config
Oct 31 17:20:35 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:35 volumio volumio[1040]: info: Saving Spotify Acccount
Oct 31 17:20:35 volumio volumio[1040]: info: Got Tidal Cloud Configuration
Oct 31 17:20:35 volumio volumio[1040]: info: Got it
Oct 31 17:20:35 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 31 17:20:35 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 31 17:20:35 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 31 17:20:36 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Oct 31 17:20:36 volumio volumio[1040]: info: Loading i18n strings for locale en
Oct 31 17:20:36 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Oct 31 17:20:36 volumio volumio[1040]: info: Setting timezone to UTC
Oct 31 17:20:36 volumio sudo[2581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Oct 31 17:20:36 volumio sudo[2581]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:36 volumio sudo[2581]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:36 volumio sudo[2584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Oct 31 17:20:36 volumio sudo[2584]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:36 volumio sudo[2584]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:36 volumio sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Oct 31 17:20:36 volumio sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:36 volumio sudo[2587]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:36 volumio sudo[2590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Oct 31 17:20:36 volumio sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:36 volumio systemd-timedated[2282]: Failed to set time zone: Permission denied
Oct 31 17:20:36 volumio sudo[2590]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:36 volumio volumio[1040]: Failed to set time zone: Access denied
Oct 31 17:20:36 volumio volumio[1040]: info: Could not set timezone, retrying
Oct 31 17:20:36 volumio volumio[1040]: Updating browse sources language
Oct 31 17:20:36 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 31 17:20:36 volumio volumio[1040]: info: Fetching Streaming Services browse cache
Oct 31 17:20:37 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Oct 31 17:20:39 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Oct 31 17:20:39 volumio volumio[1040]: info: Loading i18n strings for locale en
Oct 31 17:20:39 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Oct 31 17:20:39 volumio volumio[1040]: info: Setting timezone to Europe/Athens
Oct 31 17:20:39 volumio sudo[2595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Oct 31 17:20:39 volumio sudo[2595]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:39 volumio sudo[2595]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:39 volumio sudo[2598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Athens /etc/localtime
Oct 31 17:20:39 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:39 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:39 volumio sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Oct 31 17:20:39 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:39 volumio sudo[2601]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:39 volumio sudo[2604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Athens
Oct 31 17:20:39 volumio sudo[2604]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:39 volumio systemd-timedated[2282]: Failed to set time zone: Permission denied
Oct 31 17:20:39 volumio sudo[2604]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:39 volumio volumio[1040]: Failed to set time zone: Access denied
Oct 31 17:20:39 volumio volumio[1040]: info: Could not set timezone, retrying
Oct 31 17:20:39 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Oct 31 17:20:39 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Oct 31 17:20:39 volumio sudo[2607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Oct 31 17:20:39 volumio sudo[2607]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:39 volumio sudo[2607]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:39 volumio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Oct 31 17:20:39 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 31 17:20:39 volumio systemd-timedated[2282]: Failed to set time zone: Permission denied
Oct 31 17:20:39 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root
Oct 31 17:20:39 volumio volumio[1040]: Failed to set time zone: Access denied
Oct 31 17:20:39 volumio volumio[1040]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 31 17:20:39 volumio volumio[1040]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC'
Oct 31 17:20:39 volumio volumio[1040]: Failed to set time zone: Access denied
Oct 31 17:20:39 volumio volumio[1040]: at checkExecSyncError (child_process.js:790:11)
Oct 31 17:20:39 volumio volumio[1040]: at execSync (child_process.js:863:15)
Oct 31 17:20:39 volumio volumio[1040]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9)
Oct 31 17:20:39 volumio volumio[1040]: at listOnTimeout (internal/timers.js:557:17)
Oct 31 17:20:39 volumio volumio[1040]: at processTimers (internal/timers.js:500:7) {
Oct 31 17:20:39 volumio volumio[1040]: status: 1,
Oct 31 17:20:39 volumio volumio[1040]: signal: null,
Oct 31 17:20:39 volumio volumio[1040]: output: [ null, '', 'Failed to set time zone: Access denied\n' ],
Oct 31 17:20:39 volumio volumio[1040]: pid: 2608,
Oct 31 17:20:39 volumio volumio[1040]: stdout: '',
Oct 31 17:20:39 volumio volumio[1040]: stderr: 'Failed to set time zone: Access denied\n'
Oct 31 17:20:39 volumio volumio[1040]: }
Oct 31 17:20:39 volumio volumio[1040]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 31 17:20:40 volumio sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-31 17:19
Oct 31 17:20:40 volumio sudo[2621]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST"
VOLUMIO_VERSION="3.832"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"