-- Logs begin at Wed 2025-09-03 12:27:53 UTC, end at Wed 2025-09-03 12:29:00 UTC. --
Sep 03 12:28:00 volumio systemd[1]: Started Raise network interfaces.
Sep 03 12:28:00 volumio systemd[1]: Reached target Network.
Sep 03 12:28:00 volumio kernel: Generic FE-GE Realtek PHY r8169-0-100:00: attached PHY driver (mii_bus:phy_addr=r8169-0-100:00, irq=MAC)
Sep 03 12:28:00 volumio systemd[1]: Starting Music Player Daemon...
Sep 03 12:28:00 volumio systemd[1]: Reached target Network is Online.
Sep 03 12:28:00 volumio systemd[1]: Starting Samba NMB Daemon...
Sep 03 12:28:00 volumio systemd[1]: Starting LSB: Brings up/down network automatically...
Sep 03 12:28:00 volumio systemd[1]: Starting /etc/rc.local Compatibility...
Sep 03 12:28:00 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Sep 03 12:28:00 volumio systemd[1]: Starting Permit User Sessions...
Sep 03 12:28:00 volumio systemd[1]: Starting Network Time Service...
Sep 03 12:28:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Sep 03 12:28:00 volumio systemd[1]: Started /etc/rc.local Compatibility.
Sep 03 12:28:00 volumio systemd[1]: Started Permit User Sessions.
Sep 03 12:28:00 volumio systemd[1]: iptables.service: Succeeded.
Sep 03 12:28:00 volumio systemd[1]: Starting Hold until boot process finishes up...
Sep 03 12:28:00 volumio systemd[1]: Starting Terminate Plymouth Boot Screen...
Sep 03 12:28:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 390 (plymouthd).
Sep 03 12:28:00 volumio loadcpufreq[802]: Loading cpufreq kernel modules...done (acpi-cpufreq).
Sep 03 12:28:00 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Sep 03 12:28:00 volumio dhcpcd[921]: eth0: waiting for carrier
Sep 03 12:28:00 volumio kernel: r8169 0000:01:00.0 eth0: Link is Down
Sep 03 12:28:00 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Sep 03 12:28:00 volumio dhcpcd[921]: eth0: carrier acquired
Sep 03 12:28:00 volumio systemd[1]: plymouth-quit-wait.service: Succeeded.
Sep 03 12:28:00 volumio dhcpcd[921]: DUID 00:04:4c:4c:45:44:00:33:47:10:80:30:b6:c0:4f:4e:48:33
Sep 03 12:28:00 volumio systemd[1]: Started Hold until boot process finishes up.
Sep 03 12:28:00 volumio dhcpcd[921]: eth0: IAID 08:b3:8b:ec
Sep 03 12:28:00 volumio dhcpcd[921]: eth0: adding address fe80::a1af:c935:cac9:7376
Sep 03 12:28:00 volumio dhcpcd[921]: ipv6_addaddr1: Permission denied
Sep 03 12:28:00 volumio dhcpcd[921]: control command: /sbin/dhcpcd eth0
Sep 03 12:28:00 volumio dhcpcd[921]: eth0: carrier lost
Sep 03 12:28:00 volumio systemd[1]: plymouth-quit.service: Succeeded.
Sep 03 12:28:00 volumio systemd[1]: Started Terminate Plymouth Boot Screen.
Sep 03 12:28:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 390 (plymouthd).
Sep 03 12:28:00 volumio systemd[1]: Started Getty on tty1.
Sep 03 12:28:00 volumio systemd[1]: Reached target Login Prompts.
Sep 03 12:28:00 volumio ifplugd(eth0)[1007]: ifplugd 0.28 initializing.
Sep 03 12:28:00 volumio ifplugd(eth0)[1007]: Using interface eth0/F4:EE:08:B3:8B:EC with driver  (version: 6.6.32-volumio)
Sep 03 12:28:00 volumio ifplugd(eth0)[1007]: Using detection mode: SIOCETHTOOL
Sep 03 12:28:00 volumio ifplugd(eth0)[1007]: Initialization complete, link beat not detected.
Sep 03 12:28:00 volumio cpufrequtils[987]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done.
Sep 03 12:28:00 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters.
Sep 03 12:28:00 volumio ntpd[953]: ntpd 4.2.8p12@1.3728-o (1): Starting
Sep 03 12:28:00 volumio ntpd[953]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Sep 03 12:28:00 volumio systemd[1]: Started Network Time Service.
Sep 03 12:28:00 volumio ntpd[1015]: proto: precision = 0.118 usec (-23)
Sep 03 12:28:00 volumio ifplugd[948]: Network Interface Plugging Daemon...start eth0...done.
Sep 03 12:28:00 volumio systemd[1]: Started LSB: Brings up/down network automatically.
Sep 03 12:28:00 volumio ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Sep 03 12:28:00 volumio ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Sep 03 12:28:00 volumio ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 981 days ago
Sep 03 12:28:00 volumio ntpd[1015]: Listen and drop on 0 v6wildcard [::]:123
Sep 03 12:28:00 volumio ntpd[1015]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Sep 03 12:28:00 volumio ntpd[1015]: Listen normally on 2 lo 127.0.0.1:123
Sep 03 12:28:00 volumio ntpd[1015]: Listening on routing socket on fd #19 for interface updates
Sep 03 12:28:00 volumio ntpd[1015]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Sep 03 12:28:00 volumio ntpd[1015]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Sep 03 12:28:00 volumio sudo[941]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 03 12:28:00 volumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:00 volumio sudo[941]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Sep 03 12:28:00 volumio sudo[941]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:01 volumio nmbd[959]: [2025/09/03 12:28:01.252701,  0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
Sep 03 12:28:01 volumio nmbd[959]:   started asyncdns process 1026
Sep 03 12:28:01 volumio nmbd[959]: [2025/09/03 12:28:01.254066,  0] ../lib/util/become_daemon.c:149(daemon_status)
Sep 03 12:28:01 volumio nmbd[959]:   daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
Sep 03 12:28:01 volumio nmbd[959]: [2025/09/03 12:28:01.254169,  0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets)
Sep 03 12:28:01 volumio nmbd[959]:   NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
Sep 03 12:28:01 volumio wireless.js[798]: WIRELESS: No wireless interface, exiting
Sep 03 12:28:01 volumio iw[1031]: command failed: No such device (-19)
Sep 03 12:28:01 volumio iwconfig[1034]: Error for wireless request "Set Power Management" (8B2C) :
Sep 03 12:28:01 volumio iwconfig[1034]:     SET failed on device wlan0 ; No such device.
Sep 03 12:28:01 volumio systemd[1]: wireless.service: Succeeded.
Sep 03 12:28:01 volumio systemd[1]: Started Wireless Services.
Sep 03 12:28:01 volumio systemd[1]: Started Volumio Backend Module.
Sep 03 12:28:01 volumio systemd[1]: Started First Start.
Sep 03 12:28:01 volumio systemd[1]: Started Intel SST and HDA soundcard init service.
Sep 03 12:28:01 volumio systemd[1]: Started Start Volumio Kiosk.
Sep 03 12:28:01 volumio systemd[1]: Started Volumio Cpu Tweaker.
Sep 03 12:28:01 volumio firststart.sh[1038]: Volumio first start configuration script
Sep 03 12:28:01 volumio firststart.sh[1038]: Configuring unconfigured packages
Sep 03 12:28:01 volumio volumio-cpu-tweak[1046]: Setting RT Priority for mpd
Sep 03 12:28:01 volumio volumio-cpu-tweak[1046]: Setting MPD Affinity
Sep 03 12:28:01 volumio volumio-cpu-tweak[1046]: pid 1024's current affinity mask: f
Sep 03 12:28:01 volumio volumio-cpu-tweak[1046]: pid 1024's new affinity mask: 3
Sep 03 12:28:01 volumio volumio-cpu-tweak[1046]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Sep 03 12:28:01 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded.
Sep 03 12:28:01 volumio startx[1040]: xauth:  file /root/.Xauthority does not exist
Sep 03 12:28:01 volumio ntpd[1015]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
Sep 03 12:28:02 volumio ntpd[1015]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
Sep 03 12:28:03 volumio dhcpcd[921]: eth0: carrier acquired
Sep 03 12:28:03 volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx
Sep 03 12:28:03 volumio dhcpcd[921]: eth0: IAID 08:b3:8b:ec
Sep 03 12:28:03 volumio ntpd[1015]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Sep 03 12:28:03 volumio ifplugd(eth0)[1007]: Link beat detected.
Sep 03 12:28:03 volumio ifplugd(eth0)[1007]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Sep 03 12:28:03 volumio dhcpcd[921]: eth0: soliciting a DHCP lease
Sep 03 12:28:03 volumio dhcpcd[921]: eth0: offered 192.168.2.109 from 192.168.2.1
Sep 03 12:28:03 volumio dhcpcd[921]: eth0: probing address 192.168.2.109/24
Sep 03 12:28:04 volumio dhcpcd[921]: eth0: soliciting an IPv6 router
Sep 03 12:28:04 volumio ntpd[1015]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Sep 03 12:28:04 volumio volumio-remote-updater[797]: [2025-09-03 12:28:04] [info] asio async_connect error: system:111 (Connection refused)
Sep 03 12:28:04 volumio volumio-remote-updater[797]: [2025-09-03 12:28:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Sep 03 12:28:04 volumio volumio-remote-updater[797]: [2025-09-03 12:28:04] [error] handle_connect error: Underlying Transport Error
Sep 03 12:28:04 volumio volumio-time-update[801]: volumio-time-update-util: Fetching time from Volumio...
Sep 03 12:28:04 volumio volumio-time-update[801]: volumio-time-update-util: Date not found in response
Sep 03 12:28:04 volumio volumio-time-update[801]: volumio-time-update-util: Retrying in 5 seconds...
Sep 03 12:28:05 volumio firststart.sh[1038]: Creating /var/log/samba/cores folder
Sep 03 12:28:05 volumio firststart.sh[1038]: Removing default SSH host keys
Sep 03 12:28:05 volumio ifplugd(eth0)[1007]: client: ifup: interface eth0 already configured
Sep 03 12:28:05 volumio firststart.sh[1038]: removed '/etc/ssh/ssh_host_ecdsa_key'
Sep 03 12:28:05 volumio firststart.sh[1038]: removed '/etc/ssh/ssh_host_ecdsa_key.pub'
Sep 03 12:28:05 volumio firststart.sh[1038]: removed '/etc/ssh/ssh_host_ed25519_key'
Sep 03 12:28:05 volumio firststart.sh[1038]: removed '/etc/ssh/ssh_host_ed25519_key.pub'
Sep 03 12:28:05 volumio firststart.sh[1038]: removed '/etc/ssh/ssh_host_rsa_key'
Sep 03 12:28:05 volumio firststart.sh[1038]: removed '/etc/ssh/ssh_host_rsa_key.pub'
Sep 03 12:28:05 volumio firststart.sh[1038]: Generating SSH host keys
Sep 03 12:28:05 volumio ifplugd(eth0)[1007]: Program executed successfully.
Sep 03 12:28:05 volumio startx[1040]: X.Org X Server 1.20.4
Sep 03 12:28:05 volumio startx[1040]: X Protocol Version 11, Revision 0
Sep 03 12:28:05 volumio startx[1040]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian
Sep 03 12:28:05 volumio startx[1040]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64
Sep 03 12:28:05 volumio startx[1040]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=1f9d2b05-dd9a-4ae0-9071-3f477194c4bf bootpart=UUID=A474-C705 datapart=UUID=33bd9221-1888-4308-a302-01479145b622 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
Sep 03 12:28:05 volumio startx[1040]: Build Date: 18 December 2021  09:05:36AM
Sep 03 12:28:05 volumio startx[1040]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support)
Sep 03 12:28:05 volumio startx[1040]: Current version of pixman: 0.36.0
Sep 03 12:28:05 volumio startx[1040]:         Before reporting problems, check http://wiki.x.org
Sep 03 12:28:05 volumio startx[1040]:         to make sure that you have the latest version.
Sep 03 12:28:05 volumio startx[1040]: Markers: (--) probed, (**) from config file, (==) default setting,
Sep 03 12:28:05 volumio startx[1040]:         (++) from command line, (!!) notice, (II) informational,
Sep 03 12:28:05 volumio startx[1040]:         (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Sep 03 12:28:05 volumio startx[1040]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Sep  3 12:28:05 2025
Sep 03 12:28:05 volumio startx[1040]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Sep 03 12:28:06 volumio systemd[1]: soundcard-init.service: Succeeded.
Sep 03 12:28:06 volumio kernel: broken atomic modeset userspace detected, disabling atomic
Sep 03 12:28:07 volumio firststart.sh[1038]: debconf: unable to initialize frontend: Dialog
Sep 03 12:28:07 volumio firststart.sh[1038]: debconf: (TERM is not set, so the dialog frontend is not usable.)
Sep 03 12:28:07 volumio firststart.sh[1038]: debconf: falling back to frontend: Readline
Sep 03 12:28:07 volumio firststart.sh[1038]: debconf: unable to initialize frontend: Readline
Sep 03 12:28:07 volumio firststart.sh[1038]: debconf: (This frontend requires a controlling tty.)
Sep 03 12:28:07 volumio firststart.sh[1038]: debconf: falling back to frontend: Teletype
Sep 03 12:28:07 volumio mpd[1024]: Sep 03 12:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Sep 03 12:28:07 volumio mpd[1024]: Sep 03 12:28 : exception: Database corrupted
Sep 03 12:28:07 volumio systemd[1]: Started Music Player Daemon.
Sep 03 12:28:07 volumio mpd_monitor.sh[785]: MPD Monitor Service: MPD restarted due to no mpc output.
Sep 03 12:28:08 volumio startx[1040]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc.
Sep 03 12:28:08 volumio volumio[1037]: info: -------------------------------------------
Sep 03 12:28:08 volumio volumio[1037]: info: -----            Volumio3              ----
Sep 03 12:28:08 volumio volumio[1037]: info: -------------------------------------------
Sep 03 12:28:08 volumio volumio[1037]: info: -----          System startup          ----
Sep 03 12:28:08 volumio volumio[1037]: info: -------------------------------------------
Sep 03 12:28:08 volumio dhcpcd[921]: eth0: leased 192.168.2.109 for 86400 seconds
Sep 03 12:28:08 volumio avahi-daemon[784]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.109.
Sep 03 12:28:08 volumio avahi-daemon[784]: New relevant interface eth0.IPv4 for mDNS.
Sep 03 12:28:08 volumio avahi-daemon[784]: Registering new address record for 192.168.2.109 on eth0.IPv4.
Sep 03 12:28:08 volumio dhcpcd[921]: eth0: adding route to 192.168.2.0/24
Sep 03 12:28:08 volumio dhcpcd[921]: eth0: adding default route via 192.168.2.1
Sep 03 12:28:09 volumio firststart.sh[1038]: Creating SSH2 RSA key; this may take some time ...
Sep 03 12:28:09 volumio firststart.sh[1038]: 2048 SHA256:387qPA1VMYtxywU0K7gElRQet22fr0l/UauPLDsC1aE root@volumio (RSA)
Sep 03 12:28:09 volumio systemd[1]: Stopping Network Time Service...
Sep 03 12:28:09 volumio ntpd[1015]: ntpd exiting on signal 15 (Terminated)
Sep 03 12:28:09 volumio systemd[1]: ntp.service: Succeeded.
Sep 03 12:28:09 volumio systemd[1]: Stopped Network Time Service.
Sep 03 12:28:09 volumio firststart.sh[1038]: Creating SSH2 DSA key; this may take some time ...
Sep 03 12:28:09 volumio systemd[1]: Starting Network Time Service...
Sep 03 12:28:09 volumio firststart.sh[1038]: 1024 SHA256:9AHYNKnEc2tyG/kk7fljiZvWJnZ/ft4Gfu0uOr+/ON4 root@volumio (DSA)
Sep 03 12:28:09 volumio firststart.sh[1038]: Creating SSH2 ECDSA key; this may take some time ...
Sep 03 12:28:09 volumio ntpd[1363]: ntpd 4.2.8p12@1.3728-o (1): Starting
Sep 03 12:28:09 volumio ntpd[1363]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Sep 03 12:28:09 volumio systemd[1]: Started Network Time Service.
Sep 03 12:28:09 volumio firststart.sh[1038]: 256 SHA256:IL941WNQ8LzNlRXHNgNWteCXkUokuMReAmXaeU8G/zk root@volumio (ECDSA)
Sep 03 12:28:09 volumio ntpd[1376]: proto: precision = 0.119 usec (-23)
Sep 03 12:28:09 volumio ntpd[1376]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Sep 03 12:28:09 volumio ntpd[1376]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Sep 03 12:28:09 volumio ntpd[1376]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 981 days ago
Sep 03 12:28:09 volumio ntpd[1376]: Listen and drop on 0 v6wildcard [::]:123
Sep 03 12:28:09 volumio ntpd[1376]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Sep 03 12:28:09 volumio ntpd[1376]: Listen normally on 2 lo 127.0.0.1:123
Sep 03 12:28:09 volumio ntpd[1376]: Listen normally on 3 eth0 192.168.2.109:123
Sep 03 12:28:09 volumio ntpd[1376]: Listening on routing socket on fd #20 for interface updates
Sep 03 12:28:09 volumio ntpd[1376]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Sep 03 12:28:09 volumio ntpd[1376]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Sep 03 12:28:09 volumio volumio[1037]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio
Sep 03 12:28:09 volumio volumio[1037]: info: MYVOLUMIO Environment detected
Sep 03 12:28:09 volumio volumio[1037]: info: Plugin folders cleanup
Sep 03 12:28:09 volumio volumio[1037]: info: Scanning into folder /volumio/app/plugins/
Sep 03 12:28:09 volumio volumio[1037]: info: Scanning category audio_interface
Sep 03 12:28:09 volumio volumio[1037]: info: Scanning category miscellanea
Sep 03 12:28:09 volumio volumio[1037]: info: Scanning category music_service
Sep 03 12:28:09 volumio volumio[1037]: info: Scanning category plugins.json
Sep 03 12:28:09 volumio volumio[1037]: info: Scanning category system_controller
Sep 03 12:28:09 volumio volumio[1037]: info: Scanning category user_interface
Sep 03 12:28:09 volumio volumio[1037]: info: Scanning into folder /data/plugins/
Sep 03 12:28:09 volumio volumio[1037]: info: Plugin folders cleanup completed
Sep 03 12:28:09 volumio volumio[1037]: info: -------------------------------------------
Sep 03 12:28:09 volumio volumio[1037]: info: -----      Core plugins startup        ----
Sep 03 12:28:09 volumio volumio[1037]: info: -------------------------------------------
Sep 03 12:28:09 volumio volumio[1037]: info: Loading plugins from folder /volumio/app/plugins/
Sep 03 12:28:09 volumio volumio[1037]: info: Adding plugin upnp to MyMusic Plugins
Sep 03 12:28:09 volumio volumio[1037]: info: Adding plugin airplay_emulation to MyMusic Plugins
Sep 03 12:28:09 volumio volumio[1037]: info: Adding plugin upnp_browser to MyMusic Plugins
Sep 03 12:28:09 volumio volumio[1037]: info: Loading plugins from folder /data/plugins/
Sep 03 12:28:09 volumio volumio[1037]: info: Loading plugin "system"...
Sep 03 12:28:09 volumio volumio[1037]: info: No id defined. Creating one
Sep 03 12:28:09 volumio volumio[1037]: info: Loading plugin "appearance"...
Sep 03 12:28:09 volumio volumio-time-update[801]: volumio-time-update-util: Fetching time from Volumio...
Sep 03 12:28:10 volumio ntpd[1376]: Soliciting pool server 139.199.215.251
Sep 03 12:28:10 volumio systemd[1]: Reloading.
Sep 03 12:28:10 volumio volumio-time-update[801]: volumio-time-update-util: Setting system time to: 2025-09-03 12:28:10
Sep 03 12:28:10 volumio sudo[1431]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-03 12:28:10
Sep 03 12:28:10 volumio sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:10 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.
Sep 03 12:28:10 volumio dbus-daemon[792]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=1432 comm="timedatectl set-time 2025-09-03 12:28:10 ")
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "network"...
Sep 03 12:28:10 volumio volumio[1037]: info: Refreshing Cached IP Addresses
Sep 03 12:28:10 volumio sudo[1434]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 03 12:28:10 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:10 volumio sudo[1436]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 03 12:28:10 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:10 volumio sudo[1434]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "services"...
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "alsa_controller"...
Sep 03 12:28:10 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:10 volumio sudo[1446]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Sep 03 12:28:10 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:10 volumio sudo[1446]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:10 volumio volumio[1037]: warn: The ALSA output card is not set, defaulting to card [object Object]
Sep 03 12:28:10 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Sep 03 12:28:10 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Sep 03 12:28:10 volumio volumio[1037]: info: Device 5 does not have any Mixer Control Available
Sep 03 12:28:10 volumio volumio[1037]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Sep 03 12:28:10 volumio volumio[1037]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Sep 03 12:28:10 volumio volumio[1037]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Sep 03 12:28:10 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "wizard"...
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "networkfs"...
Sep 03 12:28:10 volumio systemd[1]: Starting Time & Date Service...
Sep 03 12:28:10 volumio volumio[1037]: info: Starting Udev Watcher for removable devices
Sep 03 12:28:10 volumio firststart.sh[1038]: rescue-ssh.target is a disabled or a static unit, not starting it.
Sep 03 12:28:10 volumio volumio[1037]: info: Ignoring mount for partition: AED6-2BC4
Sep 03 12:28:10 volumio volumio[1037]: error: Cannot associate FS Label, not mounting
Sep 03 12:28:10 volumio volumio[1037]: info: Ignoring mount for partition: 7da47df0-bf46-48bb-a9df-2c167f9cb30c
Sep 03 12:28:10 volumio dbus-daemon[792]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 03 12:28:10 volumio systemd[1]: Started Time & Date Service.
Sep 03 12:28:10 volumio systemd-timedated[1476]: Changed local time to Wed Sep  3 12:28:10 2025
Sep 03 12:28:10 volumio sudo[1431]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:10 volumio volumio-time-update[801]: volumio-time-update-util: System time updated successfully.
Sep 03 12:28:10 volumio systemd[1]: Started Volumio Time Update Utility.
Sep 03 12:28:10 volumio volumio[1037]: info: Ignoring mount for partition: 2d0d3961-1600-428e-98c9-6af375c6f6ec
Sep 03 12:28:10 volumio volumio[1037]: info: Ignoring mount for partition: 4778705d-958d-42a2-b0ba-e22aeadb9641
Sep 03 12:28:10 volumio volumio[1037]: info: Ignoring mount for partition: 514357be-09e3-4d49-976f-403d2c2c05a6
Sep 03 12:28:10 volumio volumio[1037]: info: Ignoring mount for partition: boot
Sep 03 12:28:10 volumio volumio[1037]: info: Ignoring mount for partition: volumio
Sep 03 12:28:10 volumio volumio[1037]: info: Ignoring mount for partition: volumio_data
Sep 03 12:28:10 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "volumio_command_line_client"...
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "upnp"...
Sep 03 12:28:10 volumio volumio[1037]: info: [1756902490066] Starting Upmpd Daemon
Sep 03 12:28:10 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "my_music"...
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "mpd"...
Sep 03 12:28:10 volumio firststart.sh[1038]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5).
Sep 03 12:28:10 volumio firststart.sh[1038]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty).
Sep 03 12:28:10 volumio systemd[1]: Reloading.
Sep 03 12:28:10 volumio nmbd[959]: [2025/09/03 12:28:10.327600,  0] ../lib/util/become_daemon.c:138(daemon_ready)
Sep 03 12:28:10 volumio nmbd[959]:   daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Sep 03 12:28:10 volumio ntpd[1376]: Soliciting pool server 193.182.111.142
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "upnp_browser"...
Sep 03 12:28:10 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.
Sep 03 12:28:10 volumio ntpd[1376]: Soliciting pool server 202.118.1.81
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "alarm-clock"...
Sep 03 12:28:10 volumio systemd[1]: Started Samba NMB Daemon.
Sep 03 12:28:10 volumio systemd[1]: Starting Samba Winbind Daemon...
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "airplay_emulation"...
Sep 03 12:28:10 volumio volumio[1037]: info: Starting Shairport Sync
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "last_100"...
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "webradio"...
Sep 03 12:28:10 volumio winbindd[1524]: [2025/09/03 12:28:10.913137,  0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Sep 03 12:28:10 volumio winbindd[1524]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "i2s_dacs"...
Sep 03 12:28:10 volumio volumio[1037]: info: I2S DAC not set, start Auto-detection
Sep 03 12:28:10 volumio volumio[1037]: info: Loading plugin "volumiodiscovery"...
Sep 03 12:28:11 volumio ntpd[1376]: Soliciting pool server 162.159.200.123
Sep 03 12:28:11 volumio ntpd[1376]: Soliciting pool server 116.203.151.74
Sep 03 12:28:12 volumio volumio-remote-updater[797]: [2025-09-03 12:28:12] [connect] Successful connection
Sep 03 12:28:12 volumio ntpd[1376]: Soliciting pool server 5.79.108.34
Sep 03 12:28:12 volumio ntpd[1376]: Soliciting pool server 185.209.85.222
Sep 03 12:28:13 volumio firststart.sh[1038]: Enabling SSH for first boot
Sep 03 12:28:13 volumio ntpd[1376]: Soliciting pool server 193.182.111.12
Sep 03 12:28:13 volumio ntpd[1376]: Soliciting pool server 210.28.130.4
Sep 03 12:28:13 volumio ntpd[1376]: Soliciting pool server 139.199.214.202
Sep 03 12:28:13 volumio volumio[1037]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 03 12:28:13 volumio volumio[1037]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 03 12:28:13 volumio volumio[1037]: *** WARNING *** For more information see 
Sep 03 12:28:13 volumio volumio[1037]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 03 12:28:13 volumio volumio[1037]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 03 12:28:13 volumio volumio[1037]: *** WARNING *** For more information see 
Sep 03 12:28:13 volumio volumio[1037]: info: Discovery: Started advertising with name: Volumio
Sep 03 12:28:13 volumio node[1037]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 03 12:28:13 volumio node[1037]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 03 12:28:13 volumio node[1037]: *** WARNING *** For more information see 
Sep 03 12:28:13 volumio systemd[1]: Starting OpenBSD Secure Shell server...
Sep 03 12:28:13 volumio node[1037]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 03 12:28:13 volumio node[1037]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 03 12:28:13 volumio node[1037]: *** WARNING *** For more information see 
Sep 03 12:28:13 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 03 12:28:13 volumio volumio[1037]: info: Loading plugin "outputs"...
Sep 03 12:28:14 volumio volumio[1037]: info: Loading plugin "albumart"...
Sep 03 12:28:14 volumio volumio[1037]: info: Plugin example_plugin is not enabled
Sep 03 12:28:14 volumio volumio[1037]: info: Loading plugin "inputs"...
Sep 03 12:28:14 volumio volumio[1037]: info: Loading plugin "updater_comm"...
Sep 03 12:28:14 volumio ntpd[1376]: Soliciting pool server 84.16.67.12
Sep 03 12:28:14 volumio ntpd[1376]: Soliciting pool server 119.28.206.193
Sep 03 12:28:14 volumio sshd[1544]: Server listening on 0.0.0.0 port 22.
Sep 03 12:28:14 volumio sshd[1544]: Server listening on :: port 22.
Sep 03 12:28:14 volumio systemd[1]: Started OpenBSD Secure Shell server.
Sep 03 12:28:14 volumio firststart.sh[1038]: Disabling firststart service
Sep 03 12:28:14 volumio winbindd[1524]: [2025/09/03 12:28:14.471623,  0] ../lib/util/become_daemon.c:138(daemon_ready)
Sep 03 12:28:14 volumio systemd[1]: Started Samba Winbind Daemon.
Sep 03 12:28:14 volumio winbindd[1524]:   daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Sep 03 12:28:14 volumio volumio[1037]: Forking 3 albumart workers
Sep 03 12:28:14 volumio systemd[1]: Starting Samba SMB Daemon...
Sep 03 12:28:14 volumio firststart.sh[1038]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service.
Sep 03 12:28:14 volumio systemd[1]: Reloading.
Sep 03 12:28:14 volumio volumio[1037]: info: Plugin mpdemulation is not enabled
Sep 03 12:28:14 volumio volumio[1037]: info: Loading plugin "rest_api"...
Sep 03 12:28:14 volumio volumio[1037]: info: Loading plugin "websocket"...
Sep 03 12:28:14 volumio volumio[1037]: info: Starting Socket.io Server version 2.3.0
Sep 03 12:28:14 volumio volumio[1037]: info: Loading i18n strings for locale en
Sep 03 12:28:14 volumio volumio[1037]: Updating browse sources language
Sep 03 12:28:14 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:14 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.
Sep 03 12:28:14 volumio volumio[1037]: Starting albumart workers
Sep 03 12:28:14 volumio volumio[1037]: Starting albumart workers
Sep 03 12:28:14 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 03 12:28:14 volumio volumio[1037]: info: CoreCommandRouter::initPlayerControls
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: Express server listening on port 3000
Sep 03 12:28:15 volumio volumio[1037]:  [Metrics]  WebUI:  8s 379.27ms 
Sep 03 12:28:15 volumio volumio[1037]: info: Setting Device type: x86
Sep 03 12:28:15 volumio volumio[1037]: info: CoreStateMachine::resetVolumioState
Sep 03 12:28:15 volumio volumio[1037]: info: CoreStateMachine::getcurrentVolume
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::volumioRetrievevolume
Sep 03 12:28:15 volumio volumio[1037]: info: CoreStateMachine::pushState
Sep 03 12:28:15 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:15 volumio volumio[1037]: Starting albumart workers
Sep 03 12:28:15 volumio firststart.sh[1038]: Finalizing
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Sep 03 12:28:15 volumio volumio[1037]: info: CoreStateMachine::getcurrentVolume
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::volumioRetrievevolume
Sep 03 12:28:15 volumio volumio[1037]: info: CoreStateMachine::pushState
Sep 03 12:28:15 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:15 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState
Sep 03 12:28:15 volumio volumio[1037]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Sep 03 12:28:15 volumio volumio[1037]: info: Completed loading Core Plugins
Sep 03 12:28:15 volumio volumio[1037]: info: Preparing to generate the ALSA configuration file
Sep 03 12:28:15 volumio volumio[1037]: info: Cannot read play queue from file
Sep 03 12:28:15 volumio volumio[1037]: info: Volumio Network Manager: Network status updated: 1
Sep 03 12:28:15 volumio volumio[1037]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Sep 03 12:28:15 volumio volumio[1037]: wlan0     Interface doesn't support scanning.
Sep 03 12:28:15 volumio volumio[1037]: info: Cannot use regular scanning, forcing with ap-force
Sep 03 12:28:15 volumio sudo[1605]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Sep 03 12:28:15 volumio sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:15 volumio sudo[1605]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:15 volumio volumio[1037]: command failed: No such device (-19)
Sep 03 12:28:15 volumio volumio[1037]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Sep 03 12:28:15 volumio volumio[1037]: command failed: No such device (-19)
Sep 03 12:28:15 volumio volumio[1037]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Sep 03 12:28:15 volumio volumio-remote-updater[797]: [2025-09-03 12:28:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756902492 101
Sep 03 12:28:16 volumio volumio[1037]: 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: 1
Sep 03 12:28:16 volumio volumio[1037]: info: Asound.conf file written
Sep 03 12:28:17 volumio sudo[1618]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Sep 03 12:28:17 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:17 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:17 volumio volumio[1037]: No state is present for card AUDIO
Sep 03 12:28:17 volumio volumio[1037]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" ""
Sep 03 12:28:17 volumio volumio[1037]: Hardware is initialized using a generic method
Sep 03 12:28:17 volumio volumio[1037]: No state is present for card AUDIO
Sep 03 12:28:17 volumio volumio[1037]: info: Output device has changed, restarting MPD
Sep 03 12:28:17 volumio volumio[1037]: info: Output device has changed, restarting Shairport Sync
Sep 03 12:28:17 volumio sudo[1626]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:17 volumio sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:17 volumio sudo[1628]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 03 12:28:17 volumio sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:17 volumio systemd[1]: Stopping Music Player Daemon...
Sep 03 12:28:17 volumio volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Sep 03 12:28:17 volumio volumio[1037]: info: ___________ START PLUGINS ___________
Sep 03 12:28:17 volumio volumio[1037]: info: Setting Hotspot Unique name on first start: Volumio-3B319
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam
Sep 03 12:28:17 volumio volumio[1037]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::Reload Ui
Sep 03 12:28:17 volumio volumio[1037]: info: ControllerMpd::onStart: Initializing MPD
Sep 03 12:28:17 volumio volumio[1037]: info: Creating MPD Configuration file
Sep 03 12:28:17 volumio sudo[1635]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 03 12:28:17 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:17 volumio volumio[1037]: info: [1756902497221] CoreMusicLibrary::Adding element Media Servers
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:18 volumio ntpd[1376]: receive: Unexpected origin timestamp 0xec62b2e1.5b6e91cc does not match aorg 0000000000.00000000 from server@210.28.130.4 xmt 0xec62b2e2.08dda2d4
Sep 03 12:28:18 volumio sudo[1637]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 03 12:28:18 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:18 volumio ntpd[1376]: receive: Unexpected origin timestamp 0xec62b2e1.5b74ffef does not match aorg 0000000000.00000000 from server@116.203.151.74 xmt 0xec62b2e2.1da6947e
Sep 03 12:28:18 volumio ntpd[1376]: receive: Unexpected origin timestamp 0xec62b2e1.5b73d7b0 does not match aorg 0000000000.00000000 from server@5.79.108.34 xmt 0xec62b2e2.4fb41e16
Sep 03 12:28:18 volumio ntpd[1376]: receive: Unexpected origin timestamp 0xec62b2e1.5b761843 does not match aorg 0000000000.00000000 from server@193.182.111.142 xmt 0xec62b2e2.2cccfe1b
Sep 03 12:28:18 volumio volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:18 volumio volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 03 12:28:18 volumio volumio[1037]: info: [1756902498569] CoreMusicLibrary::Adding element Last_100
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 03 12:28:18 volumio volumio[1037]: info: [1756902498571] CoreMusicLibrary::Adding element Webradio
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 03 12:28:18 volumio volumio[1037]: info: Initializing BBC Radios
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 03 12:28:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:18 volumio volumio[1037]: info: Volumio Calling Home
Sep 03 12:28:18 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:18 volumio sudo[1626]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:19 volumio sudo[1661]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf
Sep 03 12:28:19 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:20 volumio volumio[1037]: info: MPD Permissions set
Sep 03 12:28:20 volumio volumio[1037]: info: MPD Permissions set
Sep 03 12:28:20 volumio volumio[1037]: info: Volumio called home
Sep 03 12:28:20 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Sep 03 12:28:20 volumio sudo[1678]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 03 12:28:20 volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:20 volumio sudo[1678]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:20 volumio sudo[1680]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 03 12:28:20 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:20 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Sep 03 12:28:21 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:22 volumio volumio[1037]: info: Discovery: adding 87c38272-dfae-490c-86bf-c9d9cc56bb47
Sep 03 12:28:22 volumio volumio[1037]: info: Discovery: Found device Volumio
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:22 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:22 volumio volumio[1037]: info: Permissions for /etc/hostapd/hostapd.conf
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Sep 03 12:28:22 volumio volumio[1037]: info: Discovery: Restarting Advertising due to device name change
Sep 03 12:28:22 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:22 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:22 volumio sudo[1699]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Sep 03 12:28:22 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:22 volumio systemd[1]: Starting Wireless Services...
Sep 03 12:28:22 volumio systemd[1]: mpd.service: Succeeded.
Sep 03 12:28:22 volumio systemd[1]: Stopped Music Player Daemon.
Sep 03 12:28:22 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:22 volumio systemd[1]: Starting Music Player Daemon...
Sep 03 12:28:22 volumio systemd[1]: firststart.service: Succeeded.
Sep 03 12:28:22 volumio wireless.js[1701]: WIRELESS: No wireless interface, exiting
Sep 03 12:28:22 volumio volumio[1037]: info: Starting Shairport Sync
Sep 03 12:28:22 volumio volumio[1037]: info: Starting Shairport Sync
Sep 03 12:28:22 volumio sudo[1716]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Sep 03 12:28:22 volumio sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:22 volumio volumio[1037]: info: Starting Shairport Sync
Sep 03 12:28:22 volumio sudo[1708]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 03 12:28:22 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:22 volumio sudo[1718]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Sep 03 12:28:22 volumio sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:22 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:22 volumio sudo[1723]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Sep 03 12:28:22 volumio sudo[1723]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:22 volumio iw[1715]: command failed: No such device (-19)
Sep 03 12:28:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Sep 03 12:28:22 volumio systemd[1]: shairport-sync.service: Succeeded.
Sep 03 12:28:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Sep 03 12:28:22 volumio iwconfig[1729]: Error for wireless request "Set Power Management" (8B2C) :
Sep 03 12:28:22 volumio iwconfig[1729]:     SET failed on device wlan0 ; No such device.
Sep 03 12:28:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Sep 03 12:28:23 volumio sudo[1733]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Sep 03 12:28:23 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:23 volumio sudo[1718]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:23 volumio systemd[1]: wireless.service: Succeeded.
Sep 03 12:28:23 volumio systemd[1]: Started Wireless Services.
Sep 03 12:28:23 volumio sudo[1716]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:23 volumio sudo[1723]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:23 volumio volumio[1037]: info: Shairport-Sync Started
Sep 03 12:28:23 volumio volumio[1037]: Error adding Membership: Error: addMembership EINVAL
Sep 03 12:28:23 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:23 volumio volumio[1037]: info: Shairport-Sync Started
Sep 03 12:28:23 volumio volumio[1037]: info: Shairport-Sync Started
Sep 03 12:28:23 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Sep 03 12:28:23 volumio sudo[1733]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:23 volumio volumio[1037]: info: Upmpdcli Daemon Started
Sep 03 12:28:23 volumio smbd[1582]: [2025/09/03 12:28:23.094617,  0] ../lib/util/become_daemon.c:138(daemon_ready)
Sep 03 12:28:23 volumio smbd[1582]:   daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Sep 03 12:28:23 volumio systemd[1]: Started Samba SMB Daemon.
Sep 03 12:28:23 volumio systemd[1]: Reached target Multi-User System.
Sep 03 12:28:23 volumio systemd[1]: Reached target Graphical Interface.
Sep 03 12:28:23 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Sep 03 12:28:23 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Sep 03 12:28:23 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Sep 03 12:28:23 volumio mpd[1726]: Sep 03 12:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Sep 03 12:28:23 volumio systemd[1]: Started Music Player Daemon.
Sep 03 12:28:23 volumio sudo[1628]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:23 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:23 volumio systemd[1]: Startup finished in 15.771s (firmware) + 1.784s (loader) + 55.065s (kernel) + 31.643s (userspace) = 1min 44.265s.
Sep 03 12:28:23 volumio volumio[1037]: info: Completed starting Core Plugins
Sep 03 12:28:23 volumio volumio[1037]: info: -------------------------------------------
Sep 03 12:28:23 volumio volumio[1037]: info: -----    MyVolumio plugins startup     ----
Sep 03 12:28:23 volumio volumio[1037]: info: -------------------------------------------
Sep 03 12:28:23 volumio volumio[1037]: info: [MyVolumio PluginManager] Fetching plans data....
Sep 03 12:28:23 volumio volumio[1037]: info: Discovery: A device disappeared from network
Sep 03 12:28:23 volumio volumio[1037]: info: Discovery: Device volumio disappeared from network
Sep 03 12:28:23 volumio volumio[1738]: Generating RSA private key, 4096 bit long modulus (2 primes)
Sep 03 12:28:23 volumio volumio[1037]: error: MPD error: The expression evaluated to a falsy value:
Sep 03 12:28:23 volumio volumio[1037]:   assert.ok(self.idling)
Sep 03 12:28:23 volumio volumio[1037]: error: The expression evaluated to a falsy value:
Sep 03 12:28:23 volumio volumio[1037]:   assert.ok(self.idling)
Sep 03 12:28:23 volumio volumio[1037]: error: updateQueue error: null
Sep 03 12:28:23 volumio volumio[1037]: info: MPD running with PID1726
Sep 03 12:28:23 volumio volumio[1037]:  ,establishing connection
Sep 03 12:28:23 volumio volumio[1037]: error: updateQueue error: null
Sep 03 12:28:25 volumio volumio[1738]: ..........................................................................................................++++
Sep 03 12:28:27 volumio volumio[1738]: ........................................................................................................................................++++
Sep 03 12:28:27 volumio volumio[1738]: e is 65537 (0x010001)
Sep 03 12:28:27 volumio volumio[1738]: writing RSA key
Sep 03 12:28:27 volumio volumio[1037]: info: Discovery: Started advertising with name: Volumio
Sep 03 12:28:28 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection
Sep 03 12:28:28 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo
Sep 03 12:28:28 volumio sudo[1815]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 03 12:28:28 volumio sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:28 volumio sudo[1815]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:28 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Sep 03 12:28:28 volumio volumio[1037]: info: Discovery: adding 87c38272-dfae-490c-86bf-c9d9cc56bb47
Sep 03 12:28:28 volumio volumio[1037]: info: Discovery: Found device Volumio
Sep 03 12:28:28 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:28 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:28 volumio volumio[1037]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Sep 03 12:28:28 volumio dbus-daemon[792]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.15' (uid=0 pid=1768 comm="/usr/lib/chromium/chromium --show-component-extens")
Sep 03 12:28:28 volumio systemd[1]: Starting Bluetooth service...
Sep 03 12:28:30 volumio kernel: Bluetooth: Core ver 2.22
Sep 03 12:28:30 volumio kernel: NET: Registered PF_BLUETOOTH protocol family
Sep 03 12:28:30 volumio kernel: Bluetooth: HCI device and connection manager initialized
Sep 03 12:28:30 volumio kernel: Bluetooth: HCI socket layer initialized
Sep 03 12:28:30 volumio kernel: Bluetooth: L2CAP socket layer initialized
Sep 03 12:28:30 volumio kernel: Bluetooth: SCO socket layer initialized
Sep 03 12:28:30 volumio bluetoothd[1829]: Bluetooth daemon 5.50
Sep 03 12:28:30 volumio bluetoothd[1829]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Sep 03 12:28:30 volumio dbus-daemon[792]: [system] Successfully activated service 'org.bluez'
Sep 03 12:28:30 volumio systemd[1]: Started Bluetooth service.
Sep 03 12:28:30 volumio bluetoothd[1829]: Starting SDP server
Sep 03 12:28:30 volumio bluetoothd[1829]: Excluding (cli) sap
Sep 03 12:28:30 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Sep 03 12:28:30 volumio kernel: Bluetooth: BNEP filters: protocol multicast
Sep 03 12:28:30 volumio kernel: Bluetooth: BNEP socket layer initialized
Sep 03 12:28:30 volumio dbus-daemon[792]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.16' (uid=0 pid=1829 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Sep 03 12:28:30 volumio bluetoothd[1829]: Bluetooth management interface 1.22 initialized
Sep 03 12:28:31 volumio systemd[1]: Starting Hostname Service...
Sep 03 12:28:32 volumio dbus-daemon[792]: [system] Successfully activated service 'org.freedesktop.hostname1'
Sep 03 12:28:32 volumio systemd[1]: Started Hostname Service.
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart
Sep 03 12:28:33 volumio volumio[1037]: info: Refreshing Cached IP Addresses
Sep 03 12:28:33 volumio sudo[1968]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Sep 03 12:28:33 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:33 volumio sudo[1970]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 03 12:28:33 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:33 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:33 volumio systemd[1]: upmpdcli.service: Succeeded.
Sep 03 12:28:33 volumio sudo[1973]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 03 12:28:33 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:33 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:33 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:33 volumio volumio[1037]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Sep 03 12:28:33 volumio volumio[1037]: info: Received Get System Info
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 03 12:28:33 volumio volumio[1037]: info: Discovery: Getting this device information
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 03 12:28:33 volumio volumio-remote-updater[797]: No test mode
Sep 03 12:28:33 volumio volumio-remote-updater[797]: No alpha test mode
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Sep 03 12:28:33 volumio volumio[1037]: info: Adding plugin bluetooth to MyMusic Plugins
Sep 03 12:28:33 volumio volumio[1037]: info: Adding plugin multiroom to MyMusic Plugins
Sep 03 12:28:33 volumio volumio[1037]: info: Adding plugin metavolumio to MyMusic Plugins
Sep 03 12:28:33 volumio volumio[1037]: info: Adding plugin cd_controller to MyMusic Plugins
Sep 03 12:28:33 volumio volumio[1037]: info: Adding plugin qobuzconnect to MyMusic Plugins
Sep 03 12:28:33 volumio volumio[1037]: info: Adding plugin smart_inputs to MyMusic Plugins
Sep 03 12:28:33 volumio volumio[1037]: info: Adding plugin tidalconnect to MyMusic Plugins
Sep 03 12:28:33 volumio volumio[1037]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Sep 03 12:28:34 volumio volumio[1037]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Sep 03 12:28:34 volumio volumio[1037]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Sep 03 12:28:34 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:34 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:34 volumio volumio[1037]: info: Starting MyVolumio Remote Streaming Endpoints
Sep 03 12:28:34 volumio volumio[1037]: info: MyVolumio not started
Sep 03 12:28:34 volumio volumio[1037]: info: Initializing device activation check
Sep 03 12:28:34 volumio volumio[1037]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Sep 03 12:28:34 volumio volumio[1037]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Sep 03 12:28:35 volumio volumio[1037]: info: Starting Streaming Service Transparent Proxy
Sep 03 12:28:35 volumio volumio[1037]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Sep 03 12:28:35 volumio volumio[1037]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Sep 03 12:28:35 volumio volumio[1037]: info: Streaming services startup
Sep 03 12:28:35 volumio volumio[1037]: info: Starting Streaming Daemon
Sep 03 12:28:35 volumio sudo[1987]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Sep 03 12:28:35 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:35 volumio volumio[1037]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Sep 03 12:28:35 volumio sudo[1987]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:35 volumio volumio[1037]: info: Retrieving Cloud Streaming UI
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Sep 03 12:28:35 volumio volumio[1037]: info: Loading i18n strings for locale en
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Sep 03 12:28:35 volumio volumio[1037]: info: Setting timezone to UTC
Sep 03 12:28:35 volumio sudo[1995]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Sep 03 12:28:35 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:35 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:35 volumio sudo[1998]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Sep 03 12:28:35 volumio sudo[1998]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:35 volumio sudo[1998]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:35 volumio sudo[2001]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Sep 03 12:28:35 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:35 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:35 volumio sudo[2004]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Sep 03 12:28:35 volumio sudo[2004]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:35 volumio systemd-timedated[1476]: Failed to set time zone: Permission denied
Sep 03 12:28:35 volumio sudo[2004]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:35 volumio volumio[1037]: Failed to set time zone: Access denied
Sep 03 12:28:35 volumio volumio[1037]: info: Could not set timezone, retrying
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Sep 03 12:28:35 volumio volumio[1037]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix plugin installations due to apt repository
\nFix chromecast playback
\n
\nNEW ADDITIONS
\n\nAdd link for downloading CORRD in the side-menu
\n\n","title":"Update v3.832","updateavailable":true}
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Sep 03 12:28:35 volumio volumio[1037]: error: Cannot start Volumio Streaming Daemon
Sep 03 12:28:35 volumio volumio[1037]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Sep 03 12:28:35 volumio volumio[1037]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Sep 03 12:28:35 volumio volumio[1037]: info: Getting Tidal Cloud Configuration
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:35 volumio volumio[1037]: info: Getting Qobuz Cloud Configuration
Sep 03 12:28:35 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:35 volumio volumio[1037]: info: Getting Spotify Cloud Configuration
Sep 03 12:28:35 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:35 volumio volumio[1037]: info: Saving Spotify Acccount
Sep 03 12:28:35 volumio volumio[1037]: info: Got Tidal Cloud Configuration
Sep 03 12:28:35 volumio volumio[1037]: info: Got it
Sep 03 12:28:35 volumio volumio[1037]: info: Got it
Sep 03 12:28:35 volumio volumio[1037]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Sep 03 12:28:35 volumio volumio[1037]: Updating browse sources language
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:35 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:35 volumio volumio[1037]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Sep 03 12:28:35 volumio volumio[1037]: STREAMING PROXY: Starting server on port 3245
Sep 03 12:28:35 volumio volumio[1037]: Node JS runtime: 14
Sep 03 12:28:36 volumio volumio[1037]: info: Fetching Streaming Services browse cache
Sep 03 12:28:36 volumio sudo[2007]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Sep 03 12:28:36 volumio sudo[2007]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:36 volumio sudo[2007]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:43 volumio sudo[2024]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 03 12:28:43 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:43 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:43 volumio sudo[2026]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 03 12:28:43 volumio sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:43 volumio sudo[2030]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Sep 03 12:28:43 volumio sudo[2030]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:43 volumio sudo[2026]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:43 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Sep 03 12:28:43 volumio sudo[2030]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:43 volumio volumio[1037]: info: Upmpdcli Daemon Started
Sep 03 12:28:43 volumio volumio[2034]: writing RSA key
Sep 03 12:28:48 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 03 12:28:48 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:48 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Sep 03 12:28:48 volumio volumio-remote-updater[797]: No test mode
Sep 03 12:28:48 volumio volumio-remote-updater[797]: No alpha test mode
Sep 03 12:28:48 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Sep 03 12:28:49 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 03 12:28:49 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Sep 03 12:28:49 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:49 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:50 volumio volumio[1037]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix plugin installations due to apt repository
\nFix chromecast playback
\n
\nNEW ADDITIONS
\n\nAdd link for downloading CORRD in the side-menu
\n\n","title":"Update v3.832","updateavailable":true}
Sep 03 12:28:50 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::volumioGetBrowseSources
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 03 12:28:54 volumio volumio[1037]: info: Discovery: Getting this device information
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:54 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 03 12:28:55 volumio volumio[1037]: verbose: New Socket.io Connection to 192.168.2.109 from 192.168.2.32 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:55 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Sep 03 12:28:55 volumio volumio[1037]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Sep 03 12:28:55 volumio volumio[1037]: info: Completed starting MyVolumio Plugin
Sep 03 12:28:55 volumio volumio[1037]:  [Metrics]  CommandRouter:  47s 625.67ms 
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::volumiosetStartupVolume
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: info: Retrieving Cloud Streaming UI
Sep 03 12:28:55 volumio volumio[1037]: info: Getting Tidal Cloud Configuration
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: info: Getting Qobuz Cloud Configuration
Sep 03 12:28:55 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: info: Getting Spotify Cloud Configuration
Sep 03 12:28:55 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: info: Saving Spotify Acccount
Sep 03 12:28:55 volumio volumio[1037]: info: Got Tidal Cloud Configuration
Sep 03 12:28:55 volumio volumio[1037]: info: Got it
Sep 03 12:28:55 volumio volumio[1037]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: info: Retrieving Cloud Streaming UI
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Sep 03 12:28:55 volumio volumio[1037]: info: Getting Tidal Cloud Configuration
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: info: Getting Qobuz Cloud Configuration
Sep 03 12:28:55 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: info: Getting Spotify Cloud Configuration
Sep 03 12:28:55 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: info: Saving Spotify Acccount
Sep 03 12:28:55 volumio volumio[1037]: info: Got Tidal Cloud Configuration
Sep 03 12:28:55 volumio volumio[1037]: info: Got it
Sep 03 12:28:55 volumio volumio[1037]: info: Got it
Sep 03 12:28:55 volumio volumio[1037]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Sep 03 12:28:55 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:55 volumio volumio[1037]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Sep 03 12:28:56 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Sep 03 12:28:56 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Sep 03 12:28:56 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Sep 03 12:28:56 volumio volumio[1037]: info: Version has changed, forcing UI Reload
Sep 03 12:28:56 volumio volumio[1037]: info: CoreCommandRouter::Reload Ui
Sep 03 12:28:57 volumio sudo[2089]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 03 12:28:57 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:57 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:57 volumio sudo[2091]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 03 12:28:57 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:57 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:57 volumio volumio[1037]: verbose: New Socket.io Connection to 192.168.2.109 from 192.168.2.32 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 5
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Sep 03 12:28:57 volumio volumio[1037]: info: Received Get System Info
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 03 12:28:57 volumio volumio[1037]: info: Discovery: Getting this device information
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 03 12:28:57 volumio volumio-remote-updater[797]: No test mode
Sep 03 12:28:57 volumio volumio-remote-updater[797]: No alpha test mode
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:57 volumio volumio[1037]: info: Retrieving Cloud Streaming UI
Sep 03 12:28:57 volumio volumio[1037]: info: Getting Tidal Cloud Configuration
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:57 volumio volumio[1037]: info: Getting Qobuz Cloud Configuration
Sep 03 12:28:57 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:57 volumio volumio[1037]: info: Getting Spotify Cloud Configuration
Sep 03 12:28:57 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:57 volumio volumio[1037]: info: Saving Spotify Acccount
Sep 03 12:28:57 volumio volumio[1037]: info: Got Tidal Cloud Configuration
Sep 03 12:28:57 volumio volumio[1037]: info: Got it
Sep 03 12:28:57 volumio volumio[1037]: info: Got it
Sep 03 12:28:57 volumio volumio[1037]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:57 volumio volumio[1037]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Sep 03 12:28:57 volumio volumio[1037]: 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: 6
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Sep 03 12:28:57 volumio volumio[1037]: info: Received Get System Info
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 03 12:28:57 volumio volumio[1037]: info: Discovery: Getting this device information
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState
Sep 03 12:28:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 0
Sep 03 12:28:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:58 volumio volumio[1037]: info: Retrieving Cloud Streaming UI
Sep 03 12:28:58 volumio volumio[1037]: info: Getting Tidal Cloud Configuration
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:58 volumio volumio[1037]: info: Getting Qobuz Cloud Configuration
Sep 03 12:28:58 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:58 volumio volumio[1037]: info: Getting Spotify Cloud Configuration
Sep 03 12:28:58 volumio volumio[1037]: info: Asking plugin for UI Config
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:58 volumio volumio[1037]: info: Saving Spotify Acccount
Sep 03 12:28:58 volumio volumio[1037]: info: Got Tidal Cloud Configuration
Sep 03 12:28:58 volumio volumio[1037]: info: Got it
Sep 03 12:28:58 volumio volumio[1037]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 03 12:28:58 volumio volumio[1037]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Sep 03 12:28:58 volumio volumio[1037]: info: Loading i18n strings for locale zh
Sep 03 12:28:58 volumio volumio[1037]: Updating browse sources language
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Sep 03 12:28:58 volumio volumio[1037]: info: Setting timezone to Asia/Shanghai
Sep 03 12:28:58 volumio sudo[2104]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Sep 03 12:28:58 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:58 volumio sudo[2104]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:58 volumio sudo[2107]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime
Sep 03 12:28:58 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:58 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:58 volumio sudo[2110]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Sep 03 12:28:58 volumio sudo[2110]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:58 volumio sudo[2110]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:58 volumio sudo[2113]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai
Sep 03 12:28:58 volumio sudo[2113]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:58 volumio systemd-timedated[1476]: Failed to set time zone: Permission denied
Sep 03 12:28:58 volumio sudo[2113]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:58 volumio volumio[1037]: Failed to set time zone: Access denied
Sep 03 12:28:58 volumio volumio[1037]: info: Could not set timezone, retrying
Sep 03 12:28:58 volumio volumio-remote-updater[797]: No test mode
Sep 03 12:28:58 volumio volumio-remote-updater[797]: No alpha test mode
Sep 03 12:28:58 volumio volumio[1037]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix plugin installations due to apt repository
\nFix chromecast playback
\n
\nNEW ADDITIONS
\n\nAdd link for downloading CORRD in the side-menu
\n\n","title":"Update v3.832","updateavailable":true}
Sep 03 12:28:58 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Sep 03 12:28:58 volumio volumio[1037]: info: Fetching Streaming Services browse cache
Sep 03 12:28:59 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Sep 03 12:28:59 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Sep 03 12:28:59 volumio volumio[1037]: info: Loading i18n strings for locale en
Sep 03 12:28:59 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Sep 03 12:28:59 volumio volumio[1037]: info: Setting timezone to UTC
Sep 03 12:28:59 volumio sudo[2117]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Sep 03 12:28:59 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:59 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:59 volumio sudo[2120]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Sep 03 12:28:59 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:59 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:59 volumio sudo[2123]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Sep 03 12:28:59 volumio sudo[2123]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:59 volumio sudo[2123]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:59 volumio sudo[2126]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Sep 03 12:28:59 volumio sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:59 volumio systemd-timedated[1476]: Failed to set time zone: Permission denied
Sep 03 12:28:59 volumio sudo[2126]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:59 volumio volumio[1037]: Failed to set time zone: Access denied
Sep 03 12:28:59 volumio volumio[1037]: info: Could not set timezone, retrying
Sep 03 12:28:59 volumio volumio[1037]: Updating browse sources language
Sep 03 12:28:59 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 03 12:28:59 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Sep 03 12:28:59 volumio sudo[2176]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai
Sep 03 12:28:59 volumio sudo[2176]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 03 12:28:59 volumio systemd-timedated[1476]: Failed to set time zone: Permission denied
Sep 03 12:28:59 volumio sudo[2176]: pam_unix(sudo:session): session closed for user root
Sep 03 12:28:59 volumio volumio[1037]: Failed to set time zone: Access denied
Sep 03 12:28:59 volumio volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 03 12:28:59 volumio volumio[1037]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Asia/Shanghai'
Sep 03 12:28:59 volumio volumio[1037]: Failed to set time zone: Access denied
Sep 03 12:28:59 volumio volumio[1037]:     at checkExecSyncError (child_process.js:790:11)
Sep 03 12:28:59 volumio volumio[1037]:     at execSync (child_process.js:863:15)
Sep 03 12:28:59 volumio volumio[1037]:     at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9)
Sep 03 12:28:59 volumio volumio[1037]:     at listOnTimeout (internal/timers.js:557:17)
Sep 03 12:28:59 volumio volumio[1037]:     at processTimers (internal/timers.js:500:7) {
Sep 03 12:28:59 volumio volumio[1037]:   status: 1,
Sep 03 12:28:59 volumio volumio[1037]:   signal: null,
Sep 03 12:28:59 volumio volumio[1037]:   output: [ null, '', 'Failed to set time zone: Access denied\n' ],
Sep 03 12:28:59 volumio volumio[1037]:   pid: 2175,
Sep 03 12:28:59 volumio volumio[1037]:   stdout: '',
Sep 03 12:28:59 volumio volumio[1037]:   stderr: 'Failed to set time zone: Access denied\n'
Sep 03 12:28:59 volumio volumio[1037]: }
Sep 03 12:28:59 volumio volumio[1037]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 03 12:29:00 volumio sudo[2187]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 12:28
Sep 03 12:29:00 volumio sudo[2187]: 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="570c5791513f5bac7da274aba6690c1a961705de"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST"
VOLUMIO_VERSION="3.812"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"