-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sat 2025-12-27 12:29:30 EST. -- Dec 27 12:27:59 kitchen systemd[1]: Started triggerhappy global hotkey daemon. Dec 27 12:28:00 kitchen systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 27 12:28:00 kitchen systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 27 12:28:00 kitchen systemd[1]: Failed to start dhcpcd on all interfaces. Dec 27 12:28:00 kitchen systemd[1]: Started Save/Restore Sound Card State. Dec 27 12:28:00 kitchen systemd[1]: Reached target Sound Card. Dec 27 12:28:00 kitchen kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 27 12:28:00 kitchen systemd-logind[534]: New seat seat0. Dec 27 12:28:00 kitchen avahi-daemon[501]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 27 12:28:00 kitchen avahi-daemon[501]: Successfully dropped root privileges. Dec 27 12:28:00 kitchen avahi-daemon[501]: avahi-daemon 0.7 starting up. Dec 27 12:28:01 kitchen dbus-daemon[523]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 27 12:28:01 kitchen systemd[1]: Started Login Service. Dec 27 12:28:01 kitchen avahi-daemon[501]: Successfully called chroot(). Dec 27 12:28:01 kitchen avahi-daemon[501]: Successfully dropped remaining capabilities. Dec 27 12:28:01 kitchen systemd[1]: hciuart.service: Succeeded. Dec 27 12:28:01 kitchen systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 27 12:28:01 kitchen wpa_supplicant[533]: Successfully initialized wpa_supplicant Dec 27 12:28:01 kitchen avahi-daemon[501]: Loading service file /services/volumio.service. Dec 27 12:28:01 kitchen avahi-daemon[501]: Network interface enumeration completed. Dec 27 12:28:01 kitchen avahi-daemon[501]: Server startup complete. Host name is kitchen.local. Local service cookie is 290886669. Dec 27 12:28:01 kitchen avahi-daemon[501]: Service "Kitchen" (/services/volumio.service) successfully established. Dec 27 12:28:01 kitchen systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 27 12:28:01 kitchen systemd[1]: Started WPA supplicant. Dec 27 12:28:01 kitchen sh[478]: wlan0=wlan0 Dec 27 12:28:01 kitchen systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 27 12:28:01 kitchen volumio-remote-updater[504]: Error: No active session Dec 27 12:28:01 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:01] [info] asio async_connect error: system:111 (Connection refused) Dec 27 12:28:01 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 27 12:28:01 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:01] [error] handle_connect error: Underlying Transport Error Dec 27 12:28:01 kitchen bthelper[596]: Can't init device hci0: Operation not possible due to RF-kill (132) Dec 27 12:28:01 kitchen systemd[1]: bthelper@hci0.service: Main process exited, code=exited, status=1/FAILURE Dec 27 12:28:01 kitchen systemd[1]: bthelper@hci0.service: Failed with result 'exit-code'. Dec 27 12:28:01 kitchen systemd[1]: Failed to start Raspberry Pi bluetooth helper. Dec 27 12:28:01 kitchen volumio[495]: Could not open config: /tmp/upmpdcli.conf Dec 27 12:28:01 kitchen systemd[1]: Starting Bluetooth service... Dec 27 12:28:01 kitchen systemd[1]: Started Raise network interfaces. Dec 27 12:28:01 kitchen systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 27 12:28:01 kitchen systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 27 12:28:01 kitchen systemd[1]: Reached target Network. Dec 27 12:28:01 kitchen volumio-time-update[513]: volumio-time-update-util: Date not found in response Dec 27 12:28:01 kitchen volumio-time-update[513]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 12:28:01 kitchen systemd[1]: Starting Network Time Service... Dec 27 12:28:01 kitchen systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 27 12:28:01 kitchen systemd[1]: Starting OpenBSD Secure Shell server... Dec 27 12:28:01 kitchen systemd[1]: Reached target Network is Online. Dec 27 12:28:01 kitchen systemd[1]: Starting Daily apt download activities... Dec 27 12:28:01 kitchen systemd[1]: Starting Samba NMB Daemon... Dec 27 12:28:01 kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 12:28:01 kitchen systemd[1]: Starting /etc/rc.local Compatibility... Dec 27 12:28:01 kitchen systemd[1]: Mounting /mnt/backup... Dec 27 12:28:02 kitchen systemd[1]: Started /etc/rc.local Compatibility. Dec 27 12:28:02 kitchen systemd[1]: iptables.service: Succeeded. Dec 27 12:28:02 kitchen bluetoothd[619]: Bluetooth daemon 5.83 Dec 27 12:28:02 kitchen mount[629]: mount.nfs: Failed to resolve server excelsior.dm.grove.lan: System error Dec 27 12:28:02 kitchen bluetoothd[619]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 27 12:28:02 kitchen systemd[1]: mnt-backup.mount: Mount process exited, code=exited, status=32/n/a Dec 27 12:28:02 kitchen systemd[1]: mnt-backup.mount: Failed with result 'exit-code'. Dec 27 12:28:02 kitchen systemd[1]: Failed to mount /mnt/backup. Dec 27 12:28:02 kitchen systemd[1]: Dependency failed for Remote File Systems. Dec 27 12:28:02 kitchen systemd[1]: remote-fs.target: Job remote-fs.target/start failed with result 'dependency'. Dec 27 12:28:02 kitchen bluetoothd[619]: Starting SDP server Dec 27 12:28:02 kitchen bluetoothd[619]: Excluding (cli) hostname Dec 27 12:28:02 kitchen bluetoothd[619]: Excluding (cli) scanparam Dec 27 12:28:02 kitchen bluetoothd[619]: Excluding (cli) battery Dec 27 12:28:02 kitchen systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 27 12:28:02 kitchen systemd[1]: Starting LSB: Brings up/down network automatically... Dec 27 12:28:02 kitchen systemd[1]: Starting Permit User Sessions... Dec 27 12:28:02 kitchen systemd[1]: Started Bluetooth service. Dec 27 12:28:02 kitchen systemd[1]: Reached target Bluetooth. Dec 27 12:28:02 kitchen systemd[1]: Started Permit User Sessions. Dec 27 12:28:02 kitchen ntpd[620]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 27 12:28:02 kitchen ntpd[620]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 27 12:28:02 kitchen kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 27 12:28:02 kitchen kernel: Bluetooth: BNEP filters: protocol multicast Dec 27 12:28:02 kitchen kernel: Bluetooth: BNEP socket layer initialized Dec 27 12:28:02 kitchen ntpd[647]: proto: precision = 1.458 usec (-19) Dec 27 12:28:02 kitchen bluetoothd[619]: Bluetooth management interface 1.22 initialized Dec 27 12:28:02 kitchen bluetoothd[619]: Battery Provider Manager created Dec 27 12:28:02 kitchen kernel: Bluetooth: MGMT ver 1.22 Dec 27 12:28:02 kitchen haveged[470]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 27 12:28:02 kitchen haveged[470]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 27 12:28:02 kitchen haveged[470]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00213 Dec 27 12:28:02 kitchen haveged[470]: haveged: fills: 0, generated: 0 Dec 27 12:28:02 kitchen systemd[1]: Started Network Time Service. Dec 27 12:28:02 kitchen ntpd[647]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 27 12:28:02 kitchen ntpd[647]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 27 12:28:02 kitchen ntpd[647]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 365 days ago Dec 27 12:28:02 kitchen ntpd[647]: Listen and drop on 0 v6wildcard [::]:123 Dec 27 12:28:02 kitchen ntpd[647]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 27 12:28:02 kitchen ntpd[647]: Listen normally on 2 lo 127.0.0.1:123 Dec 27 12:28:02 kitchen ntpd[647]: Listening on routing socket on fd #19 for interface updates Dec 27 12:28:02 kitchen ntpd[647]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 27 12:28:02 kitchen ntpd[647]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 27 12:28:02 kitchen kernel: NET: Registered PF_ALG protocol family Dec 27 12:28:02 kitchen sshd[650]: Server listening on 0.0.0.0 port 22. Dec 27 12:28:02 kitchen sshd[650]: Server listening on :: port 22. Dec 27 12:28:02 kitchen systemd[1]: Started OpenBSD Secure Shell server. Dec 27 12:28:02 kitchen kernel: cryptd: max_cpu_qlen set to 1000 Dec 27 12:28:02 kitchen systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 27 12:28:02 kitchen systemd[1]: Starting Hold until boot process finishes up... Dec 27 12:28:02 kitchen systemd[1]: Received SIGRTMIN+21 from PID 176 (plymouthd). Dec 27 12:28:03 kitchen ifplugd[639]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Dec 27 12:28:03 kitchen ifplugd[639]: skip eth0...done. Dec 27 12:28:03 kitchen systemd[1]: plymouth-quit.service: Succeeded. Dec 27 12:28:03 kitchen systemd[1]: Started Terminate Plymouth Boot Screen. Dec 27 12:28:03 kitchen loadcpufreq[637]: Loading cpufreq kernel modules...error: unexpectedly disconnected from boot status daemon Dec 27 12:28:03 kitchen systemd[1]: Started LSB: Brings up/down network automatically. Dec 27 12:28:03 kitchen systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 27 12:28:03 kitchen systemd[1]: Started Hold until boot process finishes up. Dec 27 12:28:03 kitchen systemd[1]: Received SIGRTMIN+21 from PID 176 (plymouthd). Dec 27 12:28:03 kitchen systemd[1]: Started Getty on tty1. Dec 27 12:28:03 kitchen systemd[1]: Reached target Login Prompts. Dec 27 12:28:03 kitchen bluetoothd[619]: Failed to set mode: Failed (0x03) Dec 27 12:28:03 kitchen loadcpufreq[637]: done (none). Dec 27 12:28:03 kitchen systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 27 12:28:03 kitchen systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 27 12:28:03 kitchen ntpd[647]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 27 12:28:03 kitchen cpufrequtils[708]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 27 12:28:03 kitchen systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 27 12:28:04 kitchen systemd[1]: systemd-rfkill.service: Succeeded. Dec 27 12:28:04 kitchen nmbd[633]: [2025/12/27 12:28:04.453380, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 27 12:28:04 kitchen nmbd[633]: started asyncdns process 745 Dec 27 12:28:04 kitchen nmbd[633]: [2025/12/27 12:28:04.457108, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 27 12:28:04 kitchen nmbd[633]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 27 12:28:04 kitchen nmbd[633]: [2025/12/27 12:28:04.457442, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 27 12:28:04 kitchen nmbd[633]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 27 12:28:04 kitchen ntpd[647]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 27 12:28:04 kitchen systemd[1]: apt-daily.service: Succeeded. Dec 27 12:28:04 kitchen systemd[1]: Started Daily apt download activities. Dec 27 12:28:05 kitchen wireless.js[498]: Cleaning previous... Dec 27 12:28:05 kitchen sudo[770]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 27 12:28:05 kitchen sudo[770]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:05 kitchen sudo[770]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:05 kitchen sudo[772]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 27 12:28:05 kitchen sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:05 kitchen ntpd[647]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 27 12:28:05 kitchen sudo[772]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:05 kitchen wireless.js[498]: Stopped aP Dec 27 12:28:05 kitchen sudo[779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 27 12:28:05 kitchen sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:06 kitchen sudo[779]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:06 kitchen kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 27 12:28:06 kitchen sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 27 12:28:06 kitchen sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:06 kitchen sudo[781]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:06 kitchen sudo[788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 27 12:28:06 kitchen sudo[788]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:06 kitchen sudo[788]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:06 kitchen sudo[790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 27 12:28:06 kitchen sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:06 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:06] [info] asio async_connect error: system:111 (Connection refused) Dec 27 12:28:06 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 27 12:28:06 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:06] [error] handle_connect error: Underlying Transport Error Dec 27 12:28:06 kitchen ntpd[647]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 27 12:28:06 kitchen volumio-time-update[513]: volumio-time-update-util: Fetching time from Volumio... Dec 27 12:28:06 kitchen volumio-time-update[513]: volumio-time-update-util: Date not found in response Dec 27 12:28:06 kitchen volumio-time-update[513]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 12:28:08 kitchen sudo[790]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:08 kitchen wireless.js[498]: SETTING APPROPRIATE REG DOMAIN: US Dec 27 12:28:08 kitchen sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 27 12:28:08 kitchen sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:08 kitchen sudo[800]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:08 kitchen sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 27 12:28:08 kitchen sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:08 kitchen sudo[802]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:08 kitchen wireless.js[498]: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 27 12:28:08 kitchen wireless.js[498]: Start wireless flow Dec 27 12:28:08 kitchen wireless.js[498]: Stopped hotspot (if there).. Dec 27 12:28:09 kitchen sudo[807]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 27 12:28:09 kitchen sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:09 kitchen sudo[807]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:09 kitchen sudo[809]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 27 12:28:09 kitchen sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:09 kitchen sudo[809]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:09 kitchen wireless.js[498]: DHCP IP Dec 27 12:28:09 kitchen wireless.js[498]: Start ap Dec 27 12:28:09 kitchen wpa_supplicant[811]: Successfully initialized wpa_supplicant Dec 27 12:28:09 kitchen sudo[812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 27 12:28:09 kitchen sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:09 kitchen kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 27 12:28:09 kitchen dhcpcd[813]: dev: loaded udev Dec 27 12:28:09 kitchen kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 27 12:28:09 kitchen dhcpcd[813]: wlan0: connected to Access Point `' Dec 27 12:28:09 kitchen dhcpcd[813]: no interfaces have a carrier Dec 27 12:28:09 kitchen dhcpcd[813]: forked to background, child pid 838 Dec 27 12:28:09 kitchen dhcpcd[838]: wlan0: waiting for carrier Dec 27 12:28:09 kitchen sudo[812]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:10 kitchen wireless.js[498]: trying... Dec 27 12:28:10 kitchen sudo[840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:10 kitchen sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:10 kitchen sudo[840]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:11 kitchen wireless.js[498]: trying... Dec 27 12:28:11 kitchen sudo[843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:11 kitchen sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:11 kitchen sudo[843]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:11 kitchen volumio-time-update[513]: volumio-time-update-util: Fetching time from Volumio... Dec 27 12:28:11 kitchen volumio-time-update[513]: volumio-time-update-util: Date not found in response Dec 27 12:28:11 kitchen volumio-time-update[513]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 12:28:12 kitchen wireless.js[498]: trying... Dec 27 12:28:12 kitchen sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:12 kitchen sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:12 kitchen sudo[851]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:13 kitchen wireless.js[498]: trying... Dec 27 12:28:13 kitchen sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:13 kitchen sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:13 kitchen sudo[854]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:13 kitchen kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 27 12:28:13 kitchen kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 27 12:28:13 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:13] [info] asio async_connect error: system:111 (Connection refused) Dec 27 12:28:13 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 27 12:28:13 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:13] [error] handle_connect error: Underlying Transport Error Dec 27 12:28:14 kitchen kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 27 12:28:14 kitchen kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 27 12:28:14 kitchen wireless.js[498]: trying... Dec 27 12:28:14 kitchen sudo[857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:14 kitchen sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:14 kitchen sudo[857]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:15 kitchen wireless.js[498]: trying... Dec 27 12:28:15 kitchen sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:15 kitchen sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:15 kitchen sudo[860]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:16 kitchen wpa_supplicant[824]: wlan0: Trying to associate with SSID 'Grove' Dec 27 12:28:16 kitchen wireless.js[498]: trying... Dec 27 12:28:16 kitchen sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:16 kitchen sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:16 kitchen sudo[863]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:16 kitchen wpa_supplicant[824]: wlan0: Associated with f6:92:bf:e8:c0:66 Dec 27 12:28:16 kitchen wpa_supplicant[824]: wlan0: CTRL-EVENT-CONNECTED - Connection to f6:92:bf:e8:c0:66 completed [id=0 id_str=] Dec 27 12:28:16 kitchen dhcpcd[838]: wlan0: carrier acquired Dec 27 12:28:16 kitchen wpa_supplicant[824]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 27 12:28:16 kitchen dhcpcd[838]: wlan0: connected to Access Point `Grove' Dec 27 12:28:16 kitchen wpa_supplicant[824]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Dec 27 12:28:16 kitchen dhcpcd[838]: DUID 00:01:00:01:2a:c9:6a:9c:b8:27:eb:fb:44:be Dec 27 12:28:16 kitchen dhcpcd[838]: wlan0: IAID eb:fb:44:be Dec 27 12:28:16 kitchen dhcpcd[838]: wlan0: adding address fe80::dbba:68fe:ea14:d16b Dec 27 12:28:16 kitchen dhcpcd[838]: ipv6_addaddr1: Permission denied Dec 27 12:28:16 kitchen volumio-time-update[513]: volumio-time-update-util: Fetching time from Volumio... Dec 27 12:28:16 kitchen volumio-time-update[513]: volumio-time-update-util: Date not found in response Dec 27 12:28:16 kitchen volumio-time-update[513]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 12:28:16 kitchen dhcpcd[838]: wlan0: rebinding lease of 192.168.25.33 Dec 27 12:28:17 kitchen dhcpcd[838]: wlan0: probing address 192.168.25.33/24 Dec 27 12:28:17 kitchen wireless.js[498]: trying... Dec 27 12:28:17 kitchen sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:17 kitchen sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:17 kitchen sudo[873]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:17 kitchen dhcpcd[838]: wlan0: soliciting an IPv6 router Dec 27 12:28:18 kitchen wireless.js[498]: trying... Dec 27 12:28:18 kitchen sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:18 kitchen sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:18 kitchen sudo[876]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:19 kitchen wireless.js[498]: trying... Dec 27 12:28:19 kitchen sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:19 kitchen sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:19 kitchen sudo[879]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:20 kitchen wireless.js[498]: trying... Dec 27 12:28:20 kitchen sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:20 kitchen sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:20 kitchen sudo[882]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:21 kitchen wireless.js[498]: trying... Dec 27 12:28:21 kitchen sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:21 kitchen sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:21 kitchen sudo[885]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:21 kitchen volumio-time-update[513]: volumio-time-update-util: Fetching time from Volumio... Dec 27 12:28:21 kitchen volumio-time-update[513]: volumio-time-update-util: Date not found in response Dec 27 12:28:21 kitchen volumio-time-update[513]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 12:28:22 kitchen dhcpcd[838]: wlan0: leased 192.168.25.33 for 7200 seconds Dec 27 12:28:22 kitchen avahi-daemon[501]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.25.33. Dec 27 12:28:22 kitchen avahi-daemon[501]: New relevant interface wlan0.IPv4 for mDNS. Dec 27 12:28:22 kitchen avahi-daemon[501]: Registering new address record for 192.168.25.33 on wlan0.IPv4. Dec 27 12:28:22 kitchen dhcpcd[838]: wlan0: adding route to 192.168.25.0/24 Dec 27 12:28:22 kitchen dhcpcd[838]: wlan0: adding default route via 192.168.25.1 Dec 27 12:28:22 kitchen wireless.js[498]: trying... Dec 27 12:28:22 kitchen sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 27 12:28:22 kitchen sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:22 kitchen sudo[914]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:22 kitchen wireless.js[498]: Connected to: ----Grove Dec 27 12:28:22 kitchen wireless.js[498]: ---- Dec 27 12:28:22 kitchen sudo[917]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 12:28:22 kitchen sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:22 kitchen sudo[917]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:22 kitchen wireless.js[498]: ... joined AP, wlan0 IPv4 is 192.168.25.33, ipV6 is undefined Dec 27 12:28:22 kitchen wireless.js[498]: It's done! AP Dec 27 12:28:22 kitchen systemd[1]: Started Wireless Services. Dec 27 12:28:22 kitchen kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 27 12:28:22 kitchen systemd[1]: Started Volumio Backend Module. Dec 27 12:28:22 kitchen systemd[1]: Started Volumio Cpu Tweaker. Dec 27 12:28:22 kitchen volumio-cpu-tweak[923]: Setting RT Priority for mpd Dec 27 12:28:22 kitchen volumio-cpu-tweak[923]: pid 35's current scheduling policy: SCHED_OTHER Dec 27 12:28:22 kitchen volumio-cpu-tweak[923]: pid 35's current scheduling priority: 0 Dec 27 12:28:22 kitchen volumio-cpu-tweak[923]: Setting MPD Affinity Dec 27 12:28:22 kitchen volumio-cpu-tweak[923]: pid 3's current affinity mask: f Dec 27 12:28:23 kitchen volumio-cpu-tweak[923]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 27 12:28:23 kitchen volumio-cpu-tweak[923]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 27 12:28:23 kitchen systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 27 12:28:23 kitchen ntpd[647]: Listen normally on 3 wlan0 192.168.25.33:123 Dec 27 12:28:23 kitchen ntpd[647]: new interface(s) found: waking up resolver Dec 27 12:28:23 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:23] [info] asio async_connect error: system:111 (Connection refused) Dec 27 12:28:23 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 27 12:28:23 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:23] [error] handle_connect error: Underlying Transport Error Dec 27 12:28:24 kitchen nmbd[633]: [2025/12/27 12:28:24.557759, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 27 12:28:24 kitchen systemd[1]: Started Samba NMB Daemon. Dec 27 12:28:24 kitchen nmbd[633]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 27 12:28:24 kitchen systemd[1]: Starting Samba Winbind Daemon... Dec 27 12:28:25 kitchen winbindd[939]: [2025/12/27 12:28:25.326681, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 27 12:28:25 kitchen winbindd[939]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 27 12:28:25 kitchen winbindd[939]: [2025/12/27 12:28:25.359315, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 27 12:28:25 kitchen systemd[1]: Started Samba Winbind Daemon. Dec 27 12:28:25 kitchen winbindd[939]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 27 12:28:25 kitchen systemd[1]: Starting Samba SMB Daemon... Dec 27 12:28:26 kitchen smbd[944]: [2025/12/27 12:28:26.387037, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 27 12:28:26 kitchen systemd[1]: Started Samba SMB Daemon. Dec 27 12:28:26 kitchen smbd[944]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 27 12:28:26 kitchen volumio[922]: info: ------------------------------------------- Dec 27 12:28:26 kitchen volumio[922]: info: ----- Volumio3 ---- Dec 27 12:28:26 kitchen volumio[922]: info: ------------------------------------------- Dec 27 12:28:26 kitchen volumio[922]: info: ----- System startup ---- Dec 27 12:28:26 kitchen volumio[922]: info: ------------------------------------------- Dec 27 12:28:26 kitchen volumio-time-update[513]: volumio-time-update-util: Fetching time from Volumio... Dec 27 12:28:27 kitchen volumio-time-update[513]: volumio-time-update-util: Setting system time to: 2025-12-27 12:28:50 Dec 27 12:28:27 kitchen sudo[965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-27 12:28:50 Dec 27 12:28:27 kitchen sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:27 kitchen dbus-daemon[523]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.7' (uid=0 pid=966 comm="timedatectl set-time 2025-12-27 12:28:50 ") Dec 27 12:28:27 kitchen systemd[1]: Starting Time & Date Service... Dec 27 12:28:27 kitchen dbus-daemon[523]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 27 12:28:27 kitchen systemd[1]: Started Time & Date Service. Dec 27 12:28:27 kitchen systemd[1]: systemd-fsckd.service: Succeeded. Dec 27 12:28:50 kitchen systemd-timedated[967]: Changed local time to Sat Dec 27 12:28:50 2025 Dec 27 12:28:50 kitchen sudo[965]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:50 kitchen volumio-time-update[513]: volumio-time-update-util: System time updated successfully. Dec 27 12:28:50 kitchen systemd[1]: Started Volumio Time Update Utility. Dec 27 12:28:50 kitchen systemd[1]: Reached target Multi-User System. Dec 27 12:28:50 kitchen systemd[1]: Reached target Graphical Interface. Dec 27 12:28:50 kitchen systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 27 12:28:50 kitchen systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 27 12:28:50 kitchen systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 27 12:28:50 kitchen systemd[1]: Startup finished in 15.270s (kernel) + 34.765s (userspace) = 50.035s. Dec 27 12:28:51 kitchen volumio[922]: info: MYVOLUMIO Environment detected Dec 27 12:28:51 kitchen volumio[922]: info: Plugin folders cleanup Dec 27 12:28:51 kitchen volumio[922]: info: Scanning into folder /volumio/app/plugins/ Dec 27 12:28:51 kitchen volumio[922]: info: Scanning category audio_interface Dec 27 12:28:51 kitchen volumio[922]: info: Scanning category miscellanea Dec 27 12:28:51 kitchen volumio[922]: info: Scanning category music_service Dec 27 12:28:51 kitchen volumio[922]: info: Scanning category plugins.json Dec 27 12:28:51 kitchen volumio[922]: info: Scanning category system_controller Dec 27 12:28:51 kitchen volumio[922]: info: Scanning category user_interface Dec 27 12:28:51 kitchen volumio[922]: info: Scanning into folder /data/plugins/ Dec 27 12:28:51 kitchen volumio[922]: info: Scanning category system_controller Dec 27 12:28:51 kitchen volumio[922]: info: Plugin folders cleanup completed Dec 27 12:28:51 kitchen volumio[922]: info: ------------------------------------------- Dec 27 12:28:51 kitchen volumio[922]: info: ----- Core plugins startup ---- Dec 27 12:28:51 kitchen volumio[922]: info: ------------------------------------------- Dec 27 12:28:51 kitchen volumio[922]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 12:28:51 kitchen volumio[922]: info: Adding plugin upnp to MyMusic Plugins Dec 27 12:28:51 kitchen volumio[922]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 12:28:51 kitchen volumio[922]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 12:28:51 kitchen volumio[922]: info: Loading plugins from folder /data/plugins/ Dec 27 12:28:51 kitchen volumio[922]: info: Loading plugin "system"... Dec 27 12:28:51 kitchen volumio[922]: info: Loading plugin "appearance"... Dec 27 12:28:54 kitchen volumio[922]: info: Loading plugin "network"... Dec 27 12:28:54 kitchen volumio[922]: info: Refreshing Cached IP Addresses Dec 27 12:28:54 kitchen sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 12:28:54 kitchen sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:54 kitchen sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 12:28:54 kitchen sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:54 kitchen sudo[978]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:54 kitchen sudo[976]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:54 kitchen volumio[922]: info: Loading plugin "services"... Dec 27 12:28:54 kitchen volumio[922]: info: Loading plugin "alsa_controller"... Dec 27 12:28:54 kitchen sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 12:28:54 kitchen sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:28:54 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 12:28:54 kitchen volumio[922]: info: Loading plugin "wizard"... Dec 27 12:28:54 kitchen volumio[922]: info: Loading plugin "networkfs"... Dec 27 12:28:54 kitchen volumio[922]: info: Starting Udev Watcher for removable devices Dec 27 12:28:54 kitchen volumio[922]: info: Ignoring mount for partition: boot Dec 27 12:28:54 kitchen volumio[922]: info: Ignoring mount for partition: volumio Dec 27 12:28:54 kitchen volumio[922]: info: Ignoring mount for partition: volumio_data Dec 27 12:28:54 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 12:28:54 kitchen volumio[922]: info: Loading plugin "volumio_command_line_client"... Dec 27 12:28:54 kitchen volumio[922]: info: Plugin upnp is not enabled Dec 27 12:28:54 kitchen volumio[922]: info: Loading plugin "my_music"... Dec 27 12:28:54 kitchen volumio[922]: info: Loading plugin "mpd"... Dec 27 12:28:55 kitchen volumio[922]: info: Plugin upnp_browser is not enabled Dec 27 12:28:55 kitchen volumio[922]: info: Loading plugin "alarm-clock"... Dec 27 12:28:55 kitchen volumio[922]: info: Plugin airplay_emulation is not enabled Dec 27 12:28:55 kitchen volumio[922]: info: Loading plugin "last_100"... Dec 27 12:28:55 kitchen volumio[922]: info: Loading plugin "webradio"... Dec 27 12:28:56 kitchen volumio[922]: info: Loading plugin "i2s_dacs"... Dec 27 12:28:56 kitchen volumio[922]: info: Loading plugin "volumiodiscovery"... Dec 27 12:28:56 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:56] [connect] Successful connection Dec 27 12:28:56 kitchen volumio[922]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 12:28:56 kitchen volumio[922]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 12:28:56 kitchen volumio[922]: *** WARNING *** For more information see Dec 27 12:28:56 kitchen volumio[922]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 12:28:56 kitchen volumio[922]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 12:28:56 kitchen volumio[922]: *** WARNING *** For more information see Dec 27 12:28:56 kitchen node[922]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 12:28:56 kitchen node[922]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 12:28:56 kitchen node[922]: *** WARNING *** For more information see Dec 27 12:28:56 kitchen node[922]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 12:28:56 kitchen node[922]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 12:28:56 kitchen node[922]: *** WARNING *** For more information see Dec 27 12:28:56 kitchen volumio[922]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 12:28:56 kitchen volumio[922]: info: Discovery: Started advertising with name: Kitchen Dec 27 12:28:56 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 12:28:56 kitchen volumio[922]: info: Loading plugin "outputs"... Dec 27 12:28:56 kitchen volumio[922]: info: Loading plugin "albumart"... Dec 27 12:28:56 kitchen volumio[922]: info: Plugin example_plugin is not enabled Dec 27 12:28:56 kitchen volumio[922]: info: Loading plugin "inputs"... Dec 27 12:28:56 kitchen volumio[922]: info: Loading plugin "updater_comm"... Dec 27 12:28:57 kitchen volumio[922]: info: Plugin mpdemulation is not enabled Dec 27 12:28:57 kitchen volumio[922]: info: Loading plugin "rest_api"... Dec 27 12:28:57 kitchen volumio[922]: info: Loading plugin "websocket"... Dec 27 12:28:57 kitchen volumio[922]: info: Starting Socket.io Server version 2.3.0 Dec 27 12:28:57 kitchen volumio[922]: info: Loading plugin "backup_restore"... Dec 27 12:28:57 kitchen volumio[922]: Forking 3 albumart workers Dec 27 12:28:57 kitchen volumio[922]: info: Applying required configuration parameters for plugin backup_restore Dec 27 12:28:58 kitchen volumio[922]: info: Loading i18n strings for locale en Dec 27 12:28:58 kitchen volumio[922]: Updating browse sources language Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:28:58 kitchen sudo[990]: pam_unix(sudo:session): session closed for user root Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::initPlayerControls Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 12:28:58 kitchen volumio[922]: Express server listening on port 3000 Dec 27 12:28:58 kitchen volumio[922]: [Metrics] WebUI: 11s 715.10ms Dec 27 12:28:58 kitchen volumio[922]: info: CoreStateMachine::resetVolumioState Dec 27 12:28:58 kitchen volumio[922]: info: CoreStateMachine::getcurrentVolume Dec 27 12:28:58 kitchen volumio[922]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 12:28:58 kitchen volumio[922]: info: Volumio Network Manager: Network status updated: 2 Dec 27 12:28:59 kitchen volumio-remote-updater[504]: [2025-12-27 12:28:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766856536 101 Dec 27 12:28:59 kitchen volumio[922]: Starting albumart workers Dec 27 12:28:59 kitchen volumio[922]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Dec 27 12:28:59 kitchen volumio[922]: verbose: New Socket.io Connection to 192.168.25.33:3000 from 192.168.25.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 27 12:28:59 kitchen volumio[922]: Starting albumart workers Dec 27 12:28:59 kitchen volumio[922]: verbose: New Socket.io Connection to 192.168.25.33:3000 from 192.168.25.31 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 27 12:28:59 kitchen volumio[922]: verbose: New Socket.io Connection to 192.168.25.33:3000 from 192.168.25.34 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 27 12:28:59 kitchen volumio[922]: info: CoreStateMachine::pushState Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioPushState Dec 27 12:28:59 kitchen volumio[922]: info: CoreStateMachine::updateTrackBlock Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrackBlock Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 12:28:59 kitchen volumio[922]: Starting albumart workers Dec 27 12:28:59 kitchen volumio[922]: info: Reloading queue from file Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 27 12:28:59 kitchen volumio[922]: info: CoreStateMachine::setRepeat true single undefined Dec 27 12:28:59 kitchen volumio[922]: info: CoreStateMachine::pushState Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioPushState Dec 27 12:28:59 kitchen volumio[922]: info: CoreStateMachine::setRandom undefined Dec 27 12:28:59 kitchen volumio[922]: info: CoreStateMachine::pushState Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioPushState Dec 27 12:28:59 kitchen volumio[922]: info: Setting Device type: Raspberry PI Dec 27 12:28:59 kitchen volumio[922]: info: Completed loading Core Plugins Dec 27 12:28:59 kitchen volumio[922]: info: Preparing to generate the ALSA configuration file Dec 27 12:28:59 kitchen volumio[922]: info: CoreStateMachine::pushState Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioPushState Dec 27 12:28:59 kitchen volumio[922]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 27 12:28:59 kitchen volumio[922]: info: Reading ALSA contributions from plugins. Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioGetState Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioGetState Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioGetState Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:28:59 kitchen volumio[922]: info: Discovery: adding 7518afee-3559-416a-bc11-c5f5d5eba21e Dec 27 12:28:59 kitchen volumio[922]: info: Discovery: Found device Kitchen Dec 27 12:28:59 kitchen volumio[922]: info: CoreCommandRouter::volumioGetState Dec 27 12:28:59 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:28:59 kitchen volumio[922]: info: Discovery: adding 638d672d-5e7a-4d2e-a1bb-a5e92f756fb1 Dec 27 12:28:59 kitchen volumio[922]: info: Discovery: Found device Volumio Dec 27 12:28:59 kitchen volumio[922]: info: Discovery: Connecting to remote: 192.168.25.35 Dec 27 12:29:00 kitchen volumio[922]: info: Discovery: Connected to remote: 192.168.25.35 Dec 27 12:29:00 kitchen volumio[922]: info: Asound.conf file written Dec 27 12:29:00 kitchen sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 27 12:29:00 kitchen sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:00 kitchen sudo[1057]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:00 kitchen volumio[922]: info: Output device has changed, restarting MPD Dec 27 12:29:00 kitchen volumio[922]: info: ___________ START PLUGINS ___________ Dec 27 12:29:00 kitchen sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 12:29:00 kitchen sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:00 kitchen volumio[922]: info: ControllerMpd::onStart: Initializing MPD Dec 27 12:29:00 kitchen sudo[1070]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:00 kitchen volumio[922]: info: Creating MPD Configuration file Dec 27 12:29:00 kitchen sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 12:29:00 kitchen sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:00 kitchen volumio[922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 12:29:00 kitchen volumio[922]: info: [1766856540682] CoreMusicLibrary::Adding element Last_100 Dec 27 12:29:00 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:29:00 kitchen sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 12:29:00 kitchen sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:00 kitchen volumio[922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 12:29:00 kitchen volumio[922]: info: [1766856540715] CoreMusicLibrary::Adding element Webradio Dec 27 12:29:00 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:29:00 kitchen sudo[1080]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:00 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 12:29:00 kitchen volumio[922]: info: Initializing BBC Radios Dec 27 12:29:00 kitchen sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 12:29:00 kitchen sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:00 kitchen volumio[922]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 27 12:29:00 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:00 kitchen systemd[1]: Listening on mpd.socket. Dec 27 12:29:00 kitchen systemd[1]: Starting Music Player Daemon... Dec 27 12:29:00 kitchen systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 27 12:29:00 kitchen systemd[1]: mpd.service: Succeeded. Dec 27 12:29:00 kitchen systemd[1]: Stopped Music Player Daemon. Dec 27 12:29:00 kitchen systemd[1]: Starting Music Player Daemon... Dec 27 12:29:00 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 12:29:00 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:00 kitchen volumio[922]: info: Volumio Calling Home Dec 27 12:29:01 kitchen sudo[1109]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 12:29:01 kitchen sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:01 kitchen sudo[1109]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 27 12:29:01 kitchen sudo[1109]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:01 kitchen volumio[922]: info: Discovery: adding 296feba7-58fc-4fd3-a19c-bd4ee7ea707b Dec 27 12:29:01 kitchen volumio[922]: info: Discovery: Found device Livingroom Dec 27 12:29:01 kitchen volumio[922]: info: Discovery: Connecting to remote: 192.168.25.34 Dec 27 12:29:01 kitchen volumio[922]: info: Discovery: adding bdc8a1f3-8418-4997-902e-af944c6f7d1c Dec 27 12:29:01 kitchen volumio[922]: info: Discovery: Found device Bathroom Dec 27 12:29:01 kitchen volumio[922]: info: Discovery: Connecting to remote: 192.168.25.31 Dec 27 12:29:01 kitchen volumio[922]: info: MPD Permissions set Dec 27 12:29:01 kitchen volumio[922]: info: MPD Permissions set Dec 27 12:29:01 kitchen volumio[922]: info: Discovery: Connected to remote: 192.168.25.34 Dec 27 12:29:01 kitchen volumio[922]: info: Discovery: Connected to remote: 192.168.25.31 Dec 27 12:29:01 kitchen volumio[922]: info: Volumio called home Dec 27 12:29:01 kitchen volumio[922]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:29:01 kitchen volumio[922]: info: CoreCommandRouter::volumioGetState Dec 27 12:29:01 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:29:02 kitchen volumio[922]: verbose: New Socket.io Connection to 192.168.25.33:3000 from 192.168.25.31 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 27 12:29:02 kitchen volumio[922]: info: Discovery: adding e4ab5a5b-27bb-493b-961b-19e9cf330f71 Dec 27 12:29:02 kitchen volumio[922]: info: Discovery: Found device Bedroom Dec 27 12:29:02 kitchen volumio[922]: info: Discovery: Connecting to remote: 192.168.25.32 Dec 27 12:29:04 kitchen volumio[922]: verbose: New Socket.io Connection to 192.168.25.33:3000 from 192.168.25.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 27 12:29:04 kitchen volumio[922]: info: Discovery: Connected to remote: 192.168.25.32 Dec 27 12:29:04 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 27 12:29:04 kitchen volumio[922]: info: CoreCommandRouter::volumioGetState Dec 27 12:29:04 kitchen volumio[922]: info: CorePlayQueue::getTrack 0 Dec 27 12:29:06 kitchen mpd[1114]: Dec 27 12:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 12:29:06 kitchen systemd[1]: Started Music Player Daemon. Dec 27 12:29:06 kitchen sudo[1076]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:06 kitchen sudo[1090]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:06 kitchen volumio[922]: info: Completed starting Core Plugins Dec 27 12:29:06 kitchen volumio[922]: info: ------------------------------------------- Dec 27 12:29:06 kitchen volumio[922]: info: ----- MyVolumio plugins startup ---- Dec 27 12:29:06 kitchen volumio[922]: info: ------------------------------------------- Dec 27 12:29:06 kitchen volumio[922]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 12:29:06 kitchen volumio[922]: error: MPD error: The expression evaluated to a falsy value: Dec 27 12:29:06 kitchen volumio[922]: assert.ok(self.idling) Dec 27 12:29:06 kitchen volumio[922]: error: The expression evaluated to a falsy value: Dec 27 12:29:06 kitchen volumio[922]: assert.ok(self.idling) Dec 27 12:29:06 kitchen volumio[922]: error: updateQueue error: null Dec 27 12:29:06 kitchen volumio[922]: info: MPD running with PID1114 Dec 27 12:29:06 kitchen volumio[922]: ,establishing connection Dec 27 12:29:06 kitchen volumio[922]: error: updateQueue error: null Dec 27 12:29:09 kitchen sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 12:29:09 kitchen sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 12:29:09 kitchen sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:09 kitchen sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:09 kitchen systemd[1]: Started MPD Monitor Service. Dec 27 12:29:09 kitchen sudo[1144]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:09 kitchen sudo[1146]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:09 kitchen volumio[922]: info: Successfully started MPD Monitor Dec 27 12:29:09 kitchen volumio[922]: info: Successfully started MPD Monitor Dec 27 12:29:09 kitchen mpd_monitor.sh[1149]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 12:29:11 kitchen volumio[922]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 27 12:29:14 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 27 12:29:15 kitchen volumio[922]: info: Adding plugin bluetooth to MyMusic Plugins Dec 27 12:29:15 kitchen volumio[922]: info: Adding plugin multiroom to MyMusic Plugins Dec 27 12:29:15 kitchen volumio[922]: info: Adding plugin metavolumio to MyMusic Plugins Dec 27 12:29:15 kitchen volumio[922]: info: Adding plugin cd_controller to MyMusic Plugins Dec 27 12:29:15 kitchen volumio[922]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 27 12:29:15 kitchen volumio[922]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 27 12:29:15 kitchen volumio[922]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 27 12:29:15 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 27 12:29:17 kitchen volumio[922]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 27 12:29:17 kitchen volumio[922]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 27 12:29:17 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:17 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:17 kitchen volumio[922]: info: Starting MyVolumio Remote Streaming Endpoints Dec 27 12:29:17 kitchen volumio[922]: info: MyVolumio login type: Token Dec 27 12:29:17 kitchen volumio[922]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 27 12:29:17 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 27 12:29:20 kitchen systemd[1]: systemd-timedated.service: Succeeded. Dec 27 12:29:20 kitchen volumio[922]: info: Starting Streaming Service Transparent Proxy Dec 27 12:29:20 kitchen volumio[922]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 27 12:29:20 kitchen volumio[922]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 27 12:29:20 kitchen volumio[922]: info: Streaming services startup Dec 27 12:29:20 kitchen volumio[922]: info: Starting Streaming Daemon Dec 27 12:29:20 kitchen sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 12:29:20 kitchen sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:20 kitchen volumio[922]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 27 12:29:20 kitchen sudo[1176]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:20 kitchen volumio[922]: error: Cannot start Volumio Streaming Daemon Dec 27 12:29:20 kitchen volumio[922]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 27 12:29:20 kitchen volumio[922]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 27 12:29:21 kitchen volumio[922]: error: MyVolumio Custom Token format not valid, refreshing it Dec 27 12:29:21 kitchen volumio[922]: info: MyVolumio login type: Token Dec 27 12:29:22 kitchen volumio[922]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 27 12:29:22 kitchen volumio[922]: info: MyVolumio token set successfully Dec 27 12:29:22 kitchen volumio[922]: info: MYVOLUMIO: Adding device Dec 27 12:29:22 kitchen volumio[922]: info: MYVOLUMIO: Evaluating Server Dec 27 12:29:23 kitchen volumio[922]: info: MyVolumio Plan changed: premium Dec 27 12:29:23 kitchen volumio[922]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 27 12:29:23 kitchen volumio[922]: info: Removing browser output: myVolumio user plan is not superstar Dec 27 12:29:23 kitchen volumio[922]: info: Removing audio output: Dec 27 12:29:23 kitchen volumio[922]: info: MYVOLUMIO: Adding device Dec 27 12:29:23 kitchen volumio[922]: info: MYVOLUMIO: Evaluating Server Dec 27 12:29:23 kitchen volumio[922]: info: Setting Geolocation for MyVolumio to us3 Dec 27 12:29:23 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:23 kitchen volumio[922]: info: Remote config written successfully Dec 27 12:29:23 kitchen volumio[922]: info: Starting Tunnel 1 Dec 27 12:29:23 kitchen volumio[922]: info: Starting Tunnel Connection Checker Dec 27 12:29:23 kitchen volumio[922]: info: Completed starting MyVolumio Plugin Dec 27 12:29:23 kitchen volumio[922]: STREAMING PROXY: Starting server on port 3245 Dec 27 12:29:23 kitchen volumio[922]: Node JS runtime: 14 Dec 27 12:29:23 kitchen volumio[922]: info: MYVolumio Device enabled Dec 27 12:29:23 kitchen volumio[922]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 27 12:29:23 kitchen volumio[922]: info: MyVolumio status changed Dec 27 12:29:23 kitchen volumio[922]: info: Streaming services startup Dec 27 12:29:23 kitchen volumio[922]: info: Starting Streaming Daemon Dec 27 12:29:23 kitchen sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 12:29:23 kitchen sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:23 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 27 12:29:23 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:23 kitchen volumio[922]: error: [MyVolumio PluginManager] Cache data is invalid! Dec 27 12:29:23 kitchen volumio[922]: info: Setting Geolocation for MyVolumio to us3 Dec 27 12:29:23 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:23 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:23 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:23 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:23 kitchen sudo[1261]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:24 kitchen volumio[922]: error: Cannot start Volumio Streaming Daemon Dec 27 12:29:24 kitchen volumio[922]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 27 12:29:24 kitchen volumio[922]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 27 12:29:24 kitchen volumio[922]: info: Successfully Added MyVolumio device Dec 27 12:29:24 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 27 12:29:24 kitchen volumio[922]: info: [1766856564803] Starting BluetoothController Dec 27 12:29:25 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 27 12:29:28 kitchen volumio[922]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 27 12:29:28 kitchen sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 27 12:29:28 kitchen sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:28 kitchen sudo[1278]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:28 kitchen volumio[922]: info: MRS: MultiRoom plugin initialized Dec 27 12:29:28 kitchen volumio[922]: info: MRS: STOPPING SNAPCLIENT Dec 27 12:29:28 kitchen volumio[922]: info: MRS: Snap server stop Dec 27 12:29:28 kitchen sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 27 12:29:28 kitchen volumio[922]: info: MRS: STOPPING volumioStreaming Dec 27 12:29:28 kitchen sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:28 kitchen sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 27 12:29:28 kitchen sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:28 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 27 12:29:28 kitchen sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 27 12:29:28 kitchen sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:28 kitchen sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 27 12:29:28 kitchen sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 12:29:28 kitchen sudo[1302]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:28 kitchen sudo[1294]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:28 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 27 12:29:28 kitchen sudo[1296]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:28 kitchen sudo[1299]: pam_unix(sudo:session): session closed for user root Dec 27 12:29:28 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 27 12:29:28 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 27 12:29:28 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 27 12:29:28 kitchen volumio[922]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 27 12:29:28 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 27 12:29:29 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 27 12:29:29 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 27 12:29:29 kitchen volumio[922]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 27 12:29:29 kitchen volumio[922]: info: Preparing to generate the ALSA configuration file Dec 27 12:29:29 kitchen volumio[922]: info: Updating MyVolumio device info Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: Updating MyVolumio device info Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 27 12:29:29 kitchen volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:29:29 kitchen volumio[922]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 27 12:29:29 kitchen volumio[922]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 27 12:29:29 kitchen volumio[922]: info: Reading ALSA contributions from plugins. Dec 27 12:29:29 kitchen volumio[922]: info: Successfully Added MyVolumio device Dec 27 12:29:29 kitchen volumio[922]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 12:29:29 kitchen volumio[922]: TypeError: Cannot read property 'id' of null Dec 27 12:29:29 kitchen volumio[922]: at multiroom.disableMultiroomSyncOutput (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:17218) Dec 27 12:29:29 kitchen volumio[922]: at CoreCommandRouter.disableMultiroomSyncOutput (/volumio/app/index.js:1298:23) Dec 27 12:29:29 kitchen volumio[922]: at Socket. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:367:28) Dec 27 12:29:29 kitchen volumio[922]: at Socket.Emitter.emit (/volumio/node_modules/socket.io-client/node_modules/component-emitter/index.js:133:20) Dec 27 12:29:29 kitchen volumio[922]: at Socket.onevent (/volumio/node_modules/socket.io-client/lib/socket.js:278:10) Dec 27 12:29:29 kitchen volumio[922]: at Socket.onpacket (/volumio/node_modules/socket.io-client/lib/socket.js:236:12) Dec 27 12:29:29 kitchen volumio[922]: at Manager. (/volumio/node_modules/component-bind/index.js:21:15) Dec 27 12:29:29 kitchen volumio[922]: at Manager.Emitter.emit (/volumio/node_modules/socket.io-client/node_modules/component-emitter/index.js:133:20) Dec 27 12:29:29 kitchen volumio[922]: at Manager.ondecoded (/volumio/node_modules/socket.io-client/lib/manager.js:345:8) Dec 27 12:29:29 kitchen volumio[922]: at Decoder. (/volumio/node_modules/component-bind/index.js:21:15) Dec 27 12:29:29 kitchen volumio[922]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 12:29:30 kitchen sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 12:28 Dec 27 12:29:30 kitchen sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"