-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-12-14 18:10:10 CET. -- Dec 14 18:09:11 huiskamer fake-hwclock[331]: Sun Dec 14 17:09:11 UTC 2025 Dec 14 18:09:11 huiskamer systemd[1]: Started Restore / save the current clock. Dec 14 18:09:11 huiskamer systemd[1]: Started Load/Save Random Seed. Dec 14 18:09:11 huiskamer systemd[1]: dynamicswap.service: Succeeded. Dec 14 18:09:11 huiskamer systemd[1]: Started Create System Users. Dec 14 18:09:11 huiskamer systemd[1]: Starting Create Static Device Nodes in /dev... Dec 14 18:09:11 huiskamer systemd[1]: Started udev Coldplug all Devices. Dec 14 18:09:11 huiskamer systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 14 18:09:11 huiskamer systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 14 18:09:11 huiskamer systemd[1]: Started Create Static Device Nodes in /dev. Dec 14 18:09:11 huiskamer systemd[1]: Reached target Local File Systems (Pre). Dec 14 18:09:11 huiskamer systemd[1]: Mounting /var/spool/cups... Dec 14 18:09:11 huiskamer systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 14 18:09:11 huiskamer systemd[1]: Mounting /tmp... Dec 14 18:09:11 huiskamer systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 14 18:09:11 huiskamer systemd[1]: Mounting /var/log... Dec 14 18:09:11 huiskamer systemd[1]: Starting udev Kernel Device Manager... Dec 14 18:09:11 huiskamer systemd[1]: Mounted /var/spool/cups. Dec 14 18:09:11 huiskamer systemd[1]: Mounted /tmp. Dec 14 18:09:11 huiskamer systemd[1]: Mounted /var/log. Dec 14 18:09:11 huiskamer systemd[1]: Starting Flush Journal to Persistent Storage... Dec 14 18:09:11 huiskamer systemd[1]: Mounting /var/spool/cups/tmp... Dec 14 18:09:11 huiskamer systemd[1]: Mounted /var/spool/cups/tmp. Dec 14 18:09:11 huiskamer systemd-udevd[364]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 14 18:09:11 huiskamer systemd-journald[334]: Runtime journal (/run/log/journal/db6dce07be25c6dca1f861d565390872) is 7.5M, max 30.0M, 22.5M free. Dec 14 18:09:11 huiskamer systemd[1]: Started udev Kernel Device Manager. Dec 14 18:09:11 huiskamer systemd[1]: Started Flush Journal to Persistent Storage. Dec 14 18:09:11 huiskamer systemd[1]: Starting Show Plymouth Boot Screen... Dec 14 18:09:11 huiskamer systemd[1]: plymouth-start.service: Succeeded. Dec 14 18:09:11 huiskamer systemd[1]: Started Show Plymouth Boot Screen. Dec 14 18:09:11 huiskamer systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 14 18:09:11 huiskamer systemd[1]: Reached target Paths. Dec 14 18:09:11 huiskamer systemd[1]: Reached target Local Encrypted Volumes. Dec 14 18:09:11 huiskamer systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 14 18:09:11 huiskamer kernel: usb_phy_generic usb_phy_generic.2.auto: usb_phy_generic.2.auto supply vcc not found, using dummy regulator Dec 14 18:09:11 huiskamer kernel: musb-hdrc musb-hdrc.3.auto: MUSB HDRC host driver Dec 14 18:09:11 huiskamer kernel: musb-hdrc musb-hdrc.3.auto: new USB bus registered, assigned bus number 9 Dec 14 18:09:11 huiskamer kernel: usb usb9: New USB device found, idVendor=1d6b, idProduct=0002 Dec 14 18:09:11 huiskamer kernel: usb usb9: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 14 18:09:11 huiskamer kernel: usb usb9: Product: MUSB HDRC host driver Dec 14 18:09:11 huiskamer kernel: usb usb9: Manufacturer: Linux 4.14.0 musb-hcd Dec 14 18:09:11 huiskamer kernel: usb usb9: SerialNumber: musb-hdrc.3.auto Dec 14 18:09:11 huiskamer kernel: hub 9-0:1.0: USB hub found Dec 14 18:09:11 huiskamer kernel: hub 9-0:1.0: 1 port detected Dec 14 18:09:11 huiskamer systemd-udevd[375]: Using default interface naming scheme 'v240'. Dec 14 18:09:11 huiskamer systemd-udevd[375]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 14 18:09:11 huiskamer systemd-udevd[375]: link_config: could not get ethtool features for eth0 Dec 14 18:09:11 huiskamer systemd-udevd[375]: Could not set offload features of eth0: Device or resource busy Dec 14 18:09:11 huiskamer systemd-udevd[380]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 14 18:09:11 huiskamer systemd[1]: Found device /dev/ttyS0. Dec 14 18:09:11 huiskamer kernel: asoc-simple-card sound_i2s: pcm5102a-hifi <-> 1c22000.i2s mapping ok Dec 14 18:09:11 huiskamer systemd-udevd[378]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 14 18:09:11 huiskamer systemd-udevd[371]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 14 18:09:12 huiskamer systemd[1]: Found device /dev/mmcblk0p1. Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Starting Show Plymouth Boot Screen... Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Starting File System Check on /dev/mmcblk0p1... Dec 14 18:09:12 huiskamer systemd[1]: plymouth-start.service: Succeeded. Dec 14 18:09:12 huiskamer systemd[1]: Started Show Plymouth Boot Screen. Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Started File System Check Daemon to report status. Dec 14 18:09:12 huiskamer systemd-fsck[425]: fsck.fat 4.1 (2017-01-24) Dec 14 18:09:12 huiskamer systemd-fsck[425]: /dev/mmcblk0p1: 33 files, 11204/30651 clusters Dec 14 18:09:12 huiskamer systemd[1]: Started File System Check on /dev/mmcblk0p1. Dec 14 18:09:12 huiskamer systemd[1]: Mounting /boot... Dec 14 18:09:12 huiskamer systemd[1]: Mounted /boot. Dec 14 18:09:12 huiskamer systemd[1]: Reached target Local File Systems. Dec 14 18:09:12 huiskamer systemd[1]: Starting Preprocess NFS configuration... Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Starting Create Volatile Files and Directories... Dec 14 18:09:12 huiskamer systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 14 18:09:12 huiskamer systemd[1]: Starting Raise network interfaces... Dec 14 18:09:12 huiskamer systemd[1]: Started ifup for eth0. Dec 14 18:09:12 huiskamer systemd[1]: nfs-config.service: Succeeded. Dec 14 18:09:12 huiskamer systemd[1]: Started Preprocess NFS configuration. Dec 14 18:09:12 huiskamer systemd[1]: plymouth-read-write.service: Succeeded. Dec 14 18:09:12 huiskamer systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 14 18:09:12 huiskamer systemd[1]: Received SIGRTMIN+20 from PID 242 (plymouthd). Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Reached target NFS client services. Dec 14 18:09:12 huiskamer systemd[1]: Started Create Volatile Files and Directories. Dec 14 18:09:12 huiskamer systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 14 18:09:12 huiskamer systemd[1]: Starting RPC bind portmap service... Dec 14 18:09:12 huiskamer systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 14 18:09:12 huiskamer ifup[436]: command failed: No such device (-19) Dec 14 18:09:12 huiskamer sh[437]: command failed: No such device (-19) Dec 14 18:09:12 huiskamer systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 14 18:09:12 huiskamer systemd[1]: Reached target System Initialization. Dec 14 18:09:12 huiskamer systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 14 18:09:12 huiskamer systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 14 18:09:12 huiskamer systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 14 18:09:12 huiskamer systemd[1]: Listening on triggerhappy.socket. Dec 14 18:09:12 huiskamer systemd[1]: Reached target Sockets. Dec 14 18:09:12 huiskamer systemd[1]: Started Daily apt download activities. Dec 14 18:09:12 huiskamer systemd[1]: Reached target Basic System. Dec 14 18:09:12 huiskamer systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 14 18:09:12 huiskamer systemd[1]: Started D-Bus System Message Bus. Dec 14 18:09:12 huiskamer systemd[1]: Started Volumio Iptables Module. Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 14 18:09:12 huiskamer systemd[1]: Starting Volumio Time Update Utility... Dec 14 18:09:12 huiskamer systemd[1]: Started volumio-remote-updater.service. Dec 14 18:09:12 huiskamer systemd[1]: Starting WPA supplicant... Dec 14 18:09:12 huiskamer systemd[1]: Started Volumio Log Rotation Service. Dec 14 18:09:12 huiskamer systemd[1]: Starting Login Service... Dec 14 18:09:12 huiskamer volumio-time-update[484]: volumio-time-update-util: Fetching time from Volumio... Dec 14 18:09:13 huiskamer systemd[1]: Starting triggerhappy global hotkey daemon... Dec 14 18:09:13 huiskamer systemd[1]: Started Manage Sound Card State (restore and store). Dec 14 18:09:13 huiskamer alsactl[498]: alsactl 1.1.8 daemon started Dec 14 18:09:13 huiskamer systemd[1]: Starting Reset Realtek 8821CU Dongle USB Port (one-shot)... Dec 14 18:09:13 huiskamer thd[492]: Unable to parse trigger line: Dec 14 18:09:13 huiskamer thd[492]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 14 18:09:13 huiskamer thd[492]: Unable to parse trigger line: Dec 14 18:09:13 huiskamer thd[492]: Found socket passed from systemd Dec 14 18:09:13 huiskamer systemd[1]: Started Daily apt upgrade and clean activities. Dec 14 18:09:13 huiskamer systemd[1]: Reached target Timers. Dec 14 18:09:13 huiskamer systemd[1]: Starting DHCP Client Daemon... Dec 14 18:09:13 huiskamer systemd[1]: Starting Wireless Services... Dec 14 18:09:13 huiskamer systemd[1]: Starting Save/Restore Sound Card State... Dec 14 18:09:13 huiskamer systemd[1]: Started UPnP Renderer front-end to MPD. Dec 14 18:09:13 huiskamer systemd[1]: Started RPC bind portmap service. Dec 14 18:09:13 huiskamer systemd[1]: Started triggerhappy global hotkey daemon. Dec 14 18:09:13 huiskamer systemd[1]: Reached target RPC Port Mapper. Dec 14 18:09:13 huiskamer systemd[1]: Reached target Remote File Systems (Pre). Dec 14 18:09:13 huiskamer systemd[1]: Reached target Remote File Systems. Dec 14 18:09:13 huiskamer systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 14 18:09:13 huiskamer ifup[436]: command failed: No such device (-19) Dec 14 18:09:13 huiskamer avahi-daemon[473]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Dec 14 18:09:13 huiskamer avahi-daemon[473]: Successfully dropped root privileges. Dec 14 18:09:13 huiskamer avahi-daemon[473]: avahi-daemon 0.7 starting up. Dec 14 18:09:13 huiskamer kernel: Netfilter messages via NETLINK v0.30. Dec 14 18:09:13 huiskamer kernel: nf_tables: (c) 2007-2009 Patrick McHardy Dec 14 18:09:13 huiskamer dhcpcd[500]: no interfaces have a carrier Dec 14 18:09:13 huiskamer kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Dec 14 18:09:13 huiskamer dhcpcd[500]: no interfaces have a carrier Dec 14 18:09:13 huiskamer dhcpcd[500]: forked to background, child pid 531 Dec 14 18:09:13 huiskamer dhcpcd[500]: forked to background, child pid 531 Dec 14 18:09:13 huiskamer kernel: RTL8211E Gigabit Ethernet stmmac-0:00: attached PHY driver [RTL8211E Gigabit Ethernet] (mii_bus:phy_addr=stmmac-0:00, irq=POLL) Dec 14 18:09:13 huiskamer dhcpcd[531]: eth0: waiting for carrier Dec 14 18:09:13 huiskamer dhcpcd[531]: eth0: carrier acquired Dec 14 18:09:13 huiskamer kernel: dwmac-sun8i 1c30000.ethernet eth0: No MAC Management Counters available Dec 14 18:09:13 huiskamer kernel: dwmac-sun8i 1c30000.ethernet eth0: PTP not supported by HW Dec 14 18:09:13 huiskamer avahi-daemon[473]: Successfully called chroot(). Dec 14 18:09:13 huiskamer avahi-daemon[473]: Successfully dropped remaining capabilities. Dec 14 18:09:13 huiskamer systemd[1]: Started Save/Restore Sound Card State. Dec 14 18:09:13 huiskamer avahi-daemon[473]: Loading service file /services/volumio.service. Dec 14 18:09:13 huiskamer avahi-daemon[473]: Network interface enumeration completed. Dec 14 18:09:13 huiskamer avahi-daemon[473]: Server startup complete. Host name is huiskamer.local. Local service cookie is 2187234804. Dec 14 18:09:13 huiskamer avahi-daemon[473]: Service "huiskamer" (/services/volumio.service) successfully established. Dec 14 18:09:13 huiskamer dhcpcd[531]: DUID 00:01:00:01:2c:cb:c5:62:02:01:44:86:bd:c9 Dec 14 18:09:13 huiskamer dhcpcd[531]: eth0: IAID 44:86:bd:c9 Dec 14 18:09:13 huiskamer dhcpcd[531]: eth0: adding address fe80::4746:8e48:1d95:dad0 Dec 14 18:09:13 huiskamer dhcpcd[531]: ipv6_addaddr1: Permission denied Dec 14 18:09:13 huiskamer systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 14 18:09:13 huiskamer systemd[1]: Reached target Sound Card. Dec 14 18:09:13 huiskamer systemd-logind[490]: New seat seat0. Dec 14 18:09:13 huiskamer systemd-logind[490]: Watching system buttons on /dev/input/event0 (r-gpio-keys) Dec 14 18:09:13 huiskamer reset_8821cu_nic_usb_port.sh[535]: Realtek 8821CU Dongle not present. Dec 14 18:09:13 huiskamer systemd[1]: Started Reset Realtek 8821CU Dongle USB Port (one-shot). Dec 14 18:09:13 huiskamer dhcpcd[531]: eth0: carrier lost Dec 14 18:09:13 huiskamer systemd[1]: Started DHCP Client Daemon. Dec 14 18:09:13 huiskamer sh[437]: sending commands to master dhcpcd process Dec 14 18:09:13 huiskamer dhcpcd[478]: sending commands to master dhcpcd process Dec 14 18:09:13 huiskamer systemd[1]: Started Login Service. Dec 14 18:09:13 huiskamer dhcpcd[531]: control command: /sbin/dhcpcd eth0 Dec 14 18:09:13 huiskamer kernel: nf_conntrack version 0.5.0 (8192 buckets, 32768 max) Dec 14 18:09:13 huiskamer loadcpufreq[507]: Loading cpufreq kernel modules...done (none). Dec 14 18:09:13 huiskamer systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 14 18:09:13 huiskamer sh[437]: eth0=eth0 Dec 14 18:09:13 huiskamer systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 14 18:09:13 huiskamer kernel: i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0 Dec 14 18:09:13 huiskamer kernel: rtc-ds1307: probe of 0-0068 failed with error -110 Dec 14 18:09:13 huiskamer cpufrequtils[595]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 14 18:09:13 huiskamer systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 14 18:09:13 huiskamer systemd[1]: Started Raise network interfaces. Dec 14 18:09:13 huiskamer systemd[1]: Started WPA supplicant. Dec 14 18:09:13 huiskamer systemd[1]: Reached target Network. Dec 14 18:09:13 huiskamer systemd[1]: Starting Network Time Service... Dec 14 18:09:13 huiskamer wpa_supplicant[487]: Successfully initialized wpa_supplicant Dec 14 18:09:13 huiskamer systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 14 18:09:13 huiskamer systemd[1]: Reached target Network is Online. Dec 14 18:09:13 huiskamer systemd[1]: Starting LSB: Brings up/down network automatically... Dec 14 18:09:13 huiskamer systemd[1]: Starting Samba NMB Daemon... Dec 14 18:09:13 huiskamer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 14 18:09:14 huiskamer systemd[1]: Starting /etc/rc.local Compatibility... Dec 14 18:09:14 huiskamer systemd[1]: Starting Permit User Sessions... Dec 14 18:09:14 huiskamer systemd[1]: Started /etc/rc.local Compatibility. Dec 14 18:09:14 huiskamer systemd[1]: Started Permit User Sessions. Dec 14 18:09:14 huiskamer systemd[1]: Starting Hold until boot process finishes up... Dec 14 18:09:14 huiskamer systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 14 18:09:14 huiskamer ifplugd(eth0)[678]: ifplugd 0.28 initializing. Dec 14 18:09:14 huiskamer ifplugd(eth0)[678]: Using interface eth0/02:01:44:86:BD:C9 with driver (version: Jan_2016) Dec 14 18:09:14 huiskamer systemd[1]: Received SIGRTMIN+21 from PID 242 (plymouthd). Dec 14 18:09:14 huiskamer ifplugd(eth0)[678]: Using detection mode: SIOCETHTOOL Dec 14 18:09:14 huiskamer ifplugd(eth0)[678]: Initialization complete, link beat not detected. Dec 14 18:09:14 huiskamer volumio-time-update[484]: volumio-time-update-util: Date not found in response Dec 14 18:09:14 huiskamer volumio-time-update[484]: volumio-time-update-util: Retrying in 5 seconds... Dec 14 18:09:14 huiskamer systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 14 18:09:14 huiskamer systemd[1]: Started Hold until boot process finishes up. Dec 14 18:09:14 huiskamer systemd[1]: plymouth-quit.service: Succeeded. Dec 14 18:09:14 huiskamer systemd[1]: Started Terminate Plymouth Boot Screen. Dec 14 18:09:14 huiskamer systemd[1]: Received SIGRTMIN+21 from PID 242 (n/a). Dec 14 18:09:14 huiskamer systemd[1]: Started Serial Getty on ttyS0. Dec 14 18:09:14 huiskamer systemd[1]: Started Getty on tty1. Dec 14 18:09:14 huiskamer systemd[1]: Reached target Login Prompts. Dec 14 18:09:14 huiskamer volumio-remote-updater[485]: Error: No active session Dec 14 18:09:14 huiskamer volumio-remote-updater[485]: [2025-12-14 18:09:14] [info] asio async_connect error: system:111 (Connection refused) Dec 14 18:09:14 huiskamer volumio-remote-updater[485]: [2025-12-14 18:09:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 14 18:09:14 huiskamer volumio-remote-updater[485]: [2025-12-14 18:09:14] [error] handle_connect error: Underlying Transport Error Dec 14 18:09:14 huiskamer ifplugd[652]: Network Interface Plugging Daemon...start eth0...done. Dec 14 18:09:14 huiskamer systemd[1]: Started LSB: Brings up/down network automatically. Dec 14 18:09:14 huiskamer ntpd[651]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 14 18:09:14 huiskamer ntpd[651]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 14 18:09:14 huiskamer systemd[1]: Started Network Time Service. Dec 14 18:09:14 huiskamer ntpd[687]: proto: precision = 1.250 usec (-20) Dec 14 18:09:14 huiskamer ntpd[687]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 14 18:09:14 huiskamer ntpd[687]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 14 18:09:14 huiskamer ntpd[687]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1083 days ago Dec 14 18:09:14 huiskamer ntpd[687]: Listen and drop on 0 v6wildcard [::]:123 Dec 14 18:09:14 huiskamer ntpd[687]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 14 18:09:14 huiskamer ntpd[687]: Listen normally on 2 lo 127.0.0.1:123 Dec 14 18:09:14 huiskamer ntpd[687]: Listening on routing socket on fd #19 for interface updates Dec 14 18:09:14 huiskamer ntpd[687]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 14 18:09:14 huiskamer ntpd[687]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 14 18:09:14 huiskamer volumio[505]: Could not open config: /tmp/upmpdcli.conf Dec 14 18:09:14 huiskamer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 14 18:09:14 huiskamer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 14 18:09:15 huiskamer systemd[1]: iptables.service: Succeeded. Dec 14 18:09:15 huiskamer nmbd[659]: [2025/12/14 18:09:15.200726, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 14 18:09:15 huiskamer nmbd[659]: started asyncdns process 692 Dec 14 18:09:15 huiskamer nmbd[659]: [2025/12/14 18:09:15.203282, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 14 18:09:15 huiskamer nmbd[659]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 14 18:09:15 huiskamer nmbd[659]: [2025/12/14 18:09:15.203507, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 14 18:09:15 huiskamer nmbd[659]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 14 18:09:15 huiskamer haveged[454]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Dec 14 18:09:15 huiskamer haveged[454]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Dec 14 18:09:15 huiskamer haveged[454]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99954 Dec 14 18:09:15 huiskamer haveged[454]: haveged: fills: 0, generated: 0 Dec 14 18:09:15 huiskamer kernel: random: crng init done Dec 14 18:09:15 huiskamer ntpd[687]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 14 18:09:16 huiskamer ntpd[687]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 14 18:09:16 huiskamer wireless.js[502]: WIRELESS: No wireless interface, exiting Dec 14 18:09:16 huiskamer iw[703]: command failed: No such device (-19) Dec 14 18:09:16 huiskamer iwconfig[706]: Error for wireless request "Set Power Management" (8B2C) : Dec 14 18:09:16 huiskamer iwconfig[706]: SET failed on device wlan0 ; No such device. Dec 14 18:09:16 huiskamer systemd[1]: wireless.service: Succeeded. Dec 14 18:09:16 huiskamer systemd[1]: Started Wireless Services. Dec 14 18:09:16 huiskamer systemd[1]: Started Volumio Backend Module. Dec 14 18:09:16 huiskamer systemd[1]: Started Volumio Cpu Tweaker. Dec 14 18:09:16 huiskamer volumio-cpu-tweak[710]: Setting RT Priority for mpd Dec 14 18:09:16 huiskamer volumio-cpu-tweak[710]: pid 35's current scheduling policy: SCHED_OTHER Dec 14 18:09:16 huiskamer volumio-cpu-tweak[710]: pid 35's current scheduling priority: 0 Dec 14 18:09:16 huiskamer volumio-cpu-tweak[710]: Setting MPD Affinity Dec 14 18:09:16 huiskamer volumio-cpu-tweak[710]: pid 3's current affinity mask: 1 Dec 14 18:09:16 huiskamer volumio-cpu-tweak[710]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 14 18:09:16 huiskamer volumio-cpu-tweak[710]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 14 18:09:16 huiskamer systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 14 18:09:17 huiskamer ntpd[687]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 14 18:09:18 huiskamer dhcpcd[531]: eth0: carrier acquired Dec 14 18:09:18 huiskamer kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 14 18:09:18 huiskamer dhcpcd[531]: eth0: IAID 44:86:bd:c9 Dec 14 18:09:18 huiskamer ntpd[687]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 14 18:09:18 huiskamer dhcpcd[531]: eth0: rebinding lease of 192.168.1.37 Dec 14 18:09:18 huiskamer dhcpcd[531]: eth0: probing address 192.168.1.37/24 Dec 14 18:09:19 huiskamer dhcpcd[531]: eth0: soliciting an IPv6 router Dec 14 18:09:19 huiskamer volumio-time-update[484]: volumio-time-update-util: Fetching time from Volumio... Dec 14 18:09:19 huiskamer volumio-remote-updater[485]: [2025-12-14 18:09:19] [connect] Successful connection Dec 14 18:09:19 huiskamer volumio-time-update[484]: volumio-time-update-util: Date not found in response Dec 14 18:09:19 huiskamer volumio-time-update[484]: volumio-time-update-util: Retrying in 5 seconds... Dec 14 18:09:19 huiskamer ifplugd(eth0)[678]: Link beat detected. Dec 14 18:09:19 huiskamer ifplugd(eth0)[678]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 14 18:09:19 huiskamer ifplugd(eth0)[678]: client: ifup: interface eth0 already configured Dec 14 18:09:19 huiskamer ifplugd(eth0)[678]: Program executed successfully. Dec 14 18:09:20 huiskamer volumio[709]: info: ------------------------------------------- Dec 14 18:09:20 huiskamer volumio[709]: info: ----- Volumio3 ---- Dec 14 18:09:20 huiskamer volumio[709]: info: ------------------------------------------- Dec 14 18:09:20 huiskamer volumio[709]: info: ----- System startup ---- Dec 14 18:09:20 huiskamer volumio[709]: info: ------------------------------------------- Dec 14 18:09:21 huiskamer volumio[709]: info: MYVOLUMIO Environment detected Dec 14 18:09:21 huiskamer volumio[709]: info: Plugin folders cleanup Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning into folder /volumio/app/plugins/ Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning category audio_interface Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning category miscellanea Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning category music_service Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning category plugins.json Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning category system_controller Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning category user_interface Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning into folder /data/plugins/ Dec 14 18:09:21 huiskamer volumio[709]: info: Scanning category music_service Dec 14 18:09:21 huiskamer volumio[709]: info: Plugin folders cleanup completed Dec 14 18:09:21 huiskamer volumio[709]: info: ------------------------------------------- Dec 14 18:09:21 huiskamer volumio[709]: info: ----- Core plugins startup ---- Dec 14 18:09:21 huiskamer volumio[709]: info: ------------------------------------------- Dec 14 18:09:21 huiskamer volumio[709]: info: Loading plugins from folder /volumio/app/plugins/ Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin bluetooth to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin multiroom to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin upnp to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin metavolumio to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin ms_surface_dial to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin cd_controller to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin raat to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 14 18:09:21 huiskamer volumio[709]: info: Loading plugins from folder /data/plugins/ Dec 14 18:09:21 huiskamer volumio[709]: info: Loading plugin "system"... Dec 14 18:09:21 huiskamer volumio[709]: info: Loading plugin "appearance"... Dec 14 18:09:23 huiskamer dhcpcd[531]: eth0: leased 192.168.1.37 for 864000 seconds Dec 14 18:09:23 huiskamer dhcpcd[531]: eth0: adding route to 192.168.1.0/24 Dec 14 18:09:23 huiskamer dhcpcd[531]: eth0: adding default route via 192.168.1.1 Dec 14 18:09:23 huiskamer avahi-daemon[473]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.37. Dec 14 18:09:23 huiskamer avahi-daemon[473]: New relevant interface eth0.IPv4 for mDNS. Dec 14 18:09:23 huiskamer avahi-daemon[473]: Registering new address record for 192.168.1.37 on eth0.IPv4. Dec 14 18:09:23 huiskamer volumio[709]: info: Loading plugin "network"... Dec 14 18:09:23 huiskamer ntpd[687]: ntpd exiting on signal 15 (Terminated) Dec 14 18:09:23 huiskamer systemd[1]: Stopping Network Time Service... Dec 14 18:09:23 huiskamer volumio[709]: info: Refreshing Cached IP Addresses Dec 14 18:09:23 huiskamer systemd[1]: ntp.service: Succeeded. Dec 14 18:09:23 huiskamer systemd[1]: Stopped Network Time Service. Dec 14 18:09:23 huiskamer systemd[1]: Starting Network Time Service... Dec 14 18:09:24 huiskamer ntpd[786]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 14 18:09:24 huiskamer ntpd[786]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 14 18:09:24 huiskamer systemd[1]: Started Network Time Service. Dec 14 18:09:24 huiskamer ntpd[795]: proto: precision = 1.208 usec (-20) Dec 14 18:09:24 huiskamer ntpd[795]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 14 18:09:24 huiskamer ntpd[795]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 14 18:09:24 huiskamer ntpd[795]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1083 days ago Dec 14 18:09:24 huiskamer ntpd[795]: Listen and drop on 0 v6wildcard [::]:123 Dec 14 18:09:24 huiskamer ntpd[795]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 14 18:09:24 huiskamer ntpd[795]: Listen normally on 2 lo 127.0.0.1:123 Dec 14 18:09:24 huiskamer ntpd[795]: Listen normally on 3 eth0 192.168.1.37:123 Dec 14 18:09:24 huiskamer ntpd[795]: Listening on routing socket on fd #20 for interface updates Dec 14 18:09:24 huiskamer ntpd[795]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 14 18:09:24 huiskamer ntpd[795]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 14 18:09:24 huiskamer volumio[709]: info: Loading plugin "services"... Dec 14 18:09:24 huiskamer volumio[709]: info: Loading plugin "alsa_controller"... Dec 14 18:09:24 huiskamer sudo[791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 18:09:24 huiskamer sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 14 18:09:24 huiskamer sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:09:24 huiskamer sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:09:24 huiskamer sudo[785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 18:09:24 huiskamer sudo[785]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:09:24 huiskamer sudo[785]: pam_unix(sudo:session): session closed for user root Dec 14 18:09:24 huiskamer sudo[791]: pam_unix(sudo:session): session closed for user root Dec 14 18:09:24 huiskamer sudo[802]: pam_unix(sudo:session): session closed for user root Dec 14 18:09:24 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 18:09:24 huiskamer volumio[709]: info: Loading plugin "wizard"... Dec 14 18:09:24 huiskamer volumio[709]: info: Loading plugin "networkfs"... Dec 14 18:09:24 huiskamer volumio[709]: info: Starting Udev Watcher for removable devices Dec 14 18:09:24 huiskamer volumio-time-update[484]: volumio-time-update-util: Fetching time from Volumio... Dec 14 18:09:24 huiskamer sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.49/music /mnt/NAS/NAS Dec 14 18:09:24 huiskamer sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:09:24 huiskamer volumio-time-update[484]: volumio-time-update-util: Setting system time to: 2025-12-14 18:09:40 Dec 14 18:09:24 huiskamer kernel: FS-Cache: Loaded Dec 14 18:09:24 huiskamer volumio[709]: info: Ignoring mount for partition: BOOT Dec 14 18:09:24 huiskamer volumio[709]: info: Ignoring mount for partition: volumio Dec 14 18:09:24 huiskamer volumio[709]: info: Ignoring mount for partition: volumio_data Dec 14 18:09:24 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 18:09:24 huiskamer volumio[709]: info: Loading plugin "volumio_command_line_client"... Dec 14 18:09:24 huiskamer sudo[836]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-14 18:09:40 Dec 14 18:09:24 huiskamer sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:09:24 huiskamer kernel: FS-Cache: Netfs 'cifs' registered for caching Dec 14 18:09:24 huiskamer kernel: Key type cifs.idmap registered Dec 14 18:09:24 huiskamer kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Dec 14 18:09:24 huiskamer volumio[709]: info: Loading plugin "upnp"... Dec 14 18:09:24 huiskamer volumio[709]: info: [1765732164436] Starting Upmpd Daemon Dec 14 18:09:24 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 18:09:24 huiskamer volumio[709]: info: Loading plugin "my_music"... Dec 14 18:09:24 huiskamer dbus-daemon[475]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.5' (uid=0 pid=840 comm="timedatectl set-time 2025-12-14 18:09:40 ") Dec 14 18:09:24 huiskamer volumio[709]: info: Loading plugin "mpd"... Dec 14 18:09:24 huiskamer systemd[1]: Starting Time & Date Service... Dec 14 18:09:24 huiskamer dbus-daemon[475]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 14 18:09:24 huiskamer systemd[1]: Started Time & Date Service. Dec 14 18:09:40 huiskamer systemd-timedated[844]: Changed local time to Sun Dec 14 18:09:40 2025 Dec 14 18:09:40 huiskamer sudo[836]: pam_unix(sudo:session): session closed for user root Dec 14 18:09:40 huiskamer volumio-time-update[484]: volumio-time-update-util: System time updated successfully. Dec 14 18:09:40 huiskamer systemd[1]: Started Volumio Time Update Utility. Dec 14 18:09:40 huiskamer sudo[823]: pam_unix(sudo:session): session closed for user root Dec 14 18:09:40 huiskamer volumio[709]: info: Loading plugin "upnp_browser"... Dec 14 18:09:40 huiskamer ntpd[795]: Soliciting pool server 178.239.19.56 Dec 14 18:09:40 huiskamer nmbd[659]: [2025/12/14 18:09:40.541557, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 14 18:09:40 huiskamer nmbd[659]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 14 18:09:40 huiskamer systemd[1]: Started Samba NMB Daemon. Dec 14 18:09:40 huiskamer systemd[1]: Starting Samba Winbind Daemon... Dec 14 18:09:40 huiskamer nmbd[659]: [2025/12/14 18:09:40.562799, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 14 18:09:40 huiskamer nmbd[659]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.37 for name WORKGROUP<1d>. Dec 14 18:09:40 huiskamer nmbd[659]: This response was from IP 192.168.1.49, reporting an IP address of 192.168.1.49. Dec 14 18:09:41 huiskamer winbindd[857]: [2025/12/14 18:09:41.023231, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 14 18:09:41 huiskamer winbindd[857]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 14 18:09:41 huiskamer winbindd[857]: [2025/12/14 18:09:41.049661, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 14 18:09:41 huiskamer winbindd[857]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 14 18:09:41 huiskamer systemd[1]: Started Samba Winbind Daemon. Dec 14 18:09:41 huiskamer volumio[709]: info: Plugin bluetooth is not enabled Dec 14 18:09:41 huiskamer volumio[709]: info: Loading plugin "alarm-clock"... Dec 14 18:09:41 huiskamer systemd[1]: Starting Samba SMB Daemon... Dec 14 18:09:41 huiskamer ntpd[795]: Soliciting pool server 94.198.159.11 Dec 14 18:09:41 huiskamer ntpd[795]: Soliciting pool server 172.233.59.169 Dec 14 18:09:41 huiskamer volumio[709]: info: Loading plugin "manifestui"... Dec 14 18:09:41 huiskamer volumio[709]: info: Loading plugin "metavolumio"... Dec 14 18:09:42 huiskamer smbd[862]: [2025/12/14 18:09:42.081135, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 14 18:09:42 huiskamer smbd[862]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 14 18:09:42 huiskamer systemd[1]: Started Samba SMB Daemon. Dec 14 18:09:42 huiskamer systemd[1]: Reached target Multi-User System. Dec 14 18:09:42 huiskamer systemd[1]: Reached target Graphical Interface. Dec 14 18:09:42 huiskamer systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 14 18:09:42 huiskamer systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 14 18:09:42 huiskamer systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 14 18:09:42 huiskamer systemd[1]: Startup finished in 7.756s (kernel) + 16.908s (userspace) = 24.664s. Dec 14 18:09:42 huiskamer ntpd[795]: Soliciting pool server 5.255.124.190 Dec 14 18:09:42 huiskamer ntpd[795]: Soliciting pool server 5.255.99.180 Dec 14 18:09:42 huiskamer ntpd[795]: Soliciting pool server 193.123.38.172 Dec 14 18:09:43 huiskamer ntpd[795]: Soliciting pool server 185.51.192.63 Dec 14 18:09:43 huiskamer ntpd[795]: Soliciting pool server 45.32.4.67 Dec 14 18:09:43 huiskamer ntpd[795]: Soliciting pool server 95.211.123.72 Dec 14 18:09:43 huiskamer ntpd[795]: Soliciting pool server 158.101.216.150 Dec 14 18:09:44 huiskamer volumio[709]: info: Plugin airplay_emulation is not enabled Dec 14 18:09:44 huiskamer volumio[709]: info: Plugin cd_controller is not enabled Dec 14 18:09:44 huiskamer volumio[709]: info: Loading plugin "last_100"... Dec 14 18:09:44 huiskamer volumio[709]: info: Loading plugin "qobuzconnect"... Dec 14 18:09:44 huiskamer volumio[709]: info: Plugin raat is not enabled Dec 14 18:09:44 huiskamer volumio[709]: info: Loading plugin "streaming_services"... Dec 14 18:09:44 huiskamer ntpd[795]: Soliciting pool server 80.60.67.39 Dec 14 18:09:44 huiskamer ntpd[795]: Soliciting pool server 178.239.19.63 Dec 14 18:09:44 huiskamer ntpd[795]: Soliciting pool server 87.251.32.229 Dec 14 18:09:45 huiskamer ntpd[795]: Soliciting pool server 87.251.32.228 Dec 14 18:09:45 huiskamer ntpd[795]: Soliciting pool server 77.175.129.186 Dec 14 18:09:46 huiskamer volumio[709]: info: Starting Streaming Service Transparent Proxy Dec 14 18:09:46 huiskamer volumio[709]: info: Plugin tidalconnect is not enabled Dec 14 18:09:46 huiskamer volumio[709]: info: Loading plugin "webradio"... Dec 14 18:09:46 huiskamer ntpd[795]: Soliciting pool server 94.198.159.16 Dec 14 18:09:46 huiskamer ntpd[795]: Soliciting pool server 2a05:dfc1:cb1:1234:: Dec 14 18:09:46 huiskamer volumio[709]: info: Loading plugin "i2s_dacs"... Dec 14 18:09:46 huiskamer volumio[709]: info: I2S DAC not set, start Auto-detection Dec 14 18:09:46 huiskamer volumio[709]: info: Loading plugin "volumiodiscovery"... Dec 14 18:09:46 huiskamer volumio[709]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 14 18:09:46 huiskamer volumio[709]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 18:09:46 huiskamer volumio[709]: *** WARNING *** For more information see Dec 14 18:09:46 huiskamer volumio[709]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 14 18:09:46 huiskamer volumio[709]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 18:09:46 huiskamer node[709]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 14 18:09:46 huiskamer volumio[709]: *** WARNING *** For more information see Dec 14 18:09:46 huiskamer node[709]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 18:09:46 huiskamer node[709]: *** WARNING *** For more information see Dec 14 18:09:46 huiskamer node[709]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 14 18:09:46 huiskamer node[709]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 18:09:46 huiskamer node[709]: *** WARNING *** For more information see Dec 14 18:09:46 huiskamer volumio[709]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 14 18:09:46 huiskamer volumio[709]: info: Discovery: Started advertising with name: huiskamer Dec 14 18:09:46 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 18:09:46 huiskamer volumio[709]: info: Loading plugin "spop"... Dec 14 18:09:47 huiskamer volumio-remote-updater[485]: [2025-12-14 18:09:47] [connect] Successful connection Dec 14 18:09:49 huiskamer volumio[709]: STREAMING PROXY: Starting server on port 3245 Dec 14 18:09:49 huiskamer volumio[709]: Node JS runtime: 14 Dec 14 18:09:50 huiskamer volumio[709]: info: Plugin multiroom is not enabled Dec 14 18:09:50 huiskamer volumio[709]: info: Loading plugin "outputs"... Dec 14 18:09:50 huiskamer volumio[709]: info: Loading plugin "albumart"... Dec 14 18:09:50 huiskamer volumio[709]: info: Plugin ms_surface_dial is not enabled Dec 14 18:09:50 huiskamer volumio[709]: info: Plugin example_plugin is not enabled Dec 14 18:09:50 huiskamer volumio[709]: info: Loading plugin "hi_res_audio"... Dec 14 18:09:50 huiskamer volumio[709]: Forking 3 albumart workers Dec 14 18:09:51 huiskamer volumio[709]: Starting albumart workers Dec 14 18:09:52 huiskamer volumio[709]: Starting albumart workers Dec 14 18:09:52 huiskamer volumio[709]: Starting albumart workers Dec 14 18:09:52 huiskamer volumio[709]: info: Applying required configuration parameters for plugin hi_res_audio Dec 14 18:09:52 huiskamer volumio[709]: info: Loading plugin "inputs"... Dec 14 18:09:53 huiskamer volumio[709]: info: Loading plugin "qobuz"... Dec 14 18:09:55 huiskamer volumio[709]: info: Loading plugin "tidal"... Dec 14 18:09:57 huiskamer volumio[709]: info: Loading plugin "oem_helper"... Dec 14 18:09:59 huiskamer volumio[709]: info: Applying required configuration parameters for plugin oem_helper Dec 14 18:09:59 huiskamer volumio[709]: info: Loading plugin "updater_comm"... Dec 14 18:09:59 huiskamer systemd[1]: systemd-fsckd.service: Succeeded. Dec 14 18:09:59 huiskamer volumio[709]: info: Plugin mpdemulation is not enabled Dec 14 18:09:59 huiskamer volumio[709]: info: Loading plugin "rest_api"... Dec 14 18:09:59 huiskamer volumio[709]: info: Loading plugin "websocket"... Dec 14 18:09:59 huiskamer volumio[709]: info: Starting Socket.io Server version 2.3.0 Dec 14 18:09:59 huiskamer volumio[709]: info: Loading i18n strings for locale en Dec 14 18:09:59 huiskamer volumio[709]: Updating browse sources language Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::initPlayerControls Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 18:09:59 huiskamer volumio[709]: Express server listening on port 3000 Dec 14 18:09:59 huiskamer volumio[709]: [Metrics] WebUI: 24s 340.81ms Dec 14 18:09:59 huiskamer volumio[709]: info: CoreStateMachine::resetVolumioState Dec 14 18:09:59 huiskamer volumio[709]: info: CoreStateMachine::getcurrentVolume Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 18:09:59 huiskamer volumio[709]: info: CoreStateMachine::pushState Dec 14 18:09:59 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::volumioPushState Dec 14 18:09:59 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:10:00 huiskamer sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 18:10:00 huiskamer sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:00 huiskamer sudo[950]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:00 huiskamer sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 18:10:00 huiskamer sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:00 huiskamer volumio[709]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 14 18:10:00 huiskamer volumio[709]: info: Completed loading Core Plugins Dec 14 18:10:00 huiskamer volumio[709]: info: Preparing to generate the ALSA configuration file Dec 14 18:10:00 huiskamer sudo[953]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:00 huiskamer volumio[709]: info: Volumio Network Manager: Network status updated: 1 Dec 14 18:10:00 huiskamer volumio[709]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 14 18:10:00 huiskamer volumio[709]: wlan0 Interface doesn't support scanning. Dec 14 18:10:00 huiskamer volumio[709]: info: Cannot use regular scanning, forcing with ap-force Dec 14 18:10:00 huiskamer sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 14 18:10:00 huiskamer sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:00 huiskamer sudo[958]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:00 huiskamer volumio[709]: command failed: No such device (-19) Dec 14 18:10:00 huiskamer volumio[709]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 14 18:10:00 huiskamer volumio[709]: command failed: No such device (-19) Dec 14 18:10:00 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Dec 14 18:10:00 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Dec 14 18:10:00 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 14 18:10:00 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Dec 14 18:10:00 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 14 18:10:00 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 14 18:10:00 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:00 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:00 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:00 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:00 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:00 huiskamer volumio[709]: info: Reloading queue from file Dec 14 18:10:00 huiskamer volumio[709]: info: Asound.conf file unchanged, so no further update is needed Dec 14 18:10:00 huiskamer volumio[709]: info: Output device has changed, restarting MPD Dec 14 18:10:00 huiskamer sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:10:00 huiskamer sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:00 huiskamer sudo[968]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:00 huiskamer volumio[709]: info: ___________ START PLUGINS ___________ Dec 14 18:10:00 huiskamer volumio[709]: info: ControllerMpd::onStart: Initializing MPD Dec 14 18:10:00 huiskamer volumio[709]: info: Creating MPD Configuration file Dec 14 18:10:00 huiskamer sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:10:00 huiskamer sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:00 huiskamer systemd[1]: Listening on mpd.socket. Dec 14 18:10:00 huiskamer sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:10:00 huiskamer sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:00 huiskamer sudo[974]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 18:10:00 huiskamer systemd[1]: Starting Music Player Daemon... Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 18:10:00 huiskamer volumio[709]: info: [1765732200795] CoreMusicLibrary::Adding element Media Servers Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:00 huiskamer sudo[977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:10:00 huiskamer sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:00 huiskamer volumio[709]: info: Adding METAVOLUMIO REST API Endpoints Dec 14 18:10:00 huiskamer volumio[709]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 14 18:10:00 huiskamer volumio[709]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 14 18:10:00 huiskamer volumio[709]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 14 18:10:00 huiskamer volumio[709]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 18:10:00 huiskamer volumio[709]: info: [1765732200879] CoreMusicLibrary::Adding element Last_100 Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:00 huiskamer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 14 18:10:00 huiskamer systemd[1]: mpd.service: Succeeded. Dec 14 18:10:00 huiskamer volumio[709]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 14 18:10:00 huiskamer systemd[1]: Stopped Music Player Daemon. Dec 14 18:10:00 huiskamer systemd[1]: Starting Music Player Daemon... Dec 14 18:10:00 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:10:00 huiskamer volumio[709]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 14 18:10:00 huiskamer volumio[709]: info: Streaming services startup Dec 14 18:10:00 huiskamer volumio[709]: info: Starting Streaming Daemon Dec 14 18:10:00 huiskamer sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:10:00 huiskamer sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:01 huiskamer sudo[999]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:01 huiskamer sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 14 18:10:01 huiskamer sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:01 huiskamer sudo[1002]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:01 huiskamer sudo[994]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:10:01 huiskamer sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:01 huiskamer sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:10:01 huiskamer sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:01 huiskamer sudo[994]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 14 18:10:01 huiskamer sudo[994]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 18:10:01 huiskamer volumio[709]: info: [1765732201080] CoreMusicLibrary::Adding element Webradio Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 18:10:01 huiskamer volumio[709]: info: Initializing BBC Radios Dec 14 18:10:01 huiskamer systemd[1]: Started Volumio Qobuz Connect Service. Dec 14 18:10:01 huiskamer sudo[1003]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:10:01 huiskamer volumio[709]: info: Creating Spotify config file Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:01 huiskamer volumio[709]: error: Hi Res Audio Failed Login: Missing Login Data Dec 14 18:10:01 huiskamer volumio[709]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 14 18:10:01 huiskamer volumio[709]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 14 18:10:01 huiskamer volumio[709]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:10:01 huiskamer volumio[709]: info: Volumio Calling Home Dec 14 18:10:01 huiskamer volumio[709]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 14 18:10:01 huiskamer volumio[709]: info: Stopping AccessToken refresher cron for QOBUZ Dec 14 18:10:01 huiskamer volumio[709]: info: AccessToken refresher cron started for QOBUZ Dec 14 18:10:01 huiskamer volumio[709]: info: Adding TIDAL REST API Endpoints Dec 14 18:10:01 huiskamer volumio[709]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 14 18:10:01 huiskamer volumio[709]: info: Adding QOBUZ REST API Endpoints Dec 14 18:10:01 huiskamer volumio[709]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 14 18:10:01 huiskamer volumio[709]: info: Setting Device type: NanoPi NEO 2 Dec 14 18:10:01 huiskamer volumio[709]: info: CoreStateMachine::setRepeat true single undefined Dec 14 18:10:01 huiskamer volumio[709]: info: CoreStateMachine::pushState Dec 14 18:10:01 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::volumioPushState Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:01 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:01 huiskamer volumio[709]: info: CoreStateMachine::setRandom false Dec 14 18:10:01 huiskamer volumio[709]: info: CoreStateMachine::pushState Dec 14 18:10:01 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::volumioPushState Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:01 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:01 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:01 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:01 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:01 huiskamer volumio[709]: info: Serial port opened successfully Dec 14 18:10:01 huiskamer volumio[709]: error: Cannot start Volumio Streaming Daemon Dec 14 18:10:01 huiskamer volumio[709]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 14 18:10:01 huiskamer volumio[709]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 14 18:10:01 huiskamer volumio[709]: info: MPD Permissions set Dec 14 18:10:01 huiskamer volumio[709]: info: MPD Permissions set Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:02 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:02 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:02 huiskamer sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 14 18:10:02 huiskamer sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:02 huiskamer systemd[1]: Started UPnP Renderer front-end to MPD. Dec 14 18:10:02 huiskamer sudo[1040]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:02 huiskamer volumio[709]: info: Executing endpoint qc_getconfig Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.203 [1012.1012] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 18:10:02 huiskamer volumio[709]: info: Spotify config file written Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:02 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:02 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:02 huiskamer volumio[709]: info: Discovery: adding 6c4ab933-bafe-4db9-8f78-2dc5f0614886 Dec 14 18:10:02 huiskamer volumio[709]: info: Discovery: Found device huiskamer Dec 14 18:10:02 huiskamer sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:02 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:02 huiskamer sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:02 huiskamer systemd[1]: Started go-librespot Daemon. Dec 14 18:10:02 huiskamer sudo[1046]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:02 huiskamer go-librespot[1048]: go-librespot daemon starting... Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 18:10:02 huiskamer volumio[709]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 14 18:10:02 huiskamer volumio[709]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 18:10:02 huiskamer volumio[709]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 18:10:02 huiskamer volumio[709]: info: Upmpdcli Daemon Started Dec 14 18:10:02 huiskamer volumio[709]: info: Volumio called home Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.496 [1012.1012] INFO VolumeManager: [0x2b1d9e0]: Setting new playback volume: 75 Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.496 [1012.1012] INFO VolumeManager: [0x2b1d9e0]: Setting new mute state: 0 Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.496 [1012.1012] INFO QobuzConnect: [0x2b1e3b0]: Client initialized! Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.496 [1012.1012] INFO SampleApp: Starting Avahi advertising, name: huiskamer, service name: _qobuz-connect._tcp Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.506 [1012.1012] INFO LocalConfigManager: [0x2b1d2c0]: Starting Local Configuration server Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.506 [1012.1012] INFO SampleApp: Starting Local configuration server Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.507 [1012.1012] INFO SampleApp: Playback volume changed: 75 Dec 14 18:10:02 huiskamer qobuz-connect[1012]: 20251214 18:10:02.507 [1012.1012] INFO SampleApp: Connected to UNIX socket client 0x2b12ed8 Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: No need to fix Spotify hosts Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Dec 14 18:10:02 huiskamer volumio[709]: info: Adding MINIDSP Inputs Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 18:10:02 huiskamer volumio[709]: info: [1765732202624] CoreMusicLibrary::Adding element Inputs Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 18:10:02 huiskamer volumio[709]: info: [1765732202636] CoreMusicLibrary::Adding element Presets Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:02 huiskamer volumio[709]: Cannot find translation for source Presets Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 14 18:10:02 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:02 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:02 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 14 18:10:02 huiskamer volumio[709]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 18:10:02 huiskamer volumio[709]: SPOTIFY: BQAYWMLl68lJKHo3NSrrsVVRoNBhJMG7xzcwcULY62RlrUWG_g_XZgfk8KqBCAvwQe4cIH4fFSetYQMLwXSv1mhc5c9nxm3ADT0kf_8lwjdVagkrGuJBPgV8ndPxrn26zL7vdo-N79-pSqkV46JTWEB-hFd7xvCRcpvxFR2ugnJqAg-6Ox74U0S6_UQnRuEe9BgvRqKHquELeDg-S5A7wTdaZS9Ttjuhts5-7-nr-Md6d0vuqSG3l7v1amuxFucF_2WeNkHej6Kj4-Nj6TW9sG7N2CTNeqeZXgf3nAUgGmi6H_7qp3znZ4Q Dec 14 18:10:02 huiskamer volumio[709]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 18:10:02 huiskamer volumio[709]: info: New Spotify access token = BQAYWMLl68lJKHo3NSrrsVVRoNBhJMG7xzcwcULY62RlrUWG_g_XZgfk8KqBCAvwQe4cIH4fFSetYQMLwXSv1mhc5c9nxm3ADT0kf_8lwjdVagkrGuJBPgV8ndPxrn26zL7vdo-N79-pSqkV46JTWEB-hFd7xvCRcpvxFR2ugnJqAg-6Ox74U0S6_UQnRuEe9BgvRqKHquELeDg-S5A7wTdaZS9Ttjuhts5-7-nr-Md6d0vuqSG3l7v1amuxFucF_2WeNkHej6Kj4-Nj6TW9sG7N2CTNeqeZXgf3nAUgGmi6H_7qp3znZ4Q Dec 14 18:10:02 huiskamer volumio[709]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 18:10:03 huiskamer sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=debug msg="app state loaded" Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 18:10:03 huiskamer sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:03 huiskamer sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 18:10:03 huiskamer sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:03 huiskamer sudo[1070]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:03 huiskamer sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 18:10:03 huiskamer sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 18:10:03 huiskamer sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 18:10:03 huiskamer sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 18:10:03 huiskamer sudo[1096]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:03 huiskamer sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 18:10:03 huiskamer sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:03 huiskamer sudo[1075]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:03 huiskamer sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 18:10:03 huiskamer sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:03 huiskamer sudo[1082]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:03 huiskamer volumio-remote-updater[485]: [2025-12-14 18:10:03] [connect] Successful connection Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:03 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:03 huiskamer sudo[1100]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:03 huiskamer sudo[1087]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::volumiosetSourceActiveno-source Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:03 huiskamer volumio[709]: Cannot find translation for source Presets Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:03 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:03 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 18:10:03 huiskamer volumio-remote-updater[485]: [2025-12-14 18:10:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765732203 101 Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=info msg="zeroconf server listening on port 46859" Dec 14 18:10:03 huiskamer volumio[709]: 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: 9 Dec 14 18:10:03 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:03 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:03 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:03 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:03 huiskamer volumio[709]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=debug msg="obtained new client token: AAAa4pPdzBRlOBXP+tZMtTDAuwB59AkqKzJ9QMBhMDAZKtxwsLsz1XP8yv8LFYwgUj6Egm0GqWEbp1adv0ZzHpHCBERKjG/+f8Ol1LWs3eoKrNTNyHHlZPO5xf9mjrG5EeJ+d5XJYheFczMLQELKSiEApWNMT1BCMpF4uCEz2IuoXiC1obaeHl8f48LzGo72CW0aYZEUlfpMNXhjM63HJJfXO7YxXHQP74i6qTzB/Hh/7r34D1NV+CNb/Q==" Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 18:10:03 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 14 18:10:03 huiskamer go-librespot[1048]: time="2025-12-14T18:10:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 14 18:10:03 huiskamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 18:10:03 huiskamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 18:10:03 huiskamer sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 18:10:03 huiskamer sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:03 huiskamer sudo[1118]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:03 huiskamer sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 18:10:03 huiskamer sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:03 huiskamer sudo[1120]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:04 huiskamer volumio[709]: SPOTIFY: User informations: {"country":"NL","display_name":"ronaldwanink","email":"wanink@xs4all.nl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/ronaldwanink"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/ronaldwanink","id":"ronaldwanink","images":[],"product":"premium","type":"user","uri":"spotify:user:ronaldwanink"} Dec 14 18:10:04 huiskamer volumio[709]: info: Spotify Successfully logged in Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 18:10:04 huiskamer volumio[709]: info: [1765732204025] CoreMusicLibrary::Adding element Spotify Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:04 huiskamer volumio[709]: Cannot find translation for source Presets Dec 14 18:10:04 huiskamer volumio[709]: Cannot find translation for source Spotify Dec 14 18:10:04 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.38 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 14 18:10:04 huiskamer volumio[709]: info: Enabling external Volume Control Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:10:04 huiskamer sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 18:10:04 huiskamer sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:04 huiskamer sudo[1126]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:04 huiskamer sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 18:10:04 huiskamer sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:04 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:04 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:04 huiskamer sudo[1129]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:04 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.38 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 18:10:04 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.38 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:04 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 14 18:10:04 huiskamer volumio[709]: info: Received Get System Info Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:04 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:04 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:04 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:04 huiskamer volumio[709]: info: Listing playlists Dec 14 18:10:04 huiskamer sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 18:10:04 huiskamer sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:04 huiskamer sudo[1134]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 18:10:04 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 18:10:04 huiskamer sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 18:10:04 huiskamer sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:04 huiskamer sudo[1137]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:04 huiskamer volumio[709]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.38 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:05 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 14 18:10:05 huiskamer volumio[709]: info: Received Get System Info Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:05 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:05 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:05 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:05 huiskamer volumio[709]: info: Listing playlists Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 18:10:05 huiskamer sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 18:10:05 huiskamer sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:05 huiskamer sudo[1142]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:05 huiskamer volumio[709]: info: CoreStateMachine::pushState Dec 14 18:10:05 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::volumioPushState Dec 14 18:10:05 huiskamer sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 18:10:05 huiskamer sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:05 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:05 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:05 huiskamer sudo[1145]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:05 huiskamer mpd[1014]: Dec 14 18:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 18:10:05 huiskamer volumio[709]: info: go-librespot daemon successfully initialized Dec 14 18:10:05 huiskamer systemd[1]: Started Music Player Daemon. Dec 14 18:10:05 huiskamer volumio[709]: Upnp client error: Error: This socket has been ended by the other party Dec 14 18:10:05 huiskamer sudo[971]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:05 huiskamer sudo[977]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:05 huiskamer volumio[709]: info: Completed starting Core Plugins Dec 14 18:10:05 huiskamer volumio[709]: info: ------------------------------------------- Dec 14 18:10:05 huiskamer volumio[709]: info: ----- MyVolumio plugins startup ---- Dec 14 18:10:05 huiskamer volumio[709]: info: ------------------------------------------- Dec 14 18:10:05 huiskamer volumio[709]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 14 18:10:05 huiskamer volumio[709]: info: MPD running with PID1014 Dec 14 18:10:05 huiskamer volumio[709]: ,establishing connection Dec 14 18:10:06 huiskamer volumio[709]: error: MPD error: The expression evaluated to a falsy value: Dec 14 18:10:06 huiskamer volumio[709]: assert.ok(self.idling) Dec 14 18:10:06 huiskamer volumio[709]: error: The expression evaluated to a falsy value: Dec 14 18:10:06 huiskamer volumio[709]: assert.ok(self.idling) Dec 14 18:10:06 huiskamer volumio[709]: error: MPD error: The expression evaluated to a falsy value: Dec 14 18:10:06 huiskamer volumio[709]: assert.ok(self.idling) Dec 14 18:10:06 huiskamer volumio[709]: error: The expression evaluated to a falsy value: Dec 14 18:10:06 huiskamer volumio[709]: assert.ok(self.idling) Dec 14 18:10:06 huiskamer volumio[709]: error: updateQueue error: null Dec 14 18:10:06 huiskamer volumio[1042]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 14 18:10:06 huiskamer volumio[709]: info: CoreStateMachine::pushState Dec 14 18:10:06 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::volumioPushState Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:06 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 14 18:10:06 huiskamer volumio[1042]: ....++++ Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 18:10:06 huiskamer volumio[709]: info: Received Get System Info Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:06 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:06 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:06 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:06 huiskamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 18:10:06 huiskamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 14 18:10:06 huiskamer systemd[1]: Stopped go-librespot Daemon. Dec 14 18:10:06 huiskamer systemd[1]: Started go-librespot Daemon. Dec 14 18:10:06 huiskamer go-librespot[1168]: go-librespot daemon starting... Dec 14 18:10:06 huiskamer go-librespot[1168]: time="2025-12-14T18:10:06+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 18:10:06 huiskamer go-librespot[1168]: time="2025-12-14T18:10:06+01:00" level=debug msg="app state loaded" Dec 14 18:10:06 huiskamer go-librespot[1168]: time="2025-12-14T18:10:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 18:10:07 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=info msg="zeroconf server listening on port 45937" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="obtained new client token: AACuaIMKWh0Dp3MUYLTRAoYo1ZdkKU4X/qZ2vB38sUUTcbvVlNc+ZPd9xTev7sZ4a248Z6uiXd0dWEVN3Ft2jUTfYdcxMy+5US2PzHn65vJtpV0zNzcyhni6qsyCv1zVBJf8/2wKf73yZ7Tw/LVHg4c0OU72XKlKl2tZdPwtAi8dwGNjVkwx8eF4TxzAsNfggdsqwOqIFxJAGkuPJlueS8+HvnkPbOxRQ+VZtuyahM4Ls8qWln7ZUX8=" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="completed keyexchange" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="completed challenge" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=info msg="authenticated AP" username="ro********nk" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=info msg="authenticated Login5" username="ro********nk" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="initializing zeroconf session" username="ro********nk" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="dealer connection opened" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=trace msg="starting accesspoint recv loop" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=trace msg="starting dealer recv loop" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=trace msg="received accesspoint ping" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="received connection id: MTJmZTNiYzItZWZk...OTcxQTI1RTE2OA==" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=trace msg="received accesspoint pong ack" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="update volume requested to 42597/65535" Dec 14 18:10:07 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 18:10:07 huiskamer volumio[709]: info: Received Get System Info Dec 14 18:10:07 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:10:07 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:10:07 huiskamer volumio[709]: info: Discovery: Getting this device information Dec 14 18:10:07 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:07 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:07 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 14 18:10:07 huiskamer go-librespot[1168]: time="2025-12-14T18:10:07+01:00" level=trace msg="emitting websocket event: volume" Dec 14 18:10:08 huiskamer volumio[709]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 14 18:10:08 huiskamer volumio[1042]: .....................++++ Dec 14 18:10:08 huiskamer volumio[1042]: e is 65537 (0x010001) Dec 14 18:10:08 huiskamer volumio[1042]: writing RSA key Dec 14 18:10:08 huiskamer volumio[709]: info: Initializing connection to go-librespot Websocket Dec 14 18:10:08 huiskamer go-librespot[1168]: time="2025-12-14T18:10:08+01:00" level=debug msg="new websocket client" Dec 14 18:10:08 huiskamer volumio[709]: info: Connection to go-librespot Websocket established Dec 14 18:10:08 huiskamer sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:10:08 huiskamer sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:09 huiskamer sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:10:09 huiskamer sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="handling transfer player command from 99b9b325030d4961b522b07f350239382723c31b" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="resolved context of track" uri="spotify:artist:0c103ZyWDycpfVxR0lNrjm" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=trace msg="fetched new page 0 with 45 items (list: 45)" uri="spotify:artist:0c103ZyWDycpfVxR0lNrjm" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="loading track (paused: true, position: 48347ms)" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer systemd[1]: Started MPD Monitor Service. Dec 14 18:10:09 huiskamer sudo[1184]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:09 huiskamer volumio[709]: info: Successfully started MPD Monitor Dec 14 18:10:09 huiskamer mpd_monitor.sh[1194]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:10:09 huiskamer systemd[1]: Stopping MPD Monitor Service... Dec 14 18:10:09 huiskamer systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 14 18:10:09 huiskamer systemd[1]: mpd_monitor.service: Succeeded. Dec 14 18:10:09 huiskamer systemd[1]: Stopped MPD Monitor Service. Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=trace msg="emitting websocket event: will_play" Dec 14 18:10:09 huiskamer systemd[1]: Started MPD Monitor Service. Dec 14 18:10:09 huiskamer sudo[1191]: pam_unix(sudo:session): session closed for user root Dec 14 18:10:09 huiskamer volumio[709]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:artist:0c103ZyWDycpfVxR0lNrjm","uri":"spotify:track:1Ap0M27xnNAHupJJrSUepi","play_origin":"com.spotify.gaia"}} Dec 14 18:10:09 huiskamer mpd_monitor.sh[1196]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:10:09 huiskamer volumio[709]: info: Successfully started MPD Monitor Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="selected format OGG_VORBIS_320 (46b718286b7df2f4c12e395653e343667a036428)" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="requested aes key for file 46b718286b7df2f4c12e395653e343667a036428, gid: 1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1350" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="fetched first chunk of 11, total size is 5330792 bytes" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="fetched chunk 2/10, size: 524288" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=trace msg="seek to 48347ms (diff: 139ms, samples: 2132102, bytes: 1506670)" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="created new output device" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=info msg="loaded track \"Singin' In Chains\" (paused: true, position: 48347ms, duration: 168506ms, prefetched: false)" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1120" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=trace msg="emitting websocket event: metadata" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=trace msg="emitting websocket event: active" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="sending successful reply for dealer request" Dec 14 18:10:09 huiskamer volumio[709]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1Ap0M27xnNAHupJJrSUepi","name":"Singin' In Chains","artist_names":["MY BABY"],"album_name":"Loves Voodoo!","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026418213dd55274721c31a45c","position":48347,"duration":168506,"release_date":"year:2013 month:11 day:13","track_number":6,"disc_number":1}} Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 14 18:10:09 huiskamer volumio[709]: SPOTIFY: received: {"type":"active","data":null} Dec 14 18:10:09 huiskamer volumio[709]: info: Aligning Spotify Volume to Volumio Volume Dec 14 18:10:09 huiskamer volumio[709]: info: CoreCommandRouter::volumioGetState Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 14 18:10:09 huiskamer volumio[709]: info: CorePlayQueue::getTrack 0 Dec 14 18:10:09 huiskamer volumio[709]: info: Setting Spotify Volume from Volumio: 65 Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="fetched chunk 1/10, size: 524288" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="fetched chunk 5/10, size: 524288" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="fetched chunk 3/10, size: 524288" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=trace msg="emitting websocket event: paused" Dec 14 18:10:09 huiskamer volumio[709]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:artist:0c103ZyWDycpfVxR0lNrjm","uri":"spotify:track:1Ap0M27xnNAHupJJrSUepi","play_origin":"com.spotify.gaia"}} Dec 14 18:10:09 huiskamer volumio[709]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 18:10:09 huiskamer go-librespot[1168]: time="2025-12-14T18:10:09+01:00" level=debug msg="fetched chunk 4/10, size: 524288" uri="spotify:track:1Ap0M27xnNAHupJJrSUepi" Dec 14 18:10:09 huiskamer volumio[709]: TypeError: Cannot read property 'service' of undefined Dec 14 18:10:09 huiskamer volumio[709]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 14 18:10:09 huiskamer volumio[709]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Dec 14 18:10:09 huiskamer volumio[709]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 14 18:10:09 huiskamer volumio[709]: at WebSocket.emit (events.js:400:28) Dec 14 18:10:09 huiskamer volumio[709]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 14 18:10:09 huiskamer volumio[709]: at Receiver.emit (events.js:400:28) Dec 14 18:10:09 huiskamer volumio[709]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 14 18:10:09 huiskamer volumio[709]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 14 18:10:09 huiskamer volumio[709]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 14 18:10:09 huiskamer volumio[709]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 14 18:10:09 huiskamer volumio[709]: at writeOrBuffer (internal/streams/writable.js:358:12) Dec 14 18:10:09 huiskamer volumio[709]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Dec 14 18:10:09 huiskamer volumio[709]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Dec 14 18:10:09 huiskamer volumio[709]: at Socket.emit (events.js:400:28) Dec 14 18:10:09 huiskamer volumio[709]: at addChunk (internal/streams/readable.js:293:12) Dec 14 18:10:09 huiskamer volumio[709]: at readableAddChunk (internal/streams/readable.js:267:9) Dec 14 18:10:09 huiskamer volumio[709]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 18:10:10 huiskamer sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-14 18:09 Dec 14 18:10:10 huiskamer sudo[1210]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 18 Nov 2025 04:14:14 PM CET" VOLUMIO_VERSION="3.877" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="57978c68a391dbf6badbe915e5672a72"