-- Logs begin at Fri 2025-09-05 21:44:59 WEST, end at Fri 2025-09-05 21:46:18 WEST. -- Sep 05 21:45:00 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Sep 05 21:45:00 volumio kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Sep 05 21:45:00 volumio kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Sep 05 21:45:00 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Sep 05 21:45:00 volumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Sep 05 21:45:00 volumio kernel: input: 11-0038 generic ft5x06 (00) as /devices/platform/axi/1000120000.pcie/1f00080000.i2c/i2c-11/11-0038/input/input1 Sep 05 21:44:59 volumio systemd[1]: Found device SAMSUNG MZ9LQ256HBJD-00BVL boot. Sep 05 21:44:59 volumio systemd-udevd[461]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 05 21:44:59 volumio systemd-udevd[473]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 05 21:45:00 volumio systemd-fsck[702]: fsck.fat 4.1 (2017-01-24) Sep 05 21:45:00 volumio systemd-fsck[702]: /dev/nvme0n1p1: 404 files, 166332/184582 clusters Sep 05 21:44:59 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Sep 05 21:44:59 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Sep 05 21:44:59 volumio systemd[1]: Created slice system-systemd\x2dbacklight.slice. Sep 05 21:44:59 volumio systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:11-0045... Sep 05 21:44:59 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/CDA7-DE47... Sep 05 21:45:00 volumio sh[710]: command failed: No such device (-19) Sep 05 21:45:00 volumio systemd[1]: Started File System Check Daemon to report status. Sep 05 21:45:00 volumio systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:11-0045. Sep 05 21:45:00 volumio ifup[714]: command failed: No such device (-19) Sep 05 21:45:00 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/CDA7-DE47. Sep 05 21:45:00 volumio systemd[1]: Mounting /boot... Sep 05 21:45:00 volumio systemd[1]: Mounted /boot. Sep 05 21:45:00 volumio systemd[1]: Reached target Local File Systems. Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Sep 05 21:45:00 volumio systemd[1]: Starting Preprocess NFS configuration... Sep 05 21:45:00 volumio systemd[1]: Starting Create Volatile Files and Directories... Sep 05 21:45:00 volumio systemd[1]: Started ifup for eth0. Sep 05 21:45:00 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 05 21:45:00 volumio systemd[1]: Starting Raise network interfaces... Sep 05 21:45:00 volumio systemd[1]: nfs-config.service: Succeeded. Sep 05 21:45:00 volumio systemd[1]: Started Preprocess NFS configuration. Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Sep 05 21:45:00 volumio systemd[1]: Reached target NFS client services. Sep 05 21:45:00 volumio systemd[1]: Started Create Volatile Files and Directories. Sep 05 21:45:00 volumio systemd[1]: plymouth-read-write.service: Succeeded. Sep 05 21:45:00 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 05 21:45:00 volumio systemd[1]: Received SIGRTMIN+20 from PID 298 (plymouthd). Sep 05 21:45:00 volumio systemd[1]: Starting RPC bind portmap service... Sep 05 21:45:00 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 05 21:45:00 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 05 21:45:00 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 05 21:45:00 volumio systemd[1]: Reached target System Initialization. Sep 05 21:45:00 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 05 21:45:00 volumio systemd[1]: Started Daily apt download activities. Sep 05 21:45:00 volumio systemd[1]: Listening on triggerhappy.socket. Sep 05 21:45:00 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 05 21:45:00 volumio systemd[1]: Started Daily man-db regeneration. Sep 05 21:45:00 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 05 21:45:00 volumio systemd[1]: Reached target Timers. Sep 05 21:45:00 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 05 21:45:00 volumio systemd[1]: Reached target Sockets. Sep 05 21:45:00 volumio systemd[1]: Reached target Basic System. Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 05 21:45:00 volumio kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Sep 05 21:45:00 volumio kernel: rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Sep 05 21:45:00 volumio kernel: rpivid 1000800000.codec: Device registered as /dev/video19 Sep 05 21:45:00 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 05 21:45:00 volumio systemd[1]: Started Volumio Iptables Module. Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Sep 05 21:45:00 volumio systemd[1]: Started Volumio Log Rotation Service. Sep 05 21:45:00 volumio systemd[1]: Started MPD Monitor Service. Sep 05 21:45:00 volumio systemd[1]: Starting Wireless Services... Sep 05 21:45:00 volumio systemd[1]: Starting Volumio Time Update Utility... Sep 05 21:45:00 volumio systemd[1]: Started volumio-remote-updater.service. Sep 05 21:45:00 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Sep 05 21:45:00 volumio kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Sep 05 21:45:00 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Sep 05 21:45:00 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Sep 05 21:45:00 volumio kernel: usbcore: registered new interface driver brcmfmac Sep 05 21:45:00 volumio kernel: pispbe 1000880000.pisp_be: Runtime PM usage count underflow! Sep 05 21:45:00 volumio thd[768]: Unable to parse trigger line: Sep 05 21:45:00 volumio thd[768]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Sep 05 21:45:00 volumio thd[768]: Unable to parse trigger line: Sep 05 21:45:00 volumio thd[768]: Found socket passed from systemd Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Sep 05 21:45:00 volumio mpd_monitor.sh[763]: MPD Monitor Service: Starting MPD Monitor Service Sep 05 21:45:00 volumio ifup[714]: command failed: No such device (-19) Sep 05 21:45:00 volumio volumio-time-update[766]: volumio-time-update-util: Fetching time from Volumio... Sep 05 21:45:00 volumio dhcpcd[774]: Not running dhcpcd because /etc/network/interfaces Sep 05 21:45:00 volumio dhcpcd[774]: defines some interfaces that will use a Sep 05 21:45:00 volumio dhcpcd[774]: DHCP client or static address Sep 05 21:45:00 volumio systemd[1]: Starting dhcpcd on all interfaces... Sep 05 21:45:00 volumio systemd[1]: Starting Login Service... Sep 05 21:45:00 volumio systemd[1]: Started D-Bus System Message Bus. Sep 05 21:45:00 volumio systemd[1]: Starting WPA supplicant... Sep 05 21:45:00 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 05 21:45:00 volumio systemd[1]: Started RPC bind portmap service. Sep 05 21:45:00 volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Sep 05 21:45:00 volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Sep 05 21:45:00 volumio systemd[1]: Failed to start dhcpcd on all interfaces. Sep 05 21:45:00 volumio systemd[1]: Reached target RPC Port Mapper. Sep 05 21:45:00 volumio systemd[1]: Reached target Remote File Systems (Pre). Sep 05 21:45:00 volumio systemd[1]: Reached target Remote File Systems. Sep 05 21:45:00 volumio kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 Sep 05 21:45:00 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 05 21:45:00 volumio systemd-udevd[463]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 05 21:45:00 volumio kernel: Bluetooth: Core ver 2.22 Sep 05 21:45:00 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Sep 05 21:45:00 volumio kernel: Bluetooth: HCI device and connection manager initialized Sep 05 21:45:00 volumio kernel: Bluetooth: HCI socket layer initialized Sep 05 21:45:00 volumio kernel: Bluetooth: L2CAP socket layer initialized Sep 05 21:45:00 volumio kernel: Bluetooth: SCO socket layer initialized Sep 05 21:45:00 volumio kernel: snd-rpi-iqaudio-dac soc:sound: snd_rpi_iqaudio_gpio_unmute: un-muting amp using GPIO22 Sep 05 21:45:00 volumio systemd-logind[775]: New seat seat0. Sep 05 21:45:00 volumio systemd-logind[775]: Watching system buttons on /dev/input/event0 (pwr_button) Sep 05 21:45:00 volumio systemd-udevd[455]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 05 21:45:00 volumio mpd_monitor.sh[763]: MPD error: Cannot assign requested address Sep 05 21:45:00 volumio mpd_monitor.sh[763]: MPD Monitor Service: MPD Appears to be inactive, restarting Sep 05 21:45:00 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 05 21:45:00 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Sep 05 21:45:00 volumio kernel: Bluetooth: HCI UART protocol H4 registered Sep 05 21:45:00 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Sep 05 21:45:00 volumio kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Sep 05 21:45:00 volumio kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Sep 05 21:45:00 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Sep 05 21:45:00 volumio kernel: Registered IR keymap rc-cec Sep 05 21:45:00 volumio kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0 Sep 05 21:45:00 volumio kernel: input: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0/input2 Sep 05 21:45:00 volumio kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc/107c701400.hdmi/sound/card1/input3 Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Sep 05 21:45:00 volumio kernel: Registered IR keymap rc-cec Sep 05 21:45:00 volumio kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1 Sep 05 21:45:00 volumio kernel: input: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1/input4 Sep 05 21:45:00 volumio kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc/107c706400.hdmi/sound/card2/input5 Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Sep 05 21:45:00 volumio kernel: [drm] Initialized vc4 0.0.0 20140616 for axi:gpu on minor 2 Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Sep 05 21:45:00 volumio kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Sep 05 21:45:00 volumio kernel: cma_alloc: 2 callbacks suppressed Sep 05 21:45:00 volumio kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 3 pages, ret: -12 Sep 05 21:45:00 volumio kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 3 pages, ret: -12 Sep 05 21:45:00 volumio kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Sep 05 21:45:00 volumio kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Sep 05 21:45:00 volumio kernel: pps pps0: new PPS source ptp0 Sep 05 21:45:00 volumio kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Sep 05 21:45:00 volumio volumio-time-update[766]: volumio-time-update-util: Date not found in response Sep 05 21:45:00 volumio volumio-time-update[766]: volumio-time-update-util: Retrying in 5 seconds... Sep 05 21:45:00 volumio volumio[747]: Could not open config: /tmp/upmpdcli.conf Sep 05 21:45:00 volumio volumio-remote-updater[767]: Error: No active session Sep 05 21:45:00 volumio volumio-remote-updater[767]: [2025-09-05 21:45:00] [info] asio async_connect error: system:111 (Connection refused) Sep 05 21:45:00 volumio volumio-remote-updater[767]: [2025-09-05 21:45:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 21:45:00 volumio volumio-remote-updater[767]: [2025-09-05 21:45:00] [error] handle_connect error: Underlying Transport Error Sep 05 21:45:00 volumio sh[710]: eth0: waiting for carrier Sep 05 21:45:00 volumio systemd-udevd[488]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 05 21:45:00 volumio avahi-daemon[793]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Sep 05 21:45:00 volumio mpd_monitor.sh[763]: mpd: no process found Sep 05 21:45:00 volumio systemd-udevd[463]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 05 21:45:00 volumio avahi-daemon[793]: Successfully dropped root privileges. Sep 05 21:45:00 volumio systemd-udevd[467]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 05 21:45:00 volumio avahi-daemon[793]: avahi-daemon 0.7 starting up. Sep 05 21:45:00 volumio systemd[1]: Started WPA supplicant. Sep 05 21:45:00 volumio avahi-daemon[793]: Successfully called chroot(). Sep 05 21:45:00 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 05 21:45:00 volumio avahi-daemon[793]: Successfully dropped remaining capabilities. Sep 05 21:45:00 volumio systemd[1]: Started triggerhappy global hotkey daemon. Sep 05 21:45:00 volumio wpa_supplicant[791]: Successfully initialized wpa_supplicant Sep 05 21:45:00 volumio kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Sep 05 21:45:00 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Sep 05 21:45:00 volumio systemd-logind[775]: Watching system buttons on /dev/input/event2 (vc4-hdmi-0) Sep 05 21:45:00 volumio avahi-daemon[793]: No service file found in /etc/avahi/services. Sep 05 21:45:00 volumio systemd-udevd[455]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 05 21:45:00 volumio avahi-daemon[793]: Network interface enumeration completed. Sep 05 21:45:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 05 21:45:00 volumio avahi-daemon[793]: Server startup complete. Host name is volumio.local. Local service cookie is 3651850956. Sep 05 21:45:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 05 21:45:00 volumio dhcpcd[757]: eth0: waiting for carrier Sep 05 21:45:00 volumio systemd-logind[775]: Watching system buttons on /dev/input/event4 (vc4-hdmi-1) Sep 05 21:45:00 volumio systemd-udevd[463]: Using default interface naming scheme 'v240'. Sep 05 21:45:00 volumio systemd-udevd[463]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 05 21:45:00 volumio kernel: Bluetooth: hci0: BCM: chip id 107 Sep 05 21:45:00 volumio kernel: Bluetooth: hci0: BCM: features 0x2f Sep 05 21:45:00 volumio kernel: Bluetooth: hci0: BCM4345C0 Sep 05 21:45:00 volumio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Sep 05 21:45:00 volumio kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Sep 05 21:45:00 volumio kernel: alsactl[870]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Sep 05 21:45:00 volumio systemd[1]: iptables.service: Succeeded. Sep 05 21:45:00 volumio systemd[1]: Started Login Service. Sep 05 21:45:00 volumio systemd[1]: Listening on mpd.socket. Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Sep 05 21:45:00 volumio systemd[1]: Created slice system-bthelper.slice. Sep 05 21:45:00 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Sep 05 21:45:00 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Sep 05 21:45:00 volumio systemd[1]: Started Manage Sound Card State (restore and store). Sep 05 21:45:00 volumio alsactl[947]: alsactl 1.1.8 daemon started Sep 05 21:45:00 volumio systemd[1]: Starting Save/Restore Sound Card State... Sep 05 21:45:00 volumio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Sep 05 21:45:00 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Sep 05 21:45:00 volumio systemd[1]: Started Save/Restore Sound Card State. Sep 05 21:45:00 volumio haveged[736]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Sep 05 21:45:00 volumio haveged[736]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63488/63488 Sep 05 21:45:00 volumio haveged[736]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99909 Sep 05 21:45:00 volumio haveged[736]: haveged: fills: 0, generated: 0 Sep 05 21:45:00 volumio systemd[1]: Reached target Sound Card. Sep 05 21:45:00 volumio loadcpufreq[811]: Loading cpufreq kernel modules...done (none). Sep 05 21:45:00 volumio systemd[1]: Started ifup for wlan0. Sep 05 21:45:00 volumio systemd[1]: Started Raise network interfaces. Sep 05 21:45:00 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 05 21:45:00 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 05 21:45:00 volumio systemd[1]: Reached target Network. Sep 05 21:45:00 volumio systemd[1]: Starting Music Player Daemon... Sep 05 21:45:00 volumio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Sep 05 21:45:00 volumio systemd[1]: Starting Network Time Service... Sep 05 21:45:00 volumio systemd[1]: Starting Permit User Sessions... Sep 05 21:45:00 volumio systemd[1]: Starting OpenBSD Secure Shell server... Sep 05 21:45:00 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Sep 05 21:45:00 volumio systemd[1]: Reached target Network is Online. Sep 05 21:45:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 21:45:00 volumio systemd[1]: Starting Samba NMB Daemon... Sep 05 21:45:00 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Sep 05 21:45:00 volumio systemd[1]: Starting /etc/rc.local Compatibility... Sep 05 21:45:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 05 21:45:00 volumio systemd[1]: Started Permit User Sessions. Sep 05 21:45:00 volumio systemd[1]: Started /etc/rc.local Compatibility. Sep 05 21:45:00 volumio systemd[1]: Starting Hold until boot process finishes up... Sep 05 21:45:00 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 05 21:45:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 298 (plymouthd). Sep 05 21:45:01 volumio ntpd[993]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 05 21:45:01 volumio ntpd[993]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Sep 05 21:45:01 volumio ntpd[1049]: proto: precision = 0.351 usec (-21) Sep 05 21:45:01 volumio systemd[1]: Started Network Time Service. Sep 05 21:45:01 volumio ntpd[1049]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 05 21:45:01 volumio ntpd[1049]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 05 21:45:01 volumio ntpd[1049]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 252 days ago Sep 05 21:45:01 volumio ntpd[1049]: Listen and drop on 0 v6wildcard [::]:123 Sep 05 21:45:01 volumio ntpd[1049]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 05 21:45:01 volumio ntpd[1049]: Listen normally on 2 lo 127.0.0.1:123 Sep 05 21:45:01 volumio ntpd[1049]: Listening on routing socket on fd #19 for interface updates Sep 05 21:45:01 volumio ntpd[1049]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 05 21:45:01 volumio ntpd[1049]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 05 21:45:01 volumio systemd[1]: plymouth-quit.service: Succeeded. Sep 05 21:45:01 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Sep 05 21:45:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 298 (plymouthd). Sep 05 21:45:01 volumio sshd[1047]: Server listening on 0.0.0.0 port 22. Sep 05 21:45:01 volumio sshd[1047]: Server listening on :: port 22. Sep 05 21:45:01 volumio sudo[990]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 21:45:01 volumio systemd[1]: Started OpenBSD Secure Shell server. Sep 05 21:45:01 volumio sh[973]: wlan0=wlan0 Sep 05 21:45:01 volumio sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:01 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 05 21:45:01 volumio sudo[990]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 05 21:45:01 volumio sudo[990]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:01 volumio systemd[1]: Started Hold until boot process finishes up. Sep 05 21:45:01 volumio systemd[1]: Started Serial Getty on ttyAMA10. Sep 05 21:45:01 volumio systemd[1]: Reached target Login Prompts. Sep 05 21:45:01 volumio cpufrequtils[988]: CPUFreq Utilities: Setting ondemand CPUFreq governor...error: unexpectedly disconnected from boot status daemon Sep 05 21:45:01 volumio ifplugd(eth0)[1070]: ifplugd 0.28 initializing. Sep 05 21:45:01 volumio ifplugd(eth0)[1070]: Using interface eth0/2C:CF:67:ED:78:E7 with driver (version: 6.6.62-v8+) Sep 05 21:45:01 volumio ifplugd(eth0)[1070]: Using detection mode: SIOCETHTOOL Sep 05 21:45:01 volumio ifplugd(eth0)[1070]: Initialization complete, link beat not detected. Sep 05 21:45:01 volumio cpufrequtils[988]: CPU0...CPU1...CPU2...CPU3...done. Sep 05 21:45:01 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 05 21:45:01 volumio ifplugd[1011]: Network Interface Plugging Daemon...start eth0...done. Sep 05 21:45:01 volumio systemd[1]: Started LSB: Brings up/down network automatically. Sep 05 21:45:01 volumio wireless.js[765]: Cleaning previous... Sep 05 21:45:01 volumio sudo[1079]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 05 21:45:01 volumio sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:01 volumio sudo[1079]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:01 volumio sudo[1081]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 05 21:45:01 volumio sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:01 volumio nmbd[1016]: [2025/09/05 21:45:01.259637, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 05 21:45:01 volumio nmbd[1016]: started asyncdns process 1083 Sep 05 21:45:01 volumio kernel: Bluetooth: hci0: BCM: features 0x2f Sep 05 21:45:01 volumio kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Sep 05 21:45:01 volumio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Sep 05 21:45:01 volumio kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Sep 05 21:45:01 volumio bthelper[942]: Raspberry Pi BDADDR already set Sep 05 21:45:01 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Sep 05 21:45:01 volumio systemd[1]: Starting Bluetooth service... Sep 05 21:45:01 volumio bluetoothd[1092]: Bluetooth daemon 5.50 Sep 05 21:45:01 volumio bluetoothd[1092]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 05 21:45:01 volumio systemd[1]: Started Bluetooth service. Sep 05 21:45:01 volumio systemd[1]: Reached target Bluetooth. Sep 05 21:45:01 volumio bluetoothd[1092]: Starting SDP server Sep 05 21:45:01 volumio bluetoothd[1092]: Excluding (cli) sap Sep 05 21:45:01 volumio bluetoothd[1092]: Bluetooth management interface 1.22 initialized Sep 05 21:45:01 volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=1092 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 05 21:45:01 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 05 21:45:01 volumio kernel: Bluetooth: BNEP filters: protocol multicast Sep 05 21:45:01 volumio kernel: Bluetooth: BNEP socket layer initialized Sep 05 21:45:01 volumio kernel: Bluetooth: MGMT ver 1.22 Sep 05 21:45:01 volumio systemd[1]: Starting Hostname Service... Sep 05 21:45:01 volumio bluetoothd[1092]: Failed to set privacy: Rejected (0x0b) Sep 05 21:45:01 volumio mpd[1058]: Sep 05 21:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 21:45:01 volumio systemd[1]: Started Music Player Daemon. Sep 05 21:45:01 volumio mpd_monitor.sh[763]: MPD Monitor Service: MPD restarted due to no mpc output. Sep 05 21:45:01 volumio sudo[1081]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:01 volumio nmbd[1016]: [2025/09/05 21:45:01.768869, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 05 21:45:01 volumio nmbd[1016]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 05 21:45:01 volumio nmbd[1016]: [2025/09/05 21:45:01.768933, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 05 21:45:01 volumio nmbd[1016]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 05 21:45:01 volumio wireless.js[765]: Stopped aP Sep 05 21:45:01 volumio sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 05 21:45:01 volumio sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:01 volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 05 21:45:01 volumio systemd[1]: Started Hostname Service. Sep 05 21:45:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 05 21:45:01 volumio sudo[1103]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:01 volumio sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 05 21:45:01 volumio sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:01 volumio sudo[1105]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:01 volumio sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 05 21:45:01 volumio sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:01 volumio sudo[1112]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:01 volumio sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 05 21:45:01 volumio sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:02 volumio ntpd[1049]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 05 21:45:03 volumio ntpd[1049]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 05 21:45:04 volumio ntpd[1049]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 05 21:45:04 volumio sudo[1114]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:04 volumio wireless.js[765]: SETTING APPROPRIATE REG DOMAIN: PT Sep 05 21:45:04 volumio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 05 21:45:04 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:04 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:04 volumio sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set PT Sep 05 21:45:04 volumio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:04 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:04 volumio wireless.js[765]: SUCCESSFULLY SET NEW REGDOMAIN: PT Sep 05 21:45:04 volumio wireless.js[765]: Start wireless flow Sep 05 21:45:04 volumio wireless.js[765]: Stopped hotspot (if there).. Sep 05 21:45:04 volumio sudo[1126]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 05 21:45:04 volumio sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:04 volumio sudo[1126]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:04 volumio sudo[1128]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 05 21:45:04 volumio sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:04 volumio sudo[1128]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:04 volumio wireless.js[765]: DHCP IP Sep 05 21:45:04 volumio wireless.js[765]: Start ap Sep 05 21:45:04 volumio wpa_supplicant[1130]: Successfully initialized wpa_supplicant Sep 05 21:45:04 volumio sudo[1131]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 05 21:45:04 volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:04 volumio dhcpcd[1132]: dev: loaded udev Sep 05 21:45:04 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 05 21:45:04 volumio dhcpcd-run-hooks[1150]: wlan0: starting wpa_supplicant Sep 05 21:45:04 volumio wpa_supplicant[1130]: ctrl_iface exists and seems to be in use - cannot override it Sep 05 21:45:04 volumio wpa_supplicant[1130]: Delete '/var/run/wpa_supplicant/wlan0' manually if it is not used anymore Sep 05 21:45:04 volumio wpa_supplicant[1130]: Failed to initialize control interface '/var/run/wpa_supplicant'. You may have another wpa_supplicant process already running or the file was left by an unclean termination of wpa_supplicant in which case you will need to manually remove this file before starting wpa_supplicant again. Sep 05 21:45:05 volumio ntpd[1049]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 05 21:45:05 volumio dhcpcd[1132]: wlan0: connected to Access Point `' Sep 05 21:45:05 volumio dhcpcd[1132]: no interfaces have a carrier Sep 05 21:45:05 volumio dhcpcd[1132]: forked to background, child pid 1164 Sep 05 21:45:05 volumio dhcpcd[1164]: eth0: waiting for carrier Sep 05 21:45:05 volumio dhcpcd[1164]: wlan0: waiting for carrier Sep 05 21:45:05 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:05 volumio wpa_supplicant[1130]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 05 21:45:05 volumio volumio-time-update[766]: volumio-time-update-util: Fetching time from Volumio... Sep 05 21:45:05 volumio volumio-time-update[766]: volumio-time-update-util: Date not found in response Sep 05 21:45:05 volumio volumio-time-update[766]: volumio-time-update-util: Retrying in 5 seconds... Sep 05 21:45:05 volumio volumio-remote-updater[767]: [2025-09-05 21:45:05] [info] asio async_connect error: system:111 (Connection refused) Sep 05 21:45:05 volumio volumio-remote-updater[767]: [2025-09-05 21:45:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 21:45:05 volumio volumio-remote-updater[767]: [2025-09-05 21:45:05] [error] handle_connect error: Underlying Transport Error Sep 05 21:45:05 volumio systemd[1]: systemd-rfkill.service: Succeeded. Sep 05 21:45:05 volumio wireless.js[765]: trying... Sep 05 21:45:05 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:05 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:05 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:06 volumio bthelper[942]: Changing power off succeeded Sep 05 21:45:06 volumio bthelper[942]: [CHG] Controller 2C:CF:67:ED:78:E9 Class: 0x0000041c Sep 05 21:45:06 volumio bthelper[942]: Changing power on succeeded Sep 05 21:45:06 volumio wireless.js[765]: trying... Sep 05 21:45:06 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:06 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:06 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:07 volumio wireless.js[765]: trying... Sep 05 21:45:07 volumio sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:07 volumio sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:07 volumio sudo[1178]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:08 volumio kernel: ieee80211 phy0: brcmf_fw_crashed: Firmware has halted or crashed Sep 05 21:45:08 volumio wireless.js[765]: trying... Sep 05 21:45:10 volumio volumio-time-update[766]: volumio-time-update-util: Fetching time from Volumio... Sep 05 21:45:10 volumio volumio-time-update[766]: volumio-time-update-util: Date not found in response Sep 05 21:45:10 volumio volumio-time-update[766]: volumio-time-update-util: Retrying in 5 seconds... Sep 05 21:45:10 volumio kernel: brcmfmac: brcmf_sdio_bus_rxctl: resumed on timeout Sep 05 21:45:10 volumio kernel: brcmfmac: brcmf_sdio_checkdied: firmware trap in dongle Sep 05 21:45:10 volumio kernel: brcmfmac: dongle trap info: type 0x4 @ epc 0x001be5f2 cpsr 0x2000019f spsr 0x200001bf sp 0x0025fe50 lr 0x00049bd7 pc 0x001be5f2 offset 0x25fdf8 r0 0x002568bc r1 0x00000001 r2 0x00010000 r3 0x18001000 r4 0x002568bc r5 0xffffffff r6 0x00000000 r7 0x00000001 Sep 05 21:45:10 volumio kernel: brcmfmac: brcmf_dump_obss: dump_obss error (-110) Sep 05 21:45:10 volumio kernel: ieee80211 phy0: brcmf_fil_cmd_data: bus is down. we have nothing to do. Sep 05 21:45:10 volumio kernel: ieee80211 phy0: brcmf_cfg80211_get_tx_power: error (-5) Sep 05 21:45:10 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:10 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:10 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:10 volumio dhcpcd[1164]: wlan0: removing interface Sep 05 21:45:10 volumio wireless.js[765]: trying... Sep 05 21:45:10 volumio sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:10 volumio sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:10 volumio sudo[1199]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:10 volumio systemd[1]: Stopping ifup for wlan0... Sep 05 21:45:10 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Sep 05 21:45:10 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Sep 05 21:45:10 volumio dhcpcd-run-hooks[1229]: wlan0: stopping wpa_supplicant Sep 05 21:45:10 volumio kernel: mmc1: card 0001 removed Sep 05 21:45:10 volumio systemd[1]: ifup@wlan0.service: Succeeded. Sep 05 21:45:10 volumio systemd[1]: Stopped ifup for wlan0. Sep 05 21:45:10 volumio kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Sep 05 21:45:10 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Sep 05 21:45:10 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Sep 05 21:45:10 volumio kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 Sep 05 21:45:11 volumio kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Sep 05 21:45:11 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Sep 05 21:45:11 volumio systemd-udevd[1189]: Using default interface naming scheme 'v240'. Sep 05 21:45:11 volumio systemd-udevd[1189]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 05 21:45:11 volumio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Sep 05 21:45:11 volumio dhcpcd-run-hooks[1262]: wlan0: starting wpa_supplicant Sep 05 21:45:11 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled Sep 05 21:45:11 volumio systemd[1]: Started ifup for wlan0. Sep 05 21:45:11 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 05 21:45:11 volumio sh[1263]: wlan0=wlan0 Sep 05 21:45:11 volumio dhcpcd[1164]: wlan0: connected to Access Point `' Sep 05 21:45:11 volumio dhcpcd[1164]: wlan0: waiting for carrier Sep 05 21:45:11 volumio wireless.js[765]: trying... Sep 05 21:45:11 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:11 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:11 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:12 volumio wireless.js[765]: trying... Sep 05 21:45:12 volumio sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:12 volumio sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:12 volumio sudo[1324]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:13 volumio volumio-remote-updater[767]: [2025-09-05 21:45:13] [info] asio async_connect error: system:111 (Connection refused) Sep 05 21:45:13 volumio volumio-remote-updater[767]: [2025-09-05 21:45:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 21:45:13 volumio volumio-remote-updater[767]: [2025-09-05 21:45:13] [error] handle_connect error: Underlying Transport Error Sep 05 21:45:13 volumio wireless.js[765]: trying... Sep 05 21:45:13 volumio sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:13 volumio sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:13 volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:14 volumio wireless.js[765]: trying... Sep 05 21:45:14 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:14 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:14 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:15 volumio volumio-time-update[766]: volumio-time-update-util: Fetching time from Volumio... Sep 05 21:45:15 volumio volumio-time-update[766]: volumio-time-update-util: Date not found in response Sep 05 21:45:15 volumio volumio-time-update[766]: volumio-time-update-util: Retrying in 5 seconds... Sep 05 21:45:15 volumio wireless.js[765]: trying... Sep 05 21:45:15 volumio sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:15 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:15 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:16 volumio systemd[1]: systemd-rfkill.service: Succeeded. Sep 05 21:45:16 volumio wireless.js[765]: trying... Sep 05 21:45:16 volumio sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:16 volumio sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:16 volumio sudo[1341]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:17 volumio dhcpcd[1164]: wlan0: carrier acquired Sep 05 21:45:17 volumio dhcpcd[1164]: wlan0: connected to Access Point `NOS-7696-5' Sep 05 21:45:17 volumio dhcpcd[1164]: DUID 00:01:00:01:30:4e:01:fb:2c:cf:67:ed:78:e8 Sep 05 21:45:17 volumio dhcpcd[1164]: wlan0: IAID 67:ed:78:e8 Sep 05 21:45:17 volumio dhcpcd[1164]: wlan0: adding address fe80::9b99:1a54:56f4:4e2 Sep 05 21:45:17 volumio dhcpcd[1164]: ipv6_addaddr1: Permission denied Sep 05 21:45:17 volumio dhcpcd[1164]: wlan0: rebinding lease of 192.168.1.149 Sep 05 21:45:17 volumio dhcpcd[1164]: wlan0: probing address 192.168.1.149/24 Sep 05 21:45:17 volumio dhcpcd[1164]: wlan0: soliciting an IPv6 router Sep 05 21:45:17 volumio wireless.js[765]: trying... Sep 05 21:45:17 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:17 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:17 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:18 volumio wireless.js[765]: trying... Sep 05 21:45:18 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:18 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:18 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:19 volumio wireless.js[765]: trying... Sep 05 21:45:19 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:19 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:19 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:20 volumio volumio-time-update[766]: volumio-time-update-util: Fetching time from Volumio... Sep 05 21:45:20 volumio wireless.js[765]: trying... Sep 05 21:45:20 volumio volumio-time-update[766]: volumio-time-update-util: Date not found in response Sep 05 21:45:20 volumio volumio-time-update[766]: volumio-time-update-util: Retrying in 5 seconds... Sep 05 21:45:20 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:20 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:20 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:21 volumio wireless.js[765]: trying... Sep 05 21:45:21 volumio sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:21 volumio sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:21 volumio sudo[1362]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:22 volumio wireless.js[765]: trying... Sep 05 21:45:22 volumio sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:22 volumio sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:22 volumio sudo[1368]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:22 volumio dhcpcd[1164]: wlan0: leased 192.168.1.149 for 3600 seconds Sep 05 21:45:22 volumio avahi-daemon[793]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.149. Sep 05 21:45:22 volumio avahi-daemon[793]: New relevant interface wlan0.IPv4 for mDNS. Sep 05 21:45:22 volumio dhcpcd[1164]: wlan0: adding route to 192.168.1.0/24 Sep 05 21:45:22 volumio avahi-daemon[793]: Registering new address record for 192.168.1.149 on wlan0.IPv4. Sep 05 21:45:22 volumio dhcpcd[1164]: wlan0: adding default route via 192.168.1.1 Sep 05 21:45:23 volumio volumio-remote-updater[767]: [2025-09-05 21:45:23] [info] asio async_connect error: system:111 (Connection refused) Sep 05 21:45:23 volumio volumio-remote-updater[767]: [2025-09-05 21:45:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 21:45:23 volumio volumio-remote-updater[767]: [2025-09-05 21:45:23] [error] handle_connect error: Underlying Transport Error Sep 05 21:45:23 volumio wireless.js[765]: trying... Sep 05 21:45:23 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 05 21:45:23 volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:23 volumio sudo[1392]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:23 volumio wireless.js[765]: Connected to: ----NOS-7696-5 Sep 05 21:45:23 volumio wireless.js[765]: ---- Sep 05 21:45:23 volumio sudo[1395]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 21:45:23 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:23 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:23 volumio wireless.js[765]: ... joined AP, wlan0 IPv4 is 192.168.1.149, ipV6 is undefined Sep 05 21:45:23 volumio wireless.js[765]: It's done! AP Sep 05 21:45:23 volumio systemd[1]: Started Wireless Services. Sep 05 21:45:23 volumio systemd[1]: Started Volumio Backend Module. Sep 05 21:45:23 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 05 21:45:23 volumio systemd[1]: Started Volumio Cpu Tweaker. Sep 05 21:45:23 volumio volumio-cpu-tweak[1401]: Setting RT Priority for mpd Sep 05 21:45:23 volumio volumio-cpu-tweak[1401]: Setting MPD Affinity Sep 05 21:45:23 volumio volumio-cpu-tweak[1401]: pid 1058's current affinity mask: f Sep 05 21:45:23 volumio volumio-cpu-tweak[1401]: pid 1058's new affinity mask: 3 Sep 05 21:45:23 volumio volumio-cpu-tweak[1401]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 05 21:45:23 volumio volumio-cpu-tweak[1401]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Sep 05 21:45:23 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 05 21:45:24 volumio ntpd[1049]: Listen normally on 3 wlan0 192.168.1.149:123 Sep 05 21:45:24 volumio ntpd[1049]: new interface(s) found: waking up resolver Sep 05 21:45:24 volumio volumio[1400]: info: ------------------------------------------- Sep 05 21:45:24 volumio volumio[1400]: info: ----- Volumio3 ---- Sep 05 21:45:24 volumio volumio[1400]: info: ------------------------------------------- Sep 05 21:45:24 volumio volumio[1400]: info: ----- System startup ---- Sep 05 21:45:24 volumio volumio[1400]: info: ------------------------------------------- Sep 05 21:45:24 volumio volumio[1400]: info: MYVOLUMIO Environment detected Sep 05 21:45:24 volumio volumio[1400]: info: Plugin folders cleanup Sep 05 21:45:24 volumio volumio[1400]: info: Scanning into folder /volumio/app/plugins/ Sep 05 21:45:24 volumio volumio[1400]: info: Scanning category audio_interface Sep 05 21:45:24 volumio volumio[1400]: info: Scanning category miscellanea Sep 05 21:45:24 volumio volumio[1400]: info: Scanning category music_service Sep 05 21:45:24 volumio volumio[1400]: info: Scanning category plugins.json Sep 05 21:45:24 volumio volumio[1400]: info: Scanning category system_controller Sep 05 21:45:24 volumio volumio[1400]: info: Scanning category user_interface Sep 05 21:45:24 volumio volumio[1400]: info: Scanning into folder /data/plugins/ Sep 05 21:45:24 volumio volumio[1400]: info: Scanning category user_interface Sep 05 21:45:24 volumio volumio[1400]: info: Plugin folders cleanup completed Sep 05 21:45:24 volumio volumio[1400]: info: ------------------------------------------- Sep 05 21:45:24 volumio volumio[1400]: info: ----- Core plugins startup ---- Sep 05 21:45:24 volumio volumio[1400]: info: ------------------------------------------- Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 21:45:24 volumio volumio[1400]: info: Adding plugin upnp to MyMusic Plugins Sep 05 21:45:24 volumio volumio[1400]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 21:45:24 volumio volumio[1400]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugins from folder /data/plugins/ Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugin "system"... Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugin "appearance"... Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugin "network"... Sep 05 21:45:24 volumio volumio[1400]: info: Refreshing Cached IP Addresses Sep 05 21:45:24 volumio sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 21:45:24 volumio sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:24 volumio sudo[1431]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:24 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 21:45:24 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:24 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugin "services"... Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugin "alsa_controller"... Sep 05 21:45:24 volumio sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 05 21:45:24 volumio sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:24 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugin "wizard"... Sep 05 21:45:24 volumio volumio[1400]: info: Loading plugin "networkfs"... Sep 05 21:45:25 volumio volumio[1400]: info: Starting Udev Watcher for removable devices Sep 05 21:45:25 volumio volumio[1400]: info: Ignoring mount for partition: boot Sep 05 21:45:25 volumio volumio[1400]: info: Ignoring mount for partition: volumio Sep 05 21:45:25 volumio volumio[1400]: info: Ignoring mount for partition: volumio_data Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "volumio_command_line_client"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "upnp"... Sep 05 21:45:25 volumio volumio[1400]: info: [1757105125011] Starting Upmpd Daemon Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "my_music"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "mpd"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "upnp_browser"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "alarm-clock"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "airplay_emulation"... Sep 05 21:45:25 volumio volumio[1400]: info: Starting Shairport Sync Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "last_100"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "webradio"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "i2s_dacs"... Sep 05 21:45:25 volumio volumio[1400]: info: I2S DAC not set, start Auto-detection Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "volumiodiscovery"... Sep 05 21:45:25 volumio volumio[1400]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 21:45:25 volumio node[1400]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 21:45:25 volumio node[1400]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 21:45:25 volumio volumio[1400]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 21:45:25 volumio volumio[1400]: *** WARNING *** For more information see Sep 05 21:45:25 volumio volumio[1400]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 21:45:25 volumio volumio[1400]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 21:45:25 volumio volumio[1400]: *** WARNING *** For more information see Sep 05 21:45:25 volumio node[1400]: *** WARNING *** For more information see Sep 05 21:45:25 volumio node[1400]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 21:45:25 volumio node[1400]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 21:45:25 volumio node[1400]: *** WARNING *** For more information see Sep 05 21:45:25 volumio volumio[1400]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 21:45:25 volumio volumio[1400]: info: Discovery: Started advertising with name: Volumio Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "outputs"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "albumart"... Sep 05 21:45:25 volumio volumio[1400]: info: Plugin example_plugin is not enabled Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "inputs"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "updater_comm"... Sep 05 21:45:25 volumio volumio[1400]: info: Plugin mpdemulation is not enabled Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "rest_api"... Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "websocket"... Sep 05 21:45:25 volumio volumio[1400]: info: Starting Socket.io Server version 2.3.0 Sep 05 21:45:25 volumio volumio[1400]: info: Loading plugin "touch_display"... Sep 05 21:45:25 volumio volumio[1400]: Forking 3 albumart workers Sep 05 21:45:25 volumio volumio[1400]: info: Applying required configuration parameters for plugin touch_display Sep 05 21:45:25 volumio volumio[1400]: info: Loading i18n strings for locale pt Sep 05 21:45:25 volumio volumio[1400]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 05 21:45:25 volumio volumio[1400]: Updating browse sources language Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 21:45:25 volumio volumio[1400]: Starting albumart workers Sep 05 21:45:25 volumio volumio[1400]: Starting albumart workers Sep 05 21:45:25 volumio volumio[1400]: Starting albumart workers Sep 05 21:45:25 volumio volumio-time-update[766]: volumio-time-update-util: Fetching time from Volumio... Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::initPlayerControls Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: Express server listening on port 3000 Sep 05 21:45:25 volumio volumio[1400]: [Metrics] WebUI: 1s 608.28ms Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::resetVolumioState Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::getcurrentVolume Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: Cannot read play queue from file Sep 05 21:45:25 volumio volumio[1400]: info: Volumio Network Manager: Network status updated: 2 Sep 05 21:45:25 volumio volumio[1400]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 05 21:45:25 volumio volumio[1400]: info: VolumeController:: Volume=100 Mute =false Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:25 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::updateTrackBlock Sep 05 21:45:25 volumio volumio[1400]: info: CorePlayQueue::getTrackBlock Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::setRepeat null single undefined Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:25 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::setRandom null Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:25 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:25 volumio volumio[1400]: info: Setting Device type: Raspberry PI Sep 05 21:45:25 volumio volumio[1400]: info: Completed loading Core Plugins Sep 05 21:45:25 volumio volumio[1400]: info: Preparing to generate the ALSA configuration file Sep 05 21:45:25 volumio volumio[1400]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 05 21:45:25 volumio volumio[1400]: info: USB Boot Capable - System SBC Revision found in cpuinfo: e04171 Sep 05 21:45:25 volumio volumio[1400]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 05 21:45:25 volumio volumio[1400]: info: VolumeController:: Volume=100 Mute =false Sep 05 21:45:25 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:25 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:25 volumio volumio[1400]: info: Asound.conf file written Sep 05 21:45:25 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 05 21:45:25 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:25 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:25 volumio volumio[1400]: info: Output device has changed, restarting MPD Sep 05 21:45:25 volumio sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 21:45:25 volumio volumio[1400]: info: Output device has changed, restarting Shairport Sync Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:25 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:25 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:25 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 21:45:25 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:25 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 21:45:25 volumio volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 21:45:25 volumio volumio[1400]: info: ___________ START PLUGINS ___________ Sep 05 21:45:25 volumio volumio[1400]: info: ControllerMpd::onStart: Initializing MPD Sep 05 21:45:25 volumio volumio[1400]: info: Creating MPD Configuration file Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 21:45:25 volumio sudo[1545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 21:45:25 volumio volumio[1400]: info: [1757105125910] CoreMusicLibrary::Adding element Servidores multimédia Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 21:45:25 volumio sudo[1545]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:25 volumio sudo[1545]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:25 volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 21:45:25 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 21:45:25 volumio systemd[1]: mpd.service: Succeeded. Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:25 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 21:45:25 volumio volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 21:45:25 volumio volumio[1400]: info: [1757105125943] CoreMusicLibrary::Adding element Last_100 Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 21:45:25 volumio volumio[1400]: info: [1757105125944] CoreMusicLibrary::Adding element Webradio Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 21:45:25 volumio volumio[1400]: info: Initializing BBC Radios Sep 05 21:45:25 volumio systemd[1]: Starting Music Player Daemon... Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:25 volumio volumio[1400]: info: Loading i18n strings for locale pt Sep 05 21:45:25 volumio volumio[1400]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 05 21:45:25 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 21:45:25 volumio volumio[1400]: info: Volumio Calling Home Sep 05 21:45:25 volumio sudo[1553]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 21:45:25 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:25 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:25 volumio sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 05 21:45:25 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 05 21:45:26 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 05 21:45:26 volumio sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio volumio-time-update[766]: volumio-time-update-util: Setting system time to: 2025-09-05 21:45:25 Sep 05 21:45:26 volumio systemd[1]: Reloading. Sep 05 21:45:26 volumio sudo[1582]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-05 21:45:25 Sep 05 21:45:26 volumio sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio volumio[1400]: info: touch_display: Backlight interface detected. Sep 05 21:45:26 volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.12' (uid=0 pid=1583 comm="timedatectl set-time 2025-09-05 21:45:25 ") Sep 05 21:45:26 volumio volumio[1400]: info: touch_display: systemctl stop getty@tty1.service succeeded. Sep 05 21:45:26 volumio volumio[1400]: info: MPD Permissions set Sep 05 21:45:26 volumio volumio[1400]: info: MPD Permissions set Sep 05 21:45:26 volumio volumio[1400]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:26 volumio volumio[1400]: info: touch_display: Raspberry Pi Foundation touch screen detected. Sep 05 21:45:26 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 05 21:45:26 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio volumio[1400]: info: Starting Shairport Sync Sep 05 21:45:26 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio volumio[1400]: info: Starting Shairport Sync Sep 05 21:45:26 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/11-0045/brightness Sep 05 21:45:26 volumio volumio[1400]: info: Starting Shairport Sync Sep 05 21:45:26 volumio sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 21:45:26 volumio sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio volumio[1400]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Sep 05 21:45:26 volumio sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 21:45:26 volumio sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 21:45:26 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 05 21:45:26 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:26 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:26 volumio volumio[1400]: info: touch_display: File permissions for backlight brightness control set. Sep 05 21:45:26 volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 05 21:45:26 volumio systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 05 21:45:26 volumio mpd[1571]: Sep 05 21:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 21:45:26 volumio systemd[1]: Started Music Player Daemon. Sep 05 21:45:26 volumio sudo[1578]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio systemd[1]: Reloading. Sep 05 21:45:26 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio volumio[1400]: info: touch_display: systemctl daemon-reload succeeded. Sep 05 21:45:26 volumio volumio[1400]: info: Volumio called home Sep 05 21:45:26 volumio volumio[1400]: info: MPD running with PID1571 Sep 05 21:45:26 volumio volumio[1400]: ,establishing connection Sep 05 21:45:26 volumio sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 05 21:45:26 volumio sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:26 volumio volumio[1400]: error: MPD error: The expression evaluated to a falsy value: Sep 05 21:45:26 volumio volumio[1400]: assert.ok(self.idling) Sep 05 21:45:26 volumio volumio[1400]: error: The expression evaluated to a falsy value: Sep 05 21:45:26 volumio volumio[1400]: assert.ok(self.idling) Sep 05 21:45:26 volumio volumio[1400]: error: MPD error: The expression evaluated to a falsy value: Sep 05 21:45:26 volumio volumio[1400]: assert.ok(self.idling) Sep 05 21:45:26 volumio volumio[1400]: error: The expression evaluated to a falsy value: Sep 05 21:45:26 volumio volumio[1400]: assert.ok(self.idling) Sep 05 21:45:26 volumio volumio[1400]: info: Discovery: adding 1c566a98-fe70-460b-a72f-24a837f1c4e3 Sep 05 21:45:26 volumio volumio[1400]: info: Discovery: Found device Volumio Sep 05 21:45:26 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:26 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:26 volumio volumio[1400]: error: updateQueue error: null Sep 05 21:45:26 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 05 21:45:26 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio volumio[1400]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 05 21:45:26 volumio sudo[1627]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio volumio[1400]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Sep 05 21:45:26 volumio volumio[1400]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Sep 05 21:45:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 21:45:26 volumio volumio[1400]: info: Completed starting Core Plugins Sep 05 21:45:26 volumio volumio[1400]: info: ------------------------------------------- Sep 05 21:45:26 volumio volumio[1400]: info: ----- MyVolumio plugins startup ---- Sep 05 21:45:26 volumio volumio[1400]: info: ------------------------------------------- Sep 05 21:45:26 volumio volumio[1400]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 05 21:45:26 volumio systemd[1]: Starting Time & Date Service... Sep 05 21:45:26 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 05 21:45:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 21:45:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 21:45:26 volumio sudo[1611]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio sudo[1613]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio volumio[1400]: info: Shairport-Sync Started Sep 05 21:45:26 volumio volumio[1400]: Error adding Membership: Error: addMembership EINVAL Sep 05 21:45:26 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:26 volumio volumio[1400]: info: Shairport-Sync Started Sep 05 21:45:26 volumio volumio[1400]: info: Shairport-Sync Started Sep 05 21:45:26 volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 05 21:45:26 volumio systemd[1]: Started Time & Date Service. Sep 05 21:45:25 volumio systemd-timedated[1642]: Changed local time to Fri Sep 5 21:45:25 2025 Sep 05 21:45:25 volumio sudo[1582]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:25 volumio volumio-time-update[766]: volumio-time-update-util: System time updated successfully. Sep 05 21:45:25 volumio systemd[1]: Started Volumio Time Update Utility. Sep 05 21:45:25 volumio nmbd[1016]: [2025/09/05 21:45:25.185122, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 05 21:45:25 volumio systemd[1]: Started Samba NMB Daemon. Sep 05 21:45:25 volumio nmbd[1016]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 05 21:45:25 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 05 21:45:25 volumio winbindd[1647]: [2025/09/05 21:45:25.285462, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 05 21:45:25 volumio winbindd[1647]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 05 21:45:25 volumio winbindd[1647]: [2025/09/05 21:45:25.288986, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 05 21:45:25 volumio winbindd[1647]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 05 21:45:25 volumio systemd[1]: Started Samba Winbind Daemon. Sep 05 21:45:25 volumio systemd[1]: Starting Samba SMB Daemon... Sep 05 21:45:25 volumio smbd[1652]: [2025/09/05 21:45:25.407108, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 05 21:45:25 volumio systemd[1]: Started Samba SMB Daemon. Sep 05 21:45:25 volumio smbd[1652]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 05 21:45:25 volumio systemd[1]: Reached target Multi-User System. Sep 05 21:45:25 volumio systemd[1]: Reached target Graphical Interface. Sep 05 21:45:25 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 05 21:45:25 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 05 21:45:25 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 05 21:45:25 volumio systemd[1]: Startup finished in 12.030s (kernel) + 27.906s (userspace) = 39.936s. Sep 05 21:45:26 volumio sudo[1443]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:28 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 05 21:45:29 volumio sh[710]: timed out Sep 05 21:45:29 volumio dhcpcd[757]: timed out Sep 05 21:45:29 volumio sh[710]: dhcpcd exited Sep 05 21:45:29 volumio dhcpcd[757]: dhcpcd exited Sep 05 21:45:29 volumio sh[710]: ifup: failed to bring up eth0 Sep 05 21:45:29 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 05 21:45:29 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 05 21:45:29 volumio volumio[1400]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 05 21:45:30 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 05 21:45:33 volumio volumio-remote-updater[767]: [2025-09-05 21:45:33] [connect] Successful connection Sep 05 21:45:33 volumio volumio-remote-updater[767]: [2025-09-05 21:45:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757105133 101 Sep 05 21:45:33 volumio volumio[1400]: 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: 3 Sep 05 21:45:33 volumio sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 21:45:33 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:33 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:33 volumio sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 21:45:33 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:33 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:33 volumio sudo[1687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 21:45:33 volumio sudo[1687]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:33 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 05 21:45:33 volumio sudo[1687]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:33 volumio volumio[1400]: info: Upmpdcli Daemon Started Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 05 21:45:33 volumio volumio[1400]: info: Adding plugin bluetooth to MyMusic Plugins Sep 05 21:45:33 volumio volumio[1400]: info: Adding plugin multiroom to MyMusic Plugins Sep 05 21:45:33 volumio volumio[1400]: info: Adding plugin metavolumio to MyMusic Plugins Sep 05 21:45:33 volumio volumio[1400]: info: Adding plugin cd_controller to MyMusic Plugins Sep 05 21:45:33 volumio volumio[1400]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 05 21:45:33 volumio volumio[1400]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 05 21:45:33 volumio volumio[1400]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 05 21:45:33 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:33 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:33 volumio volumio[1400]: info: Starting MyVolumio Remote Streaming Endpoints Sep 05 21:45:33 volumio volumio[1400]: info: MyVolumio login type: Token Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 05 21:45:33 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 05 21:45:34 volumio volumio[1400]: info: Starting Streaming Service Transparent Proxy Sep 05 21:45:34 volumio volumio[1400]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 05 21:45:34 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 05 21:45:34 volumio volumio[1400]: info: Streaming services startup Sep 05 21:45:34 volumio volumio[1400]: info: Starting Streaming Daemon Sep 05 21:45:34 volumio sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 05 21:45:34 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:34 volumio volumio[1400]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 05 21:45:34 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:34 volumio volumio[1400]: error: Cannot start Volumio Streaming Daemon Sep 05 21:45:34 volumio volumio[1400]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 05 21:45:34 volumio volumio[1400]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 05 21:45:34 volumio volumio[1689]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 05 21:45:34 volumio volumio[1400]: STREAMING PROXY: Starting server on port 3245 Sep 05 21:45:34 volumio volumio[1400]: Node JS runtime: 14 Sep 05 21:45:34 volumio volumio[1400]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 05 21:45:35 volumio volumio[1400]: info: MyVolumio token set successfully Sep 05 21:45:35 volumio volumio[1400]: info: MYVOLUMIO: Adding device Sep 05 21:45:35 volumio volumio[1400]: info: MYVOLUMIO: Evaluating Server Sep 05 21:45:35 volumio volumio[1400]: info: MyVolumio Plan changed: premium Sep 05 21:45:35 volumio volumio[1400]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 05 21:45:35 volumio volumio[1400]: info: Removing browser output: myVolumio user plan is not superstar Sep 05 21:45:35 volumio volumio[1400]: info: Removing audio output: Sep 05 21:45:35 volumio volumio[1400]: info: MYVOLUMIO: Adding device Sep 05 21:45:35 volumio volumio[1400]: info: MYVOLUMIO: Evaluating Server Sep 05 21:45:35 volumio volumio[1400]: info: Remote config written successfully Sep 05 21:45:35 volumio volumio[1400]: info: Starting Tunnel 1 Sep 05 21:45:35 volumio volumio[1400]: info: Starting Tunnel Connection Checker Sep 05 21:45:35 volumio volumio[1400]: info: Completed starting MyVolumio Plugin Sep 05 21:45:35 volumio volumio[1400]: info: MYVolumio Device enabled Sep 05 21:45:35 volumio volumio[1400]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 05 21:45:35 volumio volumio[1400]: info: MyVolumio status changed Sep 05 21:45:35 volumio volumio[1400]: info: Streaming services startup Sep 05 21:45:35 volumio volumio[1400]: info: Starting Streaming Daemon Sep 05 21:45:35 volumio sudo[1752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 05 21:45:35 volumio sudo[1752]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:35 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 05 21:45:35 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 05 21:45:35 volumio sudo[1752]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:35 volumio volumio[1400]: info: [1757105135906] Starting BluetoothController Sep 05 21:45:35 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Sep 05 21:45:36 volumio sudo[1755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 05 21:45:36 volumio sudo[1755]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio sudo[1755]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: MRS: MultiRoom plugin initialized Sep 05 21:45:36 volumio volumio[1400]: info: MRS: STOPPING SNAPCLIENT Sep 05 21:45:36 volumio volumio[1400]: info: MRS: Snap server stop Sep 05 21:45:36 volumio sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 05 21:45:36 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio volumio[1400]: info: MRS: STOPPING volumioStreaming Sep 05 21:45:36 volumio sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 05 21:45:36 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 05 21:45:36 volumio sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 05 21:45:36 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 05 21:45:36 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 05 21:45:36 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 05 21:45:36 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Sep 05 21:45:36 volumio volumio[1400]: info: Preparing to generate the ALSA configuration file Sep 05 21:45:36 volumio volumio[1400]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 05 21:45:36 volumio volumio[1400]: info: Reading ALSA contributions from plugins. Sep 05 21:45:36 volumio volumio[1400]: info: Setting Geolocation for MyVolumio to eu3 Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: Setting Geolocation for MyVolumio to eu3 Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: MRS: Removed streaming files Sep 05 21:45:36 volumio volumio[1400]: info: MRS: volumioStreaming STOPPED Sep 05 21:45:36 volumio volumio[1400]: info: MRS: SNAPSERVER STOPPED Sep 05 21:45:36 volumio volumio[1400]: info: MRS: SNAPCLIENT STOPPED Sep 05 21:45:36 volumio volumio[1400]: error: Cannot start Volumio Streaming Daemon Sep 05 21:45:36 volumio volumio[1400]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 05 21:45:36 volumio volumio[1400]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 05 21:45:36 volumio volumio[1400]: info: Asound.conf file written Sep 05 21:45:36 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 05 21:45:36 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: Output device has changed, restarting MPD Sep 05 21:45:36 volumio sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 21:45:36 volumio sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio volumio[1400]: info: Output device has changed, restarting Shairport Sync Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:36 volumio sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 21:45:36 volumio sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio sudo[1788]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 21:45:36 volumio volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 21:45:36 volumio volumio[1400]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:36 volumio systemd[1]: mpd.service: Succeeded. Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 21:45:36 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Sep 05 21:45:36 volumio volumio[1400]: info: MRS: Pushing multiroomSync output for this device Sep 05 21:45:36 volumio volumio[1400]: info: MRS: Pushing multiroomSync output Sep 05 21:45:36 volumio sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Sep 05 21:45:36 volumio volumio[1400]: info: Adding audio output: Sep 05 21:45:36 volumio volumio[1400]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Sep 05 21:45:36 volumio volumio[1400]: info: Adding METAVOLUMIO REST API Endpoints Sep 05 21:45:36 volumio volumio[1400]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 05 21:45:36 volumio volumio[1400]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 05 21:45:36 volumio volumio[1400]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 05 21:45:36 volumio volumio[1400]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Sep 05 21:45:36 volumio volumio[1400]: info: Preparing CD Folders Sep 05 21:45:36 volumio volumio[1400]: info: Adding CD REST API Endpoints Sep 05 21:45:36 volumio volumio[1400]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 05 21:45:36 volumio volumio[1400]: info: Starting UDEV Watcher for CD Sep 05 21:45:36 volumio volumio[1400]: info: Detecting CD presence with UDEV Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Sep 05 21:45:36 volumio systemd[1]: Starting Music Player Daemon... Sep 05 21:45:36 volumio sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio sudo[1799]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Sep 05 21:45:36 volumio volumio[1400]: info: Adding inputs REST Endpoints Sep 05 21:45:36 volumio volumio[1400]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Sep 05 21:45:36 volumio volumio[1400]: info: Scanning Audio Inputs Sep 05 21:45:36 volumio volumio[1400]: info: Checking against Known Cards name Sep 05 21:45:36 volumio volumio[1400]: info: Adding Server instance for streaming Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Sep 05 21:45:36 volumio volumio[1400]: error: Hi Res Audio Failed Login: Missing Login Data Sep 05 21:45:36 volumio sudo[1800]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 21:45:36 volumio sudo[1800]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio volumio[1400]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 05 21:45:36 volumio volumio[1400]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 05 21:45:36 volumio sudo[1800]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Sep 05 21:45:36 volumio volumio[1400]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Sep 05 21:45:36 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 05 21:45:36 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: QobuzConnect: Starting Qobuz Connect socket and service Sep 05 21:45:36 volumio volumio[1400]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Sep 05 21:45:36 volumio volumio[1400]: info: Stopping AccessToken refresher cron Sep 05 21:45:36 volumio sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 05 21:45:36 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio volumio[1400]: info: AccessToken refresher cron started Sep 05 21:45:36 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: Stopping AccessToken refresher cron for QOBUZ Sep 05 21:45:36 volumio volumio[1400]: info: AccessToken refresher cron started for QOBUZ Sep 05 21:45:36 volumio volumio[1400]: info: Adding TIDAL REST API Endpoints Sep 05 21:45:36 volumio volumio[1400]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 05 21:45:36 volumio volumio[1400]: info: Adding QOBUZ REST API Endpoints Sep 05 21:45:36 volumio volumio[1400]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 05 21:45:36 volumio sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 05 21:45:36 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio volumio[1400]: info: Bluetooth name changed to Volumio Sep 05 21:45:36 volumio volumio[1400]: info: MPD Permissions set Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:36 volumio volumio[1400]: info: Starting Shairport Sync Sep 05 21:45:36 volumio sudo[1827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 21:45:36 volumio sudo[1827]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:36 volumio systemd[1]: Started Volumio Qobuz Connect Service. Sep 05 21:45:36 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 21:45:36 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 05 21:45:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 21:45:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 21:45:36 volumio sudo[1827]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: info: Shairport-Sync Started Sep 05 21:45:36 volumio volumio[1400]: info: Executing endpoint qc_getconfig Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.736 [1822.1822] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 05 21:45:36 volumio volumio[1400]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 05 21:45:36 volumio volumio[1400]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.764 [1822.1822] INFO VolumeManager: [0x1512b78]: Setting new playback volume: 75 Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.764 [1822.1822] INFO VolumeManager: [0x1512b78]: Setting new mute state: 0 Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.764 [1822.1822] INFO QobuzConnect: [0x1513548]: Client initialized! Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.764 [1822.1822] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.767 [1822.1822] INFO LocalConfigManager: [0x1512458]: Starting Local Configuration server Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.767 [1822.1822] INFO SampleApp: Starting Local configuration server Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.767 [1822.1822] INFO SampleApp: Connected to UNIX socket client 0x1507f60 Sep 05 21:45:36 volumio mpd[1808]: Sep 05 21:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 21:45:36 volumio systemd[1]: Started Music Player Daemon. Sep 05 21:45:36 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:36 volumio volumio[1400]: error: updateQueue error: null Sep 05 21:45:36 volumio qobuz-connect[1822]: 20250905 21:45:36.886 [1822.1822] INFO SampleApp: Playback volume changed: 75 Sep 05 21:45:36 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:36 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:37 volumio volumio[1400]: info: Successfully Added MyVolumio device Sep 05 21:45:37 volumio volumio[1400]: info: Successfully Added MyVolumio device Sep 05 21:45:37 volumio volumio[1400]: info: Updating MyVolumio device info Sep 05 21:45:37 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:37 volumio volumio[1400]: info: Updating MyVolumio device info Sep 05 21:45:37 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:37 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:37 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:37 volumio volumio[1689]: ........................................................................++++ Sep 05 21:45:38 volumio volumio[1689]: ..................++++ Sep 05 21:45:38 volumio volumio[1689]: e is 65537 (0x010001) Sep 05 21:45:38 volumio volumio[1689]: writing RSA key Sep 05 21:45:39 volumio volumio[1400]: info: Successfully Updated MyVolumio device Sep 05 21:45:39 volumio volumio[1400]: info: Successfully Updated MyVolumio device Sep 05 21:45:39 volumio sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 05 21:45:39 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:39 volumio systemd[1]: Started Volumio Bluetooth Module. Sep 05 21:45:39 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:39 volumio volumio[1400]: info: Volumio BT Module successfully started Sep 05 21:45:39 volumio sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 05 21:45:39 volumio sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:39 volumio volumiobt[1854]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 05 21:45:39 volumio sudo[1855]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:39 volumio volumio[1400]: info: TidalConnect service stoped! Sep 05 21:45:39 volumio volumio[1400]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 05 21:45:39 volumio volumio[1400]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 05 21:45:39 volumio sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 05 21:45:39 volumio sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:39 volumio systemd[1]: Started Volumio Tidal Connect Service. Sep 05 21:45:39 volumio sudo[1867]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:39 volumio volumio[1400]: info: Executing endpoint tc_getconfig Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 05 21:45:39 volumio vtcs[1869]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 05 21:45:39 volumio vtcs[1869]: STARTED TidalConnect services. Sep 05 21:45:39 volumio volumio[1400]: info: Executing endpoint tc_connect Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 05 21:45:39 volumio volumio[1400]: info: Connecting to TidalConnect Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::servicePushState Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output update for this device Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 05 21:45:39 volumio volumio[1400]: verbose: CURRENT POSITION 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::syncState stateService stop Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::syncState currentStatus stop Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output update for this device Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: No code Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output update for this device Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::servicePushState Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output update for this device Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 05 21:45:39 volumio volumio[1400]: verbose: CURRENT POSITION 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::syncState stateService stop Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::syncState currentStatus stop Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output update for this device Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: No code Sep 05 21:45:39 volumio volumio[1400]: info: CoreStateMachine::pushState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioPushState Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output update for this device Sep 05 21:45:39 volumio volumio[1400]: info: MRS: Pushing multiroomSync output Sep 05 21:45:39 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:39 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:40 volumio sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 05 21:45:40 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:40 volumio volumiobt[1854]: pulseaudio: no process found Sep 05 21:45:40 volumio sudo[1891]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:40 volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=1901 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 05 21:45:40 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Sep 05 21:45:40 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:40 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 05 21:45:40 volumio volumio[1400]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 05 21:45:40 volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 05 21:45:40 volumio systemd[1]: Started MyVolumio SSH Tunnel. Sep 05 21:45:40 volumio rtkit-daemon[1903]: Successfully called chroot. Sep 05 21:45:40 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 05 21:45:40 volumio rtkit-daemon[1903]: Successfully dropped privileges. Sep 05 21:45:40 volumio rtkit-daemon[1903]: Successfully limited resources. Sep 05 21:45:40 volumio rtkit-daemon[1903]: Running. Sep 05 21:45:40 volumio rtkit-daemon[1903]: Canary thread running. Sep 05 21:45:40 volumio rtkit-daemon[1903]: Watchdog thread running. Sep 05 21:45:40 volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=1903 comm="/usr/lib/rtkit/rtkit-daemon ") Sep 05 21:45:40 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:40 volumio volumio[1400]: info: Remote SSH Started Sep 05 21:45:40 volumio autossh[1908]: port set to 0, monitoring disabled Sep 05 21:45:40 volumio autossh[1908]: starting ssh (count 1) Sep 05 21:45:40 volumio autossh[1908]: ssh child pid is 1911 Sep 05 21:45:40 volumio systemd[1]: Starting Authorization Manager... Sep 05 21:45:40 volumio volumio[1400]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 05 21:45:40 volumio polkitd[1912]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 05 21:45:40 volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 05 21:45:40 volumio systemd[1]: Started Authorization Manager. Sep 05 21:45:40 volumio volumio[1400]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 05 21:45:40 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:40 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:40 volumio pulseaudio[1901]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 05 21:45:40 volumio pulseaudio[1901]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 05 21:45:40 volumio bluetoothd[1092]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Sep 05 21:45:40 volumio bluetoothd[1092]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Sep 05 21:45:40 volumio volumiobt[1854]: Applying permissions Sep 05 21:45:40 volumio volumiobt[1854]: Setting BT discoverable and pairable Sep 05 21:45:40 volumio pulseaudio[1901]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Sep 05 21:45:40 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Sep 05 21:45:40 volumio kernel: Bluetooth: RFCOMM socket layer initialized Sep 05 21:45:40 volumio kernel: Bluetooth: RFCOMM ver 1.11 Sep 05 21:45:40 volumio volumiobt[1854]: [CHG] Controller 2C:CF:67:ED:78:E9 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Sep 05 21:45:40 volumio volumiobt[1854]: [117B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [117B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [117B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [117B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [117B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [117B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [117B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [117B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [55B blob data] Sep 05 21:45:40 volumio volumiobt[1854]: [bluetooth]# -e power on Sep 05 21:45:40 volumio volumiobt[1854]: Invalid command in menu main: -e Sep 05 21:45:40 volumio volumiobt[1854]: Sep 05 21:45:40 volumio volumiobt[1854]: Use "help" for a list of available commands in a menu. Sep 05 21:45:40 volumio volumiobt[1854]: Use "menu " if you want to enter any submenu. Sep 05 21:45:40 volumio volumiobt[1854]: Use "back" if you want to return to menu main. Sep 05 21:45:40 volumio volumiobt[1854]: [bluetooth]# agent on Sep 05 21:45:40 volumio volumiobt[1854]: Agent is already registered Sep 05 21:45:40 volumio volumiobt[1854]: [bluetooth]# discoverable on Sep 05 21:45:40 volumio volumiobt[1854]: [bluetooth]# pairable on Sep 05 21:45:40 volumio volumiobt[1854]: [bluetooth]# agent NoInputNoOutput Sep 05 21:45:40 volumio volumiobt[1854]: Agent is already registered Sep 05 21:45:40 volumio volumiobt[1854]: [bluetooth]# default-agent Sep 05 21:45:40 volumio volumiobt[1854]: [bluetooth]# quit Sep 05 21:45:40 volumio volumiobt[1854]: [59B blob data] Sep 05 21:45:41 volumio volumiossh-tunnel[1905]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Sep 05 21:45:41 volumio volumio[1400]: info: MRS: Getting audio outputs on start Sep 05 21:45:41 volumio volumio[1400]: info: MRS: Requesting all other devices output Sep 05 21:45:42 volumio volumio[1400]: info: TidalConnect service started! Sep 05 21:45:42 volumio volumio[1400]: [Metrics] CommandRouter: 20s 4.62ms Sep 05 21:45:42 volumio volumio[1400]: info: CoreCommandRouter::volumiosetStartupVolume Sep 05 21:45:42 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:42 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:42 volumio volumio[1400]: info: CoreCommandRouter::Close All Modals sent Sep 05 21:45:42 volumio volumio[1400]: info: CoreCommandRouter::Close All Modals sent Sep 05 21:45:43 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 05 21:45:43 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 21:45:43 volumio volumio[1400]: xcb_connection_has_error() returned true Sep 05 21:45:43 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 05 21:45:48 volumio sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 21:45:48 volumio sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:48 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 21:45:48 volumio sudo[1941]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:48 volumio sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 21:45:48 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:45:48 volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Sep 05 21:45:48 volumio volumio[1400]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 05 21:45:48 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:48 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:48 volumio volumio[1400]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.151 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:49 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 05 21:45:49 volumio volumio[1400]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 05 21:45:49 volumio volumio[1400]: info: Received Get System Info Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 21:45:49 volumio volumio[1400]: info: Discovery: Getting this device information Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:49 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:49 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:49 volumio volumio[1400]: info: Listing playlists Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 21:45:49 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 05 21:45:49 volumio volumio[1400]: info: BOOT COMPLETED Sep 05 21:45:50 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 21:45:50 volumio volumio[1400]: info: Received Get System Info Sep 05 21:45:50 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 21:45:50 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 21:45:50 volumio volumio[1400]: info: Discovery: Getting this device information Sep 05 21:45:50 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:50 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:50 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 21:45:50 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 05 21:45:51 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 21:45:51 volumio volumio[1400]: info: Received Get System Info Sep 05 21:45:51 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 21:45:51 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 21:45:51 volumio volumio[1400]: info: Discovery: Getting this device information Sep 05 21:45:51 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:45:51 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:45:51 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 21:45:53 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 21:45:53 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 05 21:45:53 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 05 21:45:53 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 05 21:45:53 volumio volumio[1400]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 21:45:53 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 21:45:54 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 21:45:54 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 21:45:54 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 05 21:45:54 volumio volumio-remote-updater[767]: No test mode Sep 05 21:45:54 volumio volumio-remote-updater[767]: No alpha test mode Sep 05 21:45:54 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 05 21:45:54 volumio volumio[1400]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 05 21:45:54 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 05 21:45:55 volumio systemd[1]: systemd-timedated.service: Succeeded. Sep 05 21:45:58 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 21:46:00 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:46:00 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:46:04 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 21:46:04 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 05 21:46:06 volumio ntpd[1049]: Soliciting pool server 194.117.47.44 Sep 05 21:46:07 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 21:46:07 volumio volumio[1400]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 05 21:46:07 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 05 21:46:07 volumio volumio[1400]: info: Received Get System Version Sep 05 21:46:07 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 21:46:07 volumio volumio[1400]: info: Received Get System Info Sep 05 21:46:07 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 21:46:07 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 21:46:07 volumio volumio[1400]: info: Discovery: Getting this device information Sep 05 21:46:07 volumio volumio[1400]: info: CoreCommandRouter::volumioGetState Sep 05 21:46:07 volumio volumio[1400]: info: CorePlayQueue::getTrack 0 Sep 05 21:46:07 volumio volumio[1400]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 21:46:07 volumio ntpd[1049]: Soliciting pool server 185.11.166.59 Sep 05 21:46:07 volumio ntpd[1049]: Soliciting pool server 185.228.163.139 Sep 05 21:46:08 volumio ntpd[1049]: Soliciting pool server 23.147.168.176 Sep 05 21:46:08 volumio ntpd[1049]: Soliciting pool server 91.209.16.78 Sep 05 21:46:08 volumio ntpd[1049]: Soliciting pool server 193.136.152.72 Sep 05 21:46:09 volumio ntpd[1049]: Soliciting pool server 194.117.47.42 Sep 05 21:46:09 volumio ntpd[1049]: Soliciting pool server 81.193.254.213 Sep 05 21:46:09 volumio ntpd[1049]: Soliciting pool server 193.136.152.71 Sep 05 21:46:10 volumio ntpd[1049]: Soliciting pool server 162.159.200.123 Sep 05 21:46:10 volumio ntpd[1049]: Soliciting pool server 23.147.168.132 Sep 05 21:46:11 volumio ntpd[1049]: Soliciting pool server 23.147.168.177 Sep 05 21:46:12 volumio ntpd[1049]: Soliciting pool server 2602:faa5:131::175 Sep 05 21:46:17 volumio ntpd[1049]: receive: Unexpected origin timestamp 0xec65ca97.69ad54a8 does not match aorg 0000000000.00000000 from server@23.147.168.176 xmt 0xec65ca99.17e61bce Sep 05 21:46:17 volumio ntpd[1049]: receive: Unexpected origin timestamp 0xec65ca97.69adbb97 does not match aorg 0000000000.00000000 from server@185.228.163.139 xmt 0xec65ca99.17c574e0 Sep 05 21:46:17 volumio ntpd[1049]: receive: Unexpected origin timestamp 0xec65ca97.69ac0574 does not match aorg 0000000000.00000000 from server@81.193.254.213 xmt 0xec65ca99.1846a6d9 Sep 05 21:46:17 volumio ntpd[1049]: receive: Unexpected origin timestamp 0xec65ca97.69ae28ae does not match aorg 0000000000.00000000 from server@185.11.166.59 xmt 0xec65ca99.18b996a8 Sep 05 21:46:18 volumio volumio[1400]: info: Starting Uninstall of plugin user_interface - touch_display Sep 05 21:46:18 volumio volumio[1400]: info: Uninstalling plugin touch_display Sep 05 21:46:18 volumio sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Sep 05 21:46:18 volumio sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:46:18 volumio sudo[2035]: pam_unix(sudo:session): session closed for user root Sep 05 21:46:18 volumio sudo[2038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Sep 05 21:46:18 volumio sudo[2038]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:46:18 volumio sudo[2041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Sep 05 21:46:18 volumio sudo[2041]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:46:18 volumio volumio[1400]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Sep 05 21:46:18 volumio volumio[1400]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 21:46:18 volumio volumio[1400]: TypeError: Cannot read property 'split' of undefined Sep 05 21:46:18 volumio volumio[1400]: at /data/plugins/user_interface/touch_display/index.js:1258:37 Sep 05 21:46:18 volumio volumio[1400]: at ChildProcess.exithandler (child_process.js:299:7) Sep 05 21:46:18 volumio volumio[1400]: at ChildProcess.emit (events.js:315:20) Sep 05 21:46:18 volumio volumio[1400]: at maybeClose (internal/child_process.js:1048:16) Sep 05 21:46:18 volumio volumio[1400]: at Socket. (internal/child_process.js:439:11) Sep 05 21:46:18 volumio volumio[1400]: at Socket.emit (events.js:315:20) Sep 05 21:46:18 volumio volumio[1400]: at Pipe. (net.js:673:12) Sep 05 21:46:18 volumio volumio[1400]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 21:46:18 volumio sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Sep 05 21:46:18 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 21:46:18 volumio systemd[1]: Created slice system-getty.slice. Sep 05 21:46:18 volumio systemd[1]: Started Getty on tty1. Sep 05 21:46:18 volumio sudo[2038]: pam_unix(sudo:session): session closed for user root Sep 05 21:46:18 volumio systemd[1]: Reloading. Sep 05 21:46:18 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 05 21:46:18 volumio sudo[2069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 21:45 Sep 05 21:46:18 volumio sudo[2069]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"