-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Thu 2025-07-03 18:53:23 +07. -- Jul 03 18:52:18 volumio9 fake-hwclock[313]: Thu Jul 3 11:52:18 UTC 2025 Jul 03 18:52:18 volumio9 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 03 18:52:18 volumio9 systemd[1]: Started Restore / save the current clock. Jul 03 18:52:18 volumio9 systemd[1]: Started Load/Save Random Seed. Jul 03 18:52:18 volumio9 systemd[1]: Started udev Coldplug all Devices. Jul 03 18:52:18 volumio9 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jul 03 18:52:18 volumio9 systemd[1]: dynamicswap.service: Succeeded. Jul 03 18:52:18 volumio9 systemd[1]: Started Create System Users. Jul 03 18:52:18 volumio9 systemd[1]: Starting Create Static Device Nodes in /dev... Jul 03 18:52:18 volumio9 systemd[1]: Started Create Static Device Nodes in /dev. Jul 03 18:52:18 volumio9 systemd[1]: Reached target Local File Systems (Pre). Jul 03 18:52:18 volumio9 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 03 18:52:18 volumio9 systemd[1]: Mounting /tmp... Jul 03 18:52:18 volumio9 systemd[1]: Mounting /var/spool/cups... Jul 03 18:52:18 volumio9 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 03 18:52:18 volumio9 systemd[1]: Mounting /var/log... Jul 03 18:52:18 volumio9 systemd[1]: Starting udev Kernel Device Manager... Jul 03 18:52:18 volumio9 systemd[1]: Mounted /tmp. Jul 03 18:52:18 volumio9 systemd[1]: Mounted /var/spool/cups. Jul 03 18:52:18 volumio9 systemd[1]: Mounted /var/log. Jul 03 18:52:18 volumio9 systemd[1]: Starting Flush Journal to Persistent Storage... Jul 03 18:52:18 volumio9 systemd[1]: Mounting /var/spool/cups/tmp... Jul 03 18:52:18 volumio9 systemd[1]: Mounted /var/spool/cups/tmp. Jul 03 18:52:18 volumio9 systemd-udevd[349]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jul 03 18:52:18 volumio9 systemd-journald[316]: Runtime journal (/run/log/journal/d59f8fba44ffb4c0d2e89736682361ca) is 7.5M, max 30.0M, 22.5M free. Jul 03 18:52:18 volumio9 systemd[1]: Started udev Kernel Device Manager. Jul 03 18:52:18 volumio9 systemd[1]: Starting Show Plymouth Boot Screen... Jul 03 18:52:18 volumio9 systemd[1]: Started Flush Journal to Persistent Storage. Jul 03 18:52:18 volumio9 systemd[1]: plymouth-start.service: Succeeded. Jul 03 18:52:18 volumio9 systemd[1]: Started Show Plymouth Boot Screen. Jul 03 18:52:18 volumio9 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jul 03 18:52:18 volumio9 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 03 18:52:18 volumio9 systemd[1]: Reached target Local Encrypted Volumes. Jul 03 18:52:18 volumio9 systemd[1]: Reached target Paths. Jul 03 18:52:18 volumio9 systemd-udevd[370]: Using default interface naming scheme 'v240'. Jul 03 18:52:18 volumio9 kernel: mc: Linux media interface: v0.10 Jul 03 18:52:18 volumio9 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jul 03 18:52:18 volumio9 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jul 03 18:52:18 volumio9 systemd-udevd[378]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 03 18:52:18 volumio9 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:52:18 volumio9 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jul 03 18:52:18 volumio9 kernel: [vc_sm_connected_init]: start Jul 03 18:52:18 volumio9 systemd-udevd[370]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 03 18:52:18 volumio9 kernel: [vc_sm_connected_init]: installed successfully Jul 03 18:52:18 volumio9 kernel: videodev: Linux video capture interface: v2.00 Jul 03 18:52:18 volumio9 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:52:18 volumio9 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 03 18:52:18 volumio9 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jul 03 18:52:18 volumio9 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jul 03 18:52:18 volumio9 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 03 18:52:18 volumio9 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jul 03 18:52:18 volumio9 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:52:18 volumio9 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jul 03 18:52:18 volumio9 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jul 03 18:52:18 volumio9 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 03 18:52:18 volumio9 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jul 03 18:52:18 volumio9 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jul 03 18:52:18 volumio9 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jul 03 18:52:19 volumio9 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jul 03 18:52:19 volumio9 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jul 03 18:52:19 volumio9 kernel: usbcore: registered new interface driver brcmfmac Jul 03 18:52:19 volumio9 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:52:19 volumio9 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jul 03 18:52:19 volumio9 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 03 18:52:19 volumio9 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 03 18:52:19 volumio9 kernel: Bluetooth: Core ver 2.22 Jul 03 18:52:19 volumio9 kernel: NET: Registered PF_BLUETOOTH protocol family Jul 03 18:52:19 volumio9 kernel: Bluetooth: HCI device and connection manager initialized Jul 03 18:52:19 volumio9 kernel: Bluetooth: HCI socket layer initialized Jul 03 18:52:19 volumio9 kernel: Bluetooth: L2CAP socket layer initialized Jul 03 18:52:19 volumio9 kernel: Bluetooth: SCO socket layer initialized Jul 03 18:52:19 volumio9 kernel: Bluetooth: HCI UART driver ver 2.3 Jul 03 18:52:19 volumio9 kernel: Bluetooth: HCI UART protocol H4 registered Jul 03 18:52:19 volumio9 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jul 03 18:52:19 volumio9 kernel: Bluetooth: HCI UART protocol Broadcom registered Jul 03 18:52:19 volumio9 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jul 03 18:52:19 volumio9 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jul 03 18:52:19 volumio9 kernel: uart-pl011 fe201000.serial: no DMA platform data Jul 03 18:52:19 volumio9 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jul 03 18:52:19 volumio9 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 Jul 03 18:52:19 volumio9 systemd-udevd[375]: Using default interface naming scheme 'v240'. Jul 03 18:52:19 volumio9 systemd-udevd[375]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 03 18:52:19 volumio9 systemd-udevd[359]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 18:52:19 volumio9 kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Jul 03 18:52:19 volumio9 kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Jul 03 18:52:19 volumio9 kernel: usbcore: registered new interface driver snd-usb-audio Jul 03 18:52:19 volumio9 kernel: Bluetooth: hci0: BCM: chip id 107 Jul 03 18:52:19 volumio9 kernel: Bluetooth: hci0: BCM: features 0x2f Jul 03 18:52:19 volumio9 kernel: Bluetooth: hci0: BCM4345C0 Jul 03 18:52:19 volumio9 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jul 03 18:52:19 volumio9 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jul 03 18:52:19 volumio9 kernel: alsactl[505]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jul 03 18:52:19 volumio9 kernel: alsactl[489]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jul 03 18:52:20 volumio9 systemd-udevd[368]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jul 03 18:52:20 volumio9 systemd[1]: Found device /dev/disk/by-uuid/2E0A-AE55. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Created slice system-bthelper.slice. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 03 18:52:20 volumio9 kernel: Bluetooth: hci0: BCM: features 0x2f Jul 03 18:52:20 volumio9 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jul 03 18:52:20 volumio9 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jul 03 18:52:20 volumio9 systemd[1]: Starting Show Plymouth Boot Screen... Jul 03 18:52:20 volumio9 systemd[1]: Starting File System Check on /dev/disk/by-uuid/2E0A-AE55... Jul 03 18:52:20 volumio9 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 03 18:52:20 volumio9 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jul 03 18:52:20 volumio9 systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 03 18:52:20 volumio9 systemd[1]: plymouth-start.service: Succeeded. Jul 03 18:52:20 volumio9 systemd[1]: Started Show Plymouth Boot Screen. Jul 03 18:52:20 volumio9 systemd[1]: Started File System Check Daemon to report status. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Started Load/Save RF Kill Switch Status. Jul 03 18:52:20 volumio9 systemd-fsck[512]: fsck.fat 4.1 (2017-01-24) Jul 03 18:52:20 volumio9 systemd-fsck[512]: /dev/mmcblk0p1: 406 files, 41746/46774 clusters Jul 03 18:52:20 volumio9 systemd[1]: Started File System Check on /dev/disk/by-uuid/2E0A-AE55. Jul 03 18:52:20 volumio9 systemd[1]: Mounting /boot... Jul 03 18:52:20 volumio9 systemd[1]: Mounted /boot. Jul 03 18:52:20 volumio9 systemd[1]: Reached target Local File Systems. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 03 18:52:20 volumio9 systemd[1]: Started ifup for wlan0. Jul 03 18:52:20 volumio9 systemd[1]: Starting Preprocess NFS configuration... Jul 03 18:52:20 volumio9 systemd[1]: Starting Create Volatile Files and Directories... Jul 03 18:52:20 volumio9 systemd[1]: Started ifup for eth0. Jul 03 18:52:20 volumio9 systemd[1]: Starting Raise network interfaces... Jul 03 18:52:20 volumio9 systemd[1]: plymouth-read-write.service: Succeeded. Jul 03 18:52:20 volumio9 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 03 18:52:20 volumio9 systemd[1]: Received SIGRTMIN+20 from PID 204 (plymouthd). Jul 03 18:52:20 volumio9 systemd[1]: nfs-config.service: Succeeded. Jul 03 18:52:20 volumio9 systemd[1]: Started Preprocess NFS configuration. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Reached target NFS client services. Jul 03 18:52:20 volumio9 systemd[1]: Started Create Volatile Files and Directories. Jul 03 18:52:20 volumio9 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jul 03 18:52:20 volumio9 systemd[1]: Starting RPC bind portmap service... Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jul 03 18:52:20 volumio9 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jul 03 18:52:20 volumio9 systemd[1]: Started RPC bind portmap service. Jul 03 18:52:20 volumio9 systemd[1]: Reached target Remote File Systems (Pre). Jul 03 18:52:20 volumio9 systemd[1]: Reached target Remote File Systems. Jul 03 18:52:20 volumio9 systemd[1]: Reached target RPC Port Mapper. Jul 03 18:52:20 volumio9 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jul 03 18:52:20 volumio9 systemd[1]: Reached target System Initialization. Jul 03 18:52:20 volumio9 systemd[1]: Started Daily man-db regeneration. Jul 03 18:52:20 volumio9 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 03 18:52:20 volumio9 systemd[1]: Listening on triggerhappy.socket. Jul 03 18:52:20 volumio9 systemd[1]: Started Daily apt download activities. Jul 03 18:52:20 volumio9 systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 03 18:52:20 volumio9 systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 03 18:52:20 volumio9 systemd[1]: Reached target Sockets. Jul 03 18:52:20 volumio9 systemd[1]: Started Daily apt upgrade and clean activities. Jul 03 18:52:20 volumio9 systemd[1]: Reached target Timers. Jul 03 18:52:20 volumio9 systemd[1]: Reached target Basic System. Jul 03 18:52:20 volumio9 systemd[1]: Started Volumio Iptables Module. Jul 03 18:52:20 volumio9 systemd[1]: Started Volumio Log Rotation Service. Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Starting Volumio Time Update Utility... Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Starting dhcpcd on all interfaces... Jul 03 18:52:20 volumio9 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 03 18:52:20 volumio9 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jul 03 18:52:20 volumio9 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 03 18:52:20 volumio9 systemd[1]: Started volumio-remote-updater.service. Jul 03 18:52:20 volumio9 systemd[1]: Started D-Bus System Message Bus. Jul 03 18:52:20 volumio9 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 03 18:52:20 volumio9 systemd[1]: Starting WPA supplicant... Jul 03 18:52:20 volumio9 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jul 03 18:52:20 volumio9 dhcpcd[595]: Not running dhcpcd because /etc/network/interfaces Jul 03 18:52:20 volumio9 dhcpcd[595]: defines some interfaces that will use a Jul 03 18:52:20 volumio9 dhcpcd[595]: DHCP client or static address Jul 03 18:52:20 volumio9 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 03 18:52:20 volumio9 systemd[1]: Reached target Login Prompts. Jul 03 18:52:20 volumio9 systemd[1]: Starting Login Service... Jul 03 18:52:20 volumio9 volumio-time-update[594]: volumio-time-update-util: Fetching time from Volumio... Jul 03 18:52:20 volumio9 systemd[1]: Started Manage Sound Card State (restore and store). Jul 03 18:52:20 volumio9 alsactl[612]: alsactl 1.1.8 daemon started Jul 03 18:52:20 volumio9 systemd[1]: Starting Save/Restore Sound Card State... Jul 03 18:52:20 volumio9 systemd[1]: Started MPD Monitor Service. Jul 03 18:52:20 volumio9 systemd[1]: Starting Wireless Services... Jul 03 18:52:20 volumio9 systemd[1]: Starting triggerhappy global hotkey daemon... Jul 03 18:52:20 volumio9 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jul 03 18:52:20 volumio9 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jul 03 18:52:20 volumio9 systemd[1]: Failed to start dhcpcd on all interfaces. Jul 03 18:52:20 volumio9 mpd_monitor.sh[617]: MPD Monitor Service: Starting MPD Monitor Service Jul 03 18:52:21 volumio9 kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 03 18:52:21 volumio9 thd[619]: Unable to parse trigger line: Jul 03 18:52:21 volumio9 thd[619]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 03 18:52:21 volumio9 thd[619]: Unable to parse trigger line: Jul 03 18:52:21 volumio9 thd[619]: Unable to parse trigger line: Jul 03 18:52:21 volumio9 thd[619]: Found socket passed from systemd Jul 03 18:52:21 volumio9 systemd-logind[610]: New seat seat0. Jul 03 18:52:21 volumio9 avahi-daemon[596]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jul 03 18:52:21 volumio9 mpd_monitor.sh[617]: MPD error: Connection refused Jul 03 18:52:21 volumio9 avahi-daemon[596]: Successfully dropped root privileges. Jul 03 18:52:21 volumio9 avahi-daemon[596]: avahi-daemon 0.7 starting up. Jul 03 18:52:21 volumio9 mpd_monitor.sh[617]: MPD Monitor Service: MPD Appears to be inactive, restarting Jul 03 18:52:21 volumio9 mpd_monitor.sh[617]: mpd: no process found Jul 03 18:52:21 volumio9 volumio-remote-updater[600]: Error: No active session Jul 03 18:52:21 volumio9 volumio-remote-updater[600]: [2025-07-03 18:52:21] [info] asio async_connect error: system:111 (Connection refused) Jul 03 18:52:21 volumio9 volumio-remote-updater[600]: [2025-07-03 18:52:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 03 18:52:21 volumio9 volumio-remote-updater[600]: [2025-07-03 18:52:21] [error] handle_connect error: Underlying Transport Error Jul 03 18:52:21 volumio9 volumio-time-update[594]: volumio-time-update-util: Date not found in response Jul 03 18:52:21 volumio9 volumio-time-update[594]: volumio-time-update-util: Retrying in 5 seconds... Jul 03 18:52:21 volumio9 systemd[1]: Started WPA supplicant. Jul 03 18:52:21 volumio9 wpa_supplicant[606]: Successfully initialized wpa_supplicant Jul 03 18:52:21 volumio9 systemd[1]: Started triggerhappy global hotkey daemon. Jul 03 18:52:21 volumio9 systemd[1]: hciuart.service: Succeeded. Jul 03 18:52:21 volumio9 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jul 03 18:52:21 volumio9 systemd[1]: Started Save/Restore Sound Card State. Jul 03 18:52:21 volumio9 sh[526]: eth0: waiting for carrier Jul 03 18:52:21 volumio9 dhcpcd[575]: eth0: waiting for carrier Jul 03 18:52:21 volumio9 avahi-daemon[596]: Successfully called chroot(). Jul 03 18:52:21 volumio9 avahi-daemon[596]: Successfully dropped remaining capabilities. Jul 03 18:52:21 volumio9 avahi-daemon[596]: Loading service file /services/volumio.service. Jul 03 18:52:21 volumio9 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jul 03 18:52:21 volumio9 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jul 03 18:52:21 volumio9 avahi-daemon[596]: Network interface enumeration completed. Jul 03 18:52:21 volumio9 avahi-daemon[596]: Server startup complete. Host name is volumio9.local. Local service cookie is 2961212752. Jul 03 18:52:21 volumio9 avahi-daemon[596]: Service "Volumio9" (/services/volumio.service) successfully established. Jul 03 18:52:21 volumio9 ifup[530]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jul 03 18:52:21 volumio9 systemd[1]: Started Login Service. Jul 03 18:52:21 volumio9 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 03 18:52:21 volumio9 sh[521]: wlan0=wlan0 Jul 03 18:52:21 volumio9 volumio[598]: Could not open config: /tmp/upmpdcli.conf Jul 03 18:52:21 volumio9 systemd[1]: Listening on mpd.socket. Jul 03 18:52:21 volumio9 systemd[1]: Reached target Sound Card. Jul 03 18:52:21 volumio9 systemd[1]: Starting Raspberry Pi bluetooth helper... Jul 03 18:52:21 volumio9 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 03 18:52:21 volumio9 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 03 18:52:21 volumio9 systemd[1]: Started Raise network interfaces. Jul 03 18:52:21 volumio9 systemd[1]: Reached target Network. Jul 03 18:52:21 volumio9 systemd[1]: Starting Network Time Service... Jul 03 18:52:21 volumio9 systemd[1]: Starting Permit User Sessions... Jul 03 18:52:21 volumio9 systemd[1]: Starting Music Player Daemon... Jul 03 18:52:21 volumio9 haveged[564]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jul 03 18:52:21 volumio9 haveged[564]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jul 03 18:52:21 volumio9 haveged[564]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00068 Jul 03 18:52:21 volumio9 haveged[564]: haveged: fills: 0, generated: 0 Jul 03 18:52:23 volumio9 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jul 03 18:52:23 volumio9 systemd[1]: Reached target Network is Online. Jul 03 18:52:23 volumio9 bthelper[712]: Raspberry Pi BDADDR already set Jul 03 18:52:23 volumio9 loadcpufreq[597]: Loading cpufreq kernel modules...done (none). Jul 03 18:52:23 volumio9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 18:52:23 volumio9 systemd[1]: Starting Samba NMB Daemon... Jul 03 18:52:23 volumio9 systemd[1]: Starting LSB: Brings up/down network automatically... Jul 03 18:52:23 volumio9 systemd[1]: Starting /etc/rc.local Compatibility... Jul 03 18:52:23 volumio9 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jul 03 18:52:23 volumio9 systemd[1]: Started Raspberry Pi bluetooth helper. Jul 03 18:52:23 volumio9 systemd[1]: Started Permit User Sessions. Jul 03 18:52:23 volumio9 systemd[1]: Started /etc/rc.local Compatibility. Jul 03 18:52:23 volumio9 systemd[1]: iptables.service: Succeeded. Jul 03 18:52:23 volumio9 systemd[1]: Starting Terminate Plymouth Boot Screen... Jul 03 18:52:23 volumio9 systemd[1]: Starting Hold until boot process finishes up... Jul 03 18:52:23 volumio9 systemd[1]: Starting Bluetooth service... Jul 03 18:52:23 volumio9 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jul 03 18:52:23 volumio9 systemd[1]: Received SIGRTMIN+21 from PID 204 (plymouthd). Jul 03 18:52:23 volumio9 systemd[1]: Received SIGRTMIN+21 from PID 204 (plymouthd). Jul 03 18:52:23 volumio9 systemd[1]: plymouth-quit-wait.service: Succeeded. Jul 03 18:52:23 volumio9 systemd[1]: Started Hold until boot process finishes up. Jul 03 18:52:23 volumio9 systemd[1]: plymouth-quit.service: Succeeded. Jul 03 18:52:23 volumio9 systemd[1]: Started Terminate Plymouth Boot Screen. Jul 03 18:52:24 volumio9 ntpd[726]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 03 18:52:24 volumio9 ntpd[726]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jul 03 18:52:24 volumio9 systemd[1]: Started Network Time Service. Jul 03 18:52:24 volumio9 ntpd[779]: proto: precision = 0.852 usec (-20) Jul 03 18:52:24 volumio9 ifplugd(eth0)[786]: ifplugd 0.28 initializing. Jul 03 18:52:24 volumio9 ifplugd(eth0)[786]: Using interface eth0/D8:3A:DD:68:34:85 with driver (version: 6.6.62-v7l+) Jul 03 18:52:24 volumio9 ifplugd(eth0)[786]: Using detection mode: SIOCETHTOOL Jul 03 18:52:24 volumio9 ifplugd(eth0)[786]: Initialization complete, link beat not detected. Jul 03 18:52:24 volumio9 ntpd[779]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 03 18:52:24 volumio9 ntpd[779]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 03 18:52:24 volumio9 ntpd[779]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 188 days ago Jul 03 18:52:24 volumio9 ntpd[779]: Listen and drop on 0 v6wildcard [::]:123 Jul 03 18:52:24 volumio9 ntpd[779]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 03 18:52:24 volumio9 ntpd[779]: Listen normally on 2 lo 127.0.0.1:123 Jul 03 18:52:24 volumio9 ntpd[779]: Listening on routing socket on fd #19 for interface updates Jul 03 18:52:24 volumio9 ntpd[779]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 18:52:24 volumio9 ntpd[779]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 18:52:24 volumio9 cpufrequtils[764]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jul 03 18:52:24 volumio9 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jul 03 18:52:24 volumio9 ifplugd[756]: Network Interface Plugging Daemon...start eth0...done. Jul 03 18:52:24 volumio9 systemd[1]: Started LSB: Brings up/down network automatically. Jul 03 18:52:24 volumio9 bluetoothd[763]: Bluetooth daemon 5.50 Jul 03 18:52:24 volumio9 bluetoothd[763]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 03 18:52:24 volumio9 systemd[1]: Started Bluetooth service. Jul 03 18:52:24 volumio9 systemd[1]: Reached target Bluetooth. Jul 03 18:52:24 volumio9 bluetoothd[763]: Starting SDP server Jul 03 18:52:24 volumio9 bluetoothd[763]: Excluding (cli) sap Jul 03 18:52:24 volumio9 bluetoothd[763]: Bluetooth management interface 1.22 initialized Jul 03 18:52:24 volumio9 dbus-daemon[602]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=763 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jul 03 18:52:24 volumio9 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jul 03 18:52:24 volumio9 kernel: Bluetooth: BNEP filters: protocol multicast Jul 03 18:52:24 volumio9 kernel: Bluetooth: BNEP socket layer initialized Jul 03 18:52:24 volumio9 kernel: Bluetooth: MGMT ver 1.22 Jul 03 18:52:24 volumio9 sudo[728]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 18:52:24 volumio9 sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:24 volumio9 sudo[728]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 03 18:52:24 volumio9 sudo[728]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:25 volumio9 bluetoothd[763]: Failed to set privacy: Rejected (0x0b) Jul 03 18:52:25 volumio9 systemd[1]: Starting Hostname Service... Jul 03 18:52:25 volumio9 dbus-daemon[602]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 03 18:52:25 volumio9 systemd[1]: Started Hostname Service. Jul 03 18:52:25 volumio9 systemd[1]: systemd-rfkill.service: Succeeded. Jul 03 18:52:25 volumio9 ntpd[779]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 03 18:52:25 volumio9 nmbd[760]: [2025/07/03 18:52:25.619160, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 03 18:52:25 volumio9 nmbd[760]: started asyncdns process 803 Jul 03 18:52:25 volumio9 nmbd[760]: [2025/07/03 18:52:25.620778, 0] ../lib/util/become_daemon.c:149(daemon_status) Jul 03 18:52:25 volumio9 nmbd[760]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 03 18:52:25 volumio9 nmbd[760]: [2025/07/03 18:52:25.620896, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jul 03 18:52:25 volumio9 nmbd[760]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 03 18:52:25 volumio9 sh[526]: eth0: carrier acquired Jul 03 18:52:25 volumio9 dhcpcd[575]: eth0: carrier acquired Jul 03 18:52:25 volumio9 sh[526]: DUID 00:01:00:01:2f:b6:20:33:d8:3a:dd:68:34:85 Jul 03 18:52:25 volumio9 sh[526]: eth0: IAID dd:68:34:85 Jul 03 18:52:25 volumio9 sh[526]: eth0: adding address fe80::da3a:ddff:fe68:3485 Jul 03 18:52:25 volumio9 sh[526]: ipv6_addaddr1: Permission denied Jul 03 18:52:25 volumio9 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jul 03 18:52:25 volumio9 dhcpcd[575]: DUID 00:01:00:01:2f:b6:20:33:d8:3a:dd:68:34:85 Jul 03 18:52:25 volumio9 dhcpcd[575]: eth0: IAID dd:68:34:85 Jul 03 18:52:25 volumio9 dhcpcd[575]: eth0: adding address fe80::da3a:ddff:fe68:3485 Jul 03 18:52:25 volumio9 dhcpcd[575]: ipv6_addaddr1: Permission denied Jul 03 18:52:25 volumio9 sh[526]: eth0: soliciting an IPv6 router Jul 03 18:52:25 volumio9 dhcpcd[575]: eth0: soliciting an IPv6 router Jul 03 18:52:25 volumio9 wireless.js[618]: Cleaning previous... Jul 03 18:52:26 volumio9 sudo[812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 03 18:52:26 volumio9 sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:26 volumio9 sudo[812]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:26 volumio9 sudo[814]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 03 18:52:26 volumio9 sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:26 volumio9 sh[526]: eth0: soliciting a DHCP lease Jul 03 18:52:26 volumio9 dhcpcd[575]: eth0: soliciting a DHCP lease Jul 03 18:52:26 volumio9 volumio-time-update[594]: volumio-time-update-util: Fetching time from Volumio... Jul 03 18:52:26 volumio9 ntpd[779]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 03 18:52:26 volumio9 volumio-time-update[594]: volumio-time-update-util: Date not found in response Jul 03 18:52:26 volumio9 volumio-time-update[594]: volumio-time-update-util: Retrying in 5 seconds... Jul 03 18:52:26 volumio9 volumio-remote-updater[600]: [2025-07-03 18:52:26] [info] asio async_connect error: system:111 (Connection refused) Jul 03 18:52:26 volumio9 volumio-remote-updater[600]: [2025-07-03 18:52:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 03 18:52:26 volumio9 volumio-remote-updater[600]: [2025-07-03 18:52:26] [error] handle_connect error: Underlying Transport Error Jul 03 18:52:26 volumio9 sudo[814]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:26 volumio9 ifplugd(eth0)[786]: Link beat detected. Jul 03 18:52:26 volumio9 wireless.js[618]: Stopped aP Jul 03 18:52:26 volumio9 wireless.js[618]: Wireless Networking DISABLED, not starting wireless flow Jul 03 18:52:26 volumio9 ifplugd(eth0)[786]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 03 18:52:26 volumio9 ifplugd(eth0)[786]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jul 03 18:52:26 volumio9 systemd[1]: wireless.service: Succeeded. Jul 03 18:52:26 volumio9 systemd[1]: Started Wireless Services. Jul 03 18:52:26 volumio9 systemd[1]: Started Volumio Backend Module. Jul 03 18:52:26 volumio9 systemd[1]: Started Volumio Cpu Tweaker. Jul 03 18:52:26 volumio9 volumio-cpu-tweak[833]: Setting RT Priority for mpd Jul 03 18:52:26 volumio9 volumio-cpu-tweak[833]: Setting MPD Affinity Jul 03 18:52:26 volumio9 volumio-cpu-tweak[833]: pid 800's current affinity mask: f Jul 03 18:52:26 volumio9 volumio-cpu-tweak[833]: pid 800's new affinity mask: 3 Jul 03 18:52:26 volumio9 volumio-cpu-tweak[833]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 03 18:52:26 volumio9 volumio-cpu-tweak[833]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 03 18:52:26 volumio9 systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 03 18:52:27 volumio9 ntpd[779]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 03 18:52:27 volumio9 mpd[800]: Jul 03 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 18:52:27 volumio9 systemd[1]: Started Music Player Daemon. Jul 03 18:52:27 volumio9 mpd_monitor.sh[617]: MPD Monitor Service: MPD restarted due to no mpc output. Jul 03 18:52:28 volumio9 bthelper[712]: Changing power off succeeded Jul 03 18:52:28 volumio9 sh[526]: eth0: offered 192.168.1.77 from 192.168.1.254 Jul 03 18:52:28 volumio9 dhcpcd[575]: eth0: offered 192.168.1.77 from 192.168.1.254 Jul 03 18:52:28 volumio9 bthelper[712]: [CHG] Controller D8:3A:DD:68:34:88 Class: 0x0000041c Jul 03 18:52:28 volumio9 bthelper[712]: Changing power on succeeded Jul 03 18:52:28 volumio9 sh[526]: eth0: probing address 192.168.1.77/24 Jul 03 18:52:28 volumio9 dhcpcd[575]: eth0: probing address 192.168.1.77/24 Jul 03 18:52:28 volumio9 ntpd[779]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 03 18:52:29 volumio9 volumio[832]: info: ------------------------------------------- Jul 03 18:52:29 volumio9 volumio[832]: info: ----- Volumio3 ---- Jul 03 18:52:29 volumio9 volumio[832]: info: ------------------------------------------- Jul 03 18:52:29 volumio9 volumio[832]: info: ----- System startup ---- Jul 03 18:52:29 volumio9 volumio[832]: info: ------------------------------------------- Jul 03 18:52:30 volumio9 volumio[832]: info: MYVOLUMIO Environment detected Jul 03 18:52:30 volumio9 volumio[832]: info: Plugin folders cleanup Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning into folder /volumio/app/plugins/ Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning category audio_interface Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning category miscellanea Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning category music_service Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning category plugins.json Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning category system_controller Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning category user_interface Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning into folder /data/plugins/ Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning category music_service Jul 03 18:52:30 volumio9 volumio[832]: info: Scanning category user_interface Jul 03 18:52:30 volumio9 volumio[832]: info: Plugin folders cleanup completed Jul 03 18:52:30 volumio9 volumio[832]: info: ------------------------------------------- Jul 03 18:52:30 volumio9 volumio[832]: info: ----- Core plugins startup ---- Jul 03 18:52:30 volumio9 volumio[832]: info: ------------------------------------------- Jul 03 18:52:30 volumio9 volumio[832]: info: Loading plugins from folder /volumio/app/plugins/ Jul 03 18:52:30 volumio9 volumio[832]: info: Adding plugin upnp to MyMusic Plugins Jul 03 18:52:30 volumio9 volumio[832]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 03 18:52:30 volumio9 volumio[832]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 03 18:52:30 volumio9 volumio[832]: info: Loading plugins from folder /data/plugins/ Jul 03 18:52:30 volumio9 volumio[832]: info: Loading plugin "system"... Jul 03 18:52:30 volumio9 volumio[832]: info: Loading plugin "appearance"... Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "network"... Jul 03 18:52:31 volumio9 volumio[832]: info: Refreshing Cached IP Addresses Jul 03 18:52:31 volumio9 sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 18:52:31 volumio9 sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:31 volumio9 sudo[874]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:31 volumio9 sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 18:52:31 volumio9 sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "services"... Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "alsa_controller"... Jul 03 18:52:31 volumio9 sudo[876]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:31 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "wizard"... Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "networkfs"... Jul 03 18:52:31 volumio9 volumio[832]: info: Starting Udev Watcher for removable devices Jul 03 18:52:31 volumio9 sudo[897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.12/Hires Music /mnt/NAS/Music Jul 03 18:52:31 volumio9 sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:31 volumio9 volumio[832]: info: Ignoring mount for partition: boot Jul 03 18:52:31 volumio9 volumio[832]: info: Ignoring mount for partition: volumio Jul 03 18:52:31 volumio9 volumio[832]: info: Ignoring mount for partition: volumio_data Jul 03 18:52:31 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "volumio_command_line_client"... Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "upnp"... Jul 03 18:52:31 volumio9 volumio[832]: info: [1751543551452] Starting Upmpd Daemon Jul 03 18:52:31 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "my_music"... Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "mpd"... Jul 03 18:52:31 volumio9 volumio-time-update[594]: volumio-time-update-util: Fetching time from Volumio... Jul 03 18:52:31 volumio9 volumio-time-update[594]: volumio-time-update-util: Date not found in response Jul 03 18:52:31 volumio9 volumio-time-update[594]: volumio-time-update-util: Retrying in 5 seconds... Jul 03 18:52:31 volumio9 sudo[897]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:31 volumio9 kernel: Key type cifs.spnego registered Jul 03 18:52:31 volumio9 kernel: Key type cifs.idmap registered Jul 03 18:52:31 volumio9 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jul 03 18:52:31 volumio9 kernel: CIFS: Attempting to mount //192.168.1.12/Hires Music Jul 03 18:52:31 volumio9 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 03 18:52:31 volumio9 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jul 03 18:52:31 volumio9 volumio[832]: info: Loading plugin "upnp_browser"... Jul 03 18:52:32 volumio9 volumio[832]: info: Loading plugin "alarm-clock"... Jul 03 18:52:32 volumio9 volumio[832]: info: Loading plugin "airplay_emulation"... Jul 03 18:52:32 volumio9 volumio[832]: info: Starting Shairport Sync Jul 03 18:52:32 volumio9 volumio[832]: info: Loading plugin "last_100"... Jul 03 18:52:32 volumio9 volumio[832]: info: Loading plugin "webradio"... Jul 03 18:52:32 volumio9 volumio[832]: info: Loading plugin "i2s_dacs"... Jul 03 18:52:32 volumio9 volumio[832]: info: I2S DAC not set, start Auto-detection Jul 03 18:52:32 volumio9 volumio[832]: info: Loading plugin "volumiodiscovery"... Jul 03 18:52:32 volumio9 volumio[832]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 18:52:32 volumio9 volumio[832]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:52:32 volumio9 node[832]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 18:52:32 volumio9 volumio[832]: *** WARNING *** For more information see Jul 03 18:52:32 volumio9 volumio[832]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 18:52:32 volumio9 volumio[832]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:52:32 volumio9 volumio[832]: *** WARNING *** For more information see Jul 03 18:52:32 volumio9 node[832]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:52:32 volumio9 node[832]: *** WARNING *** For more information see Jul 03 18:52:32 volumio9 node[832]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 18:52:32 volumio9 node[832]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:52:32 volumio9 node[832]: *** WARNING *** For more information see Jul 03 18:52:32 volumio9 volumio[832]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 03 18:52:32 volumio9 volumio[832]: info: Discovery: Started advertising with name: Volumio9 Jul 03 18:52:32 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:52:32 volumio9 volumio[832]: info: Loading plugin "spop"... Jul 03 18:52:33 volumio9 sh[526]: eth0: leased 192.168.1.77 for 28800 seconds Jul 03 18:52:33 volumio9 sh[526]: eth0: adding route to 192.168.1.0/24 Jul 03 18:52:33 volumio9 sh[526]: eth0: adding default route via 192.168.1.254 Jul 03 18:52:33 volumio9 dhcpcd[575]: eth0: leased 192.168.1.77 for 28800 seconds Jul 03 18:52:33 volumio9 dhcpcd[575]: eth0: adding route to 192.168.1.0/24 Jul 03 18:52:33 volumio9 dhcpcd[575]: eth0: adding default route via 192.168.1.254 Jul 03 18:52:33 volumio9 avahi-daemon[596]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.77. Jul 03 18:52:33 volumio9 avahi-daemon[596]: New relevant interface eth0.IPv4 for mDNS. Jul 03 18:52:33 volumio9 avahi-daemon[596]: Registering new address record for 192.168.1.77 on eth0.IPv4. Jul 03 18:52:33 volumio9 sh[526]: forked to background, child pid 939 Jul 03 18:52:33 volumio9 dhcpcd[575]: forked to background, child pid 939 Jul 03 18:52:33 volumio9 ifplugd(eth0)[786]: client: ifup: interface eth0 already configured Jul 03 18:52:33 volumio9 sh[526]: eth0=eth0 Jul 03 18:52:33 volumio9 ifplugd(eth0)[786]: Program executed successfully. Jul 03 18:52:33 volumio9 volumio[832]: info: Loading plugin "outputs"... Jul 03 18:52:33 volumio9 volumio[832]: info: Loading plugin "albumart"... Jul 03 18:52:33 volumio9 volumio[832]: info: Plugin example_plugin is not enabled Jul 03 18:52:33 volumio9 volumio[832]: info: Loading plugin "inputs"... Jul 03 18:52:33 volumio9 volumio[832]: info: Loading plugin "updater_comm"... Jul 03 18:52:34 volumio9 volumio[832]: info: Plugin mpdemulation is not enabled Jul 03 18:52:34 volumio9 volumio[832]: info: Loading plugin "rest_api"... Jul 03 18:52:34 volumio9 volumio[832]: info: Loading plugin "websocket"... Jul 03 18:52:34 volumio9 volumio[832]: info: Starting Socket.io Server version 2.3.0 Jul 03 18:52:34 volumio9 volumio[832]: info: Plugin peppymeterbasic is not enabled Jul 03 18:52:34 volumio9 volumio[832]: info: Loading plugin "touch_display"... Jul 03 18:52:34 volumio9 volumio[832]: Forking 3 albumart workers Jul 03 18:52:34 volumio9 volumio-remote-updater[600]: [2025-07-03 18:52:34] [connect] Successful connection Jul 03 18:52:34 volumio9 volumio[832]: info: Applying required configuration parameters for plugin touch_display Jul 03 18:52:34 volumio9 ntpd[779]: Listen normally on 3 eth0 192.168.1.77:123 Jul 03 18:52:34 volumio9 ntpd[779]: new interface(s) found: waking up resolver Jul 03 18:52:34 volumio9 volumio[832]: Starting albumart workers Jul 03 18:52:34 volumio9 volumio[832]: Starting albumart workers Jul 03 18:52:34 volumio9 volumio[832]: Starting albumart workers Jul 03 18:52:34 volumio9 volumio[832]: info: Loading i18n strings for locale th Jul 03 18:52:34 volumio9 volumio[832]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 03 18:52:34 volumio9 volumio[832]: Updating browse sources language Jul 03 18:52:34 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::initPlayerControls Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: Express server listening on port 3000 Jul 03 18:52:35 volumio9 volumio[832]: [Metrics] WebUI: 6s 587.08ms Jul 03 18:52:35 volumio9 volumio[832]: info: CoreStateMachine::resetVolumioState Jul 03 18:52:35 volumio9 volumio[832]: info: CoreStateMachine::getcurrentVolume Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 18:52:35 volumio9 volumio[832]: info: CoreStateMachine::pushState Jul 03 18:52:35 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioPushState Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: Volumio Network Manager: Network status updated: 0 Jul 03 18:52:35 volumio9 volumio[832]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Jul 03 18:52:35 volumio9 volumio[832]: verbose: New Socket.io Connection to 192.168.1.77 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.117 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 03 18:52:35 volumio9 volumio[832]: verbose: New Socket.io Connection to 192.168.1.77:3000 from 192.168.1.73 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jul 03 18:52:35 volumio9 volumio-remote-updater[600]: [2025-07-03 18:52:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751543554 101 Jul 03 18:52:35 volumio9 volumio[832]: 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 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 18:52:35 volumio9 volumio[832]: info: Discovery: Getting this device information Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:52:35 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 18:52:35 volumio9 volumio[832]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 03 18:52:35 volumio9 volumio[832]: info: Reloading queue from file Jul 03 18:52:35 volumio9 volumio[832]: info: CoreStateMachine::setRepeat null single undefined Jul 03 18:52:35 volumio9 volumio[832]: info: CoreStateMachine::pushState Jul 03 18:52:35 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioPushState Jul 03 18:52:35 volumio9 volumio[832]: info: CoreStateMachine::setRandom null Jul 03 18:52:35 volumio9 volumio[832]: info: CoreStateMachine::pushState Jul 03 18:52:35 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioPushState Jul 03 18:52:35 volumio9 volumio[832]: info: Setting Device type: Raspberry PI Jul 03 18:52:35 volumio9 volumio[832]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 03 18:52:35 volumio9 volumio[832]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jul 03 18:52:35 volumio9 volumio[832]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 03 18:52:35 volumio9 volumio[832]: verbose: New Socket.io Connection to 192.168.1.77:3000 from 192.168.1.73 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 03 18:52:35 volumio9 volumio[832]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:52:35 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 03 18:52:35 volumio9 volumio[832]: info: Completed loading Core Plugins Jul 03 18:52:35 volumio9 volumio[832]: info: Preparing to generate the ALSA configuration file Jul 03 18:52:35 volumio9 volumio[832]: info: Asound.conf file unchanged, so no further update is needed Jul 03 18:52:35 volumio9 volumio[832]: info: Output device has changed, restarting MPD Jul 03 18:52:35 volumio9 sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 18:52:35 volumio9 volumio[832]: info: Output device has changed, restarting Shairport Sync Jul 03 18:52:35 volumio9 sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:52:35 volumio9 sudo[1026]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:35 volumio9 sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 18:52:35 volumio9 sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:35 volumio9 systemd[1]: Stopping Music Player Daemon... Jul 03 18:52:35 volumio9 volumio[832]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:52:35 volumio9 volumio[832]: info: ___________ START PLUGINS ___________ Jul 03 18:52:35 volumio9 volumio[832]: info: ControllerMpd::onStart: Initializing MPD Jul 03 18:52:35 volumio9 volumio[832]: info: Creating MPD Configuration file Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:52:35 volumio9 volumio[832]: info: [1751543555584] CoreMusicLibrary::Adding element Media Servers Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:52:35 volumio9 sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 18:52:35 volumio9 sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:52:35 volumio9 sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:35 volumio9 sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:35 volumio9 sudo[1035]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:35 volumio9 nmbd[760]: [2025/07/03 18:52:35.626878, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 18:52:35 volumio9 systemd[1]: Started Samba NMB Daemon. Jul 03 18:52:35 volumio9 nmbd[760]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 03 18:52:35 volumio9 volumio[832]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:52:35 volumio9 systemd[1]: Starting Samba Winbind Daemon... Jul 03 18:52:35 volumio9 volumio[832]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:52:35 volumio9 volumio[832]: info: [1751543555704] CoreMusicLibrary::Adding element Last_100 Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:52:35 volumio9 volumio[832]: info: [1751543555706] CoreMusicLibrary::Adding element Webradio Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 18:52:35 volumio9 volumio[832]: info: Initializing BBC Radios Jul 03 18:52:35 volumio9 systemd[1]: mpd.service: Succeeded. Jul 03 18:52:35 volumio9 systemd[1]: Stopped Music Player Daemon. Jul 03 18:52:35 volumio9 systemd[1]: Starting Music Player Daemon... Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:52:35 volumio9 volumio[832]: info: Creating Spotify config file Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:35 volumio9 sudo[1051]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 18:52:35 volumio9 sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:35 volumio9 sudo[1051]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:35 volumio9 volumio[832]: info: Loading i18n strings for locale th Jul 03 18:52:35 volumio9 volumio[832]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 03 18:52:35 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 18:52:35 volumio9 volumio[832]: info: Volumio Calling Home Jul 03 18:52:35 volumio9 winbindd[1043]: [2025/07/03 18:52:35.934119, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 03 18:52:35 volumio9 winbindd[1043]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 03 18:52:35 volumio9 sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 03 18:52:35 volumio9 sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:35 volumio9 sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 03 18:52:35 volumio9 sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:35 volumio9 winbindd[1043]: [2025/07/03 18:52:35.997697, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 18:52:35 volumio9 winbindd[1043]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 03 18:52:36 volumio9 sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 03 18:52:36 volumio9 sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:36 volumio9 volumio[832]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Jul 03 18:52:36 volumio9 systemd[1]: Started Samba Winbind Daemon. Jul 03 18:52:36 volumio9 systemd[1]: Reloading. Jul 03 18:52:36 volumio9 volumio[832]: info: MPD Permissions set Jul 03 18:52:36 volumio9 volumio[832]: info: MPD Permissions set Jul 03 18:52:36 volumio9 volumio[832]: info: Spotify config file written Jul 03 18:52:36 volumio9 sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 03 18:52:36 volumio9 sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:36 volumio9 volumio[832]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 03 18:52:36 volumio9 volumio[832]: info: No need to fix Spotify hosts Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:52:36 volumio9 volumio[832]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 03 18:52:36 volumio9 sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 03 18:52:36 volumio9 sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:36 volumio9 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. Jul 03 18:52:36 volumio9 sudo[1100]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:36 volumio9 volumio[832]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 03 18:52:36 volumio9 volumio-time-update[594]: volumio-time-update-util: Fetching time from Volumio... Jul 03 18:52:36 volumio9 sudo[1071]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:36 volumio9 mpd[1060]: Jul 03 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 18:52:36 volumio9 systemd[1]: Reloading. Jul 03 18:52:36 volumio9 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. Jul 03 18:52:37 volumio9 volumio-time-update[594]: volumio-time-update-util: Setting system time to: 2025-07-03 18:53:11 Jul 03 18:52:37 volumio9 sudo[1122]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-03 18:53:11 Jul 03 18:52:37 volumio9 sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:37 volumio9 systemd[1]: Started Music Player Daemon. Jul 03 18:52:37 volumio9 sudo[1068]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:37 volumio9 sudo[1037]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:37 volumio9 sudo[1028]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:37 volumio9 volumio[832]: info: Starting Shairport Sync Jul 03 18:52:37 volumio9 volumio[832]: info: Starting Shairport Sync Jul 03 18:52:37 volumio9 volumio[832]: info: Starting Shairport Sync Jul 03 18:52:37 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:52:37 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:37 volumio9 volumio[832]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 03 18:52:37 volumio9 volumio[832]: info: touch_display: systemctl daemon-reload succeeded. Jul 03 18:52:37 volumio9 volumio[832]: info: Volumio called home Jul 03 18:52:37 volumio9 volumio[832]: verbose: New Socket.io Connection to 192.168.1.77 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.117 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 03 18:52:37 volumio9 volumio[832]: info: MPD running with PID1060 Jul 03 18:52:37 volumio9 volumio[832]: ,establishing connection Jul 03 18:52:37 volumio9 sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:52:37 volumio9 sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:37 volumio9 sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 03 18:52:37 volumio9 volumio[832]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 03 18:52:37 volumio9 volumio[832]: SPOTIFY: BQB-P26trRDhEHFlleyuCNfS_wztJqpoidVDZSpO75aggKC39yqzpXlVTrqjTI0boPio3GZCDSJ2nbP70ERXnfrXZ4IIEzJ7zDqdke97SALa6ySJYVE82p2XlOXalYemvhjcfDc9-p0e5_t0zf8ygjHJcbxyxMKLE4N_a5fX1_JHKRpEB_FrFxgek9nzchlbbsA84Cb_zzWsib0VlTp___V059O2jMVKETPwqdOXoGRmc214s52KCs8_1HFyKra2bsRlLexoEmiy9T0DIuGjeCseHX8USuLZUNtaxzWRLDAjbCQKVkY Jul 03 18:52:37 volumio9 volumio[832]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 03 18:52:37 volumio9 volumio[832]: info: New Spotify access token = BQB-P26trRDhEHFlleyuCNfS_wztJqpoidVDZSpO75aggKC39yqzpXlVTrqjTI0boPio3GZCDSJ2nbP70ERXnfrXZ4IIEzJ7zDqdke97SALa6ySJYVE82p2XlOXalYemvhjcfDc9-p0e5_t0zf8ygjHJcbxyxMKLE4N_a5fX1_JHKRpEB_FrFxgek9nzchlbbsA84Cb_zzWsib0VlTp___V059O2jMVKETPwqdOXoGRmc214s52KCs8_1HFyKra2bsRlLexoEmiy9T0DIuGjeCseHX8USuLZUNtaxzWRLDAjbCQKVkY Jul 03 18:52:37 volumio9 volumio[832]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 03 18:52:37 volumio9 dbus-daemon[602]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.12' (uid=0 pid=1123 comm="timedatectl set-time 2025-07-03 18:53:11 ") Jul 03 18:52:37 volumio9 sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:52:37 volumio9 sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:37 volumio9 sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:52:37 volumio9 sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:37 volumio9 sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:52:37 volumio9 volumio[832]: error: MPD error: The expression evaluated to a falsy value: Jul 03 18:52:37 volumio9 volumio[832]: assert.ok(self.idling) Jul 03 18:52:37 volumio9 volumio[832]: error: The expression evaluated to a falsy value: Jul 03 18:52:37 volumio9 volumio[832]: assert.ok(self.idling) Jul 03 18:52:37 volumio9 volumio[832]: error: MPD error: The expression evaluated to a falsy value: Jul 03 18:52:37 volumio9 volumio[832]: assert.ok(self.idling) Jul 03 18:52:37 volumio9 volumio[832]: error: The expression evaluated to a falsy value: Jul 03 18:52:37 volumio9 volumio[832]: assert.ok(self.idling) Jul 03 18:52:37 volumio9 volumio[832]: error: updateQueue error: null Jul 03 18:52:37 volumio9 systemd[1]: Started Volumio Kiosk. Jul 03 18:52:37 volumio9 sudo[1134]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:37 volumio9 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 18:52:37 volumio9 volumio[832]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 03 18:52:37 volumio9 volumio[832]: info: touch_display: Volumio Kiosk started. Jul 03 18:52:37 volumio9 systemd[1]: Starting Time & Date Service... Jul 03 18:52:37 volumio9 systemd[1]: Started go-librespot Daemon. Jul 03 18:52:37 volumio9 sudo[1076]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:37 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:52:37 volumio9 go-librespot[1143]: go-librespot daemon starting... Jul 03 18:52:37 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:37 volumio9 volumio[832]: info: Completed starting Core Plugins Jul 03 18:52:37 volumio9 systemd[1]: Starting Samba SMB Daemon... Jul 03 18:52:37 volumio9 volumio[832]: info: ------------------------------------------- Jul 03 18:52:37 volumio9 volumio[832]: info: ----- MyVolumio plugins startup ---- Jul 03 18:52:37 volumio9 volumio[832]: info: ------------------------------------------- Jul 03 18:52:37 volumio9 volumio[832]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 03 18:52:37 volumio9 systemd[1]: shairport-sync.service: Succeeded. Jul 03 18:52:37 volumio9 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 18:52:37 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:52:37 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:37 volumio9 sudo[1066]: pam_unix(sudo:session): session closed for user root Jul 03 18:52:37 volumio9 volumio[832]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 03 18:52:37 volumio9 dbus-daemon[602]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 03 18:52:38 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 18:52:38 volumio9 systemd[1]: Started Time & Date Service. Jul 03 18:52:38 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 18:52:38 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:52:38 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:52:38 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:52:38 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 18:52:38 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetQueue Jul 03 18:52:38 volumio9 volumio[832]: info: CoreStateMachine::getQueue Jul 03 18:52:38 volumio9 volumio[832]: info: CorePlayQueue::getQueue Jul 03 18:52:38 volumio9 volumio[832]: info: Listing playlists Jul 03 18:52:38 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 18:53:11 volumio9 systemd-timedated[1141]: Changed local time to Thu Jul 3 18:53:11 2025 Jul 03 18:53:11 volumio9 sudo[1122]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:11 volumio9 volumio-time-update[594]: volumio-time-update-util: System time updated successfully. Jul 03 18:53:11 volumio9 volumio[832]: info: Received Get System Info Jul 03 18:53:11 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 18:53:11 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 18:53:11 volumio9 volumio[832]: info: Discovery: Getting this device information Jul 03 18:53:11 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:53:11 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:53:11 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 18:53:11 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:53:11 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:53:11 volumio9 volumio[832]: info: touch_display: X display number found: 0 Jul 03 18:53:11 volumio9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 18:53:11 volumio9 sudo[1127]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:11 volumio9 systemd[1]: Started Volumio Time Update Utility. Jul 03 18:53:11 volumio9 sudo[1125]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:11 volumio9 sudo[1129]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:11 volumio9 volumio[832]: info: touch_display: X display number found: 0 Jul 03 18:53:11 volumio9 volumio[832]: info: Shairport-Sync Started Jul 03 18:53:11 volumio9 volumio[832]: Error adding Membership: Error: addMembership EINVAL Jul 03 18:53:11 volumio9 volumio[832]: info: Shairport-Sync Started Jul 03 18:53:11 volumio9 volumio[832]: info: Shairport-Sync Started Jul 03 18:53:11 volumio9 volumio[832]: SPOTIFY: User informations: {"country":"TH","display_name":"ชนะ ชนะภัย","email":"schanapai@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31ujwh6hkh6kshabxeokjduu7j3e"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31ujwh6hkh6kshabxeokjduu7j3e","id":"31ujwh6hkh6kshabxeokjduu7j3e","images":[{"height":300,"url":"https://scontent-cdg4-3.xx.fbcdn.net/v/t39.30808-1/405560085_6916165708500864_5513590859254949333_n.jpg?stp=c427.0.1518.1518a_dst-jpg_s240x240_tt6&_nc_cat=106&ccb=1-7&_nc_sid=79bf43&_nc_ohc=0FpSZJcCOEkQ7kNvwHmZpcZ&_nc_oc=AdmKUhLntMjAEa9htLCmfsbdk5tIUp8uNczYBJQNfVuYXWdKONWMCGE1HF9mWktiith6lXR9WJnqKEqnmWXRNldB&_nc_zt=24&_nc_ht=scontent-cdg4-3.xx&edm=AP4hL3IEAAAA&_nc_gid=Ap01GNL1R0Y-1tonB5Endg&oh=00_AfOFOmfvnJEE0Q71xW_sxpiLFrflpkT9MNb0xJ2I1BEM-Q&oe=686C27B5","width":300},{"height":64,"url":"https://scontent-cdg4-3.xx.fbcdn.net/v/t39.30808-1/405560085_6916165708500864_5513590859254949333_n.jpg?stp=c427.0.1518.1518a_cp0_dst-jpg_s50x50_tt6&_nc_cat=106&ccb=1-7&_nc_sid=28885b&_nc_ohc=0FpSZJcCOEkQ7kNvwHmZpcZ&_nc_oc=AdmKUhLntMjAEa9htLCmfsbdk5tIUp8uNczYBJQNfVuYXWdKONWMCGE1HF9mWktiith6lXR9WJnqKEqnmWXRNldB&_nc_zt=24&_nc_ht=scontent-cdg4-3.xx&edm=AP4hL3IEAAAA&_nc_gid=Ap01GNL1R0Y-1tonB5Endg&oh=00_AfMDYYj8fDBwOWIIJbQsyi6vEZ8erEreaNKpHGGoeSR_jA&oe=686C27B5","width":64}],"product":"premium","type":"user","uri":"spotify:user:31ujwh6hkh6kshabxeokjduu7j3e"} Jul 03 18:53:11 volumio9 volumio[832]: info: Spotify Successfully logged in Jul 03 18:53:11 volumio9 volumio[832]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:53:11 volumio9 volumio[832]: info: [1751543591196] CoreMusicLibrary::Adding element Spotify Jul 03 18:53:11 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:53:11 volumio9 volumio[832]: Cannot find translation for source Spotify Jul 03 18:53:11 volumio9 volumio[832]: info: touch_display: X display number found: 0 Jul 03 18:53:11 volumio9 volumio[832]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 03 18:53:11 volumio9 volumio[832]: info: touch_display: X display number found: 0 Jul 03 18:53:11 volumio9 startx[1139]: X.Org X Server 1.20.4 Jul 03 18:53:11 volumio9 startx[1139]: X Protocol Version 11, Revision 0 Jul 03 18:53:11 volumio9 startx[1139]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 03 18:53:11 volumio9 startx[1139]: Current Operating System: Linux volumio9 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Jul 03 18:53:11 volumio9 startx[1139]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:68:34:85 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=e88ce58b-e81a-40e3-8391-6dece4f7098e uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jul 03 18:53:11 volumio9 startx[1139]: Build Date: 04 April 2023 07:50:56AM Jul 03 18:53:11 volumio9 startx[1139]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 03 18:53:11 volumio9 startx[1139]: Current version of pixman: 0.36.0 Jul 03 18:53:11 volumio9 startx[1139]: Before reporting problems, check http://wiki.x.org Jul 03 18:53:11 volumio9 startx[1139]: to make sure that you have the latest version. Jul 03 18:53:11 volumio9 startx[1139]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 03 18:53:11 volumio9 startx[1139]: (++) from command line, (!!) notice, (II) informational, Jul 03 18:53:11 volumio9 startx[1139]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 03 18:53:11 volumio9 startx[1139]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jul 3 18:53:11 2025 Jul 03 18:53:11 volumio9 startx[1139]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 03 18:53:11 volumio9 startx[1139]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 03 18:53:11 volumio9 smbd[1158]: [2025/07/03 18:53:11.548383, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 18:53:11 volumio9 smbd[1158]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 03 18:53:11 volumio9 systemd[1]: Started Samba SMB Daemon. Jul 03 18:53:11 volumio9 systemd[1]: Reached target Multi-User System. Jul 03 18:53:11 volumio9 systemd[1]: Reached target Graphical Interface. Jul 03 18:53:11 volumio9 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 03 18:53:11 volumio9 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 03 18:53:11 volumio9 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 03 18:53:11 volumio9 systemd[1]: Startup finished in 16.887s (kernel) + 21.711s (userspace) = 38.599s. Jul 03 18:53:11 volumio9 go-librespot[1143]: time="2025-07-03T18:53:11+07:00" level=info msg="running go-librespot 0.2.0" Jul 03 18:53:11 volumio9 go-librespot[1143]: time="2025-07-03T18:53:11+07:00" level=debug msg="app state loaded" Jul 03 18:53:11 volumio9 go-librespot[1143]: time="2025-07-03T18:53:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=info msg="zeroconf server listening on port 39673" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=debug msg="obtained new client token: AAAO1kuqF77iqfRDC+i0L1zWbrKNiSe144LfrMplc3MqXzOLd3OPSgkbCSCt8l0ymRgEC7nnEjLInyRdr2Q2NrGDWIdmFjl8l47xV7Fx/4euwM1Nj/swj0MmI9+SsmJK4Dn3AbR/H2rdo07ljM5Dgjyu7IdgpAb681Z2elraJBMgZh9XEVVD3k7nAfbaPW8bd2P3YPz+7PpEgBdBBFKYGgNo1C6p9pBytanT2+Yt8NAd5rw5nQTcCvTJeg==" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=debug msg="completed keyexchange" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=debug msg="completed challenge" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=info msg="authenticated AP as 31ujwh6hkh6kshabxeokjduu7j3e" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=info msg="authenticated Login5 as 31ujwh6hkh6kshabxeokjduu7j3e" Jul 03 18:53:12 volumio9 go-librespot[1143]: time="2025-07-03T18:53:12+07:00" level=debug msg="initializing zeroconf session, username: 31ujwh6hkh6kshabxeokjduu7j3e" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="dealer connection opened" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=trace msg="starting accesspoint recv loop" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=trace msg="starting dealer recv loop" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=trace msg="received accesspoint ping" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="received connection id: OTgyNWE1OTUtYzE2NS00M2Y2LWI3ZDktMjgxYTFiYmRmYjAxK2RlYWxlcit0Y3A6Ly8wYWIxNTgyYi5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMTUwNTRCMkU3NTI2QTI5OTlBMDIxODMwQTA2OTgxNUZCREU1ODIxNzQ4MUI1NURBMzk2MTg5MUQxREUyRjQ4MA==" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=trace msg="received accesspoint pong ack" Jul 03 18:53:13 volumio9 sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.12/Hires Music /mnt/NAS/Music Jul 03 18:53:13 volumio9 sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:53:13 volumio9 kernel: CIFS: Attempting to mount //192.168.1.12/Hires Music Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="put connect state because NEW_DEVICE" Jul 03 18:53:13 volumio9 go-librespot[1143]: time="2025-07-03T18:53:13+07:00" level=debug msg="update volume to 65535/65535" Jul 03 18:53:13 volumio9 kernel: cryptd: max_cpu_qlen set to 1000 Jul 03 18:53:13 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:53:13 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:53:13 volumio9 volumio[832]: info: touch_display: Setting screensaver timeout to 45 seconds. Jul 03 18:53:13 volumio9 sudo[1205]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:13 volumio9 volumio[832]: info: go-librespot daemon successfully initialized Jul 03 18:53:14 volumio9 volumio[832]: info: Volumio Network Manager: Network status updated: 1 Jul 03 18:53:14 volumio9 go-librespot[1143]: time="2025-07-03T18:53:14+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 03 18:53:14 volumio9 go-librespot[1143]: time="2025-07-03T18:53:14+07:00" level=trace msg="emitting websocket event: volume" Jul 03 18:53:14 volumio9 sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 18:53:14 volumio9 sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:53:14 volumio9 sudo[1284]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:14 volumio9 sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 18:53:14 volumio9 sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:53:14 volumio9 sudo[1286]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:14 volumio9 sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 03 18:53:14 volumio9 sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:53:14 volumio9 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 03 18:53:14 volumio9 sudo[1290]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:14 volumio9 volumio[832]: info: Upmpdcli Daemon Started Jul 03 18:53:15 volumio9 volumio[1292]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 03 18:53:15 volumio9 volumio[832]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 03 18:53:16 volumio9 volumio[832]: info: Initializing connection to go-librespot Websocket Jul 03 18:53:16 volumio9 go-librespot[1143]: time="2025-07-03T18:53:16+07:00" level=debug msg="new websocket client" Jul 03 18:53:16 volumio9 volumio[832]: info: Connection to go-librespot Websocket established Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 18:53:18 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 03 18:53:19 volumio9 volumio[832]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 03 18:53:19 volumio9 volumio[832]: info: Adding plugin bluetooth to MyMusic Plugins Jul 03 18:53:19 volumio9 volumio[832]: info: Adding plugin multiroom to MyMusic Plugins Jul 03 18:53:19 volumio9 volumio[832]: info: Adding plugin metavolumio to MyMusic Plugins Jul 03 18:53:20 volumio9 volumio[1292]: .....................................................++++ Jul 03 18:53:20 volumio9 volumio[832]: info: Adding plugin cd_controller to MyMusic Plugins Jul 03 18:53:20 volumio9 volumio[832]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 03 18:53:20 volumio9 volumio[832]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 03 18:53:20 volumio9 volumio[832]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 03 18:53:20 volumio9 volumio[832]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 03 18:53:22 volumio9 volumio[832]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 03 18:53:22 volumio9 volumio[832]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 03 18:53:22 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:53:22 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:53:22 volumio9 volumio[832]: info: Starting MyVolumio Remote Streaming Endpoints Jul 03 18:53:22 volumio9 volumio[832]: info: MyVolumio login type: Token Jul 03 18:53:22 volumio9 volumio[832]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 03 18:53:22 volumio9 volumio[832]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 03 18:53:23 volumio9 volumio[832]: info: Starting Streaming Service Transparent Proxy Jul 03 18:53:23 volumio9 volumio[832]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 03 18:53:23 volumio9 volumio[832]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 03 18:53:23 volumio9 volumio[832]: info: Streaming services startup Jul 03 18:53:23 volumio9 volumio[832]: info: Starting Streaming Daemon Jul 03 18:53:23 volumio9 sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 18:53:23 volumio9 sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:53:23 volumio9 sudo[1391]: pam_unix(sudo:session): session closed for user root Jul 03 18:53:23 volumio9 volumio[832]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 03 18:53:23 volumio9 volumio[832]: info: Getting Spotify volume Jul 03 18:53:23 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 03 18:53:23 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 03 18:53:23 volumio9 volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 18:53:23 volumio9 volumio[832]: error: Cannot start Volumio Streaming Daemon Jul 03 18:53:23 volumio9 volumio[832]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 03 18:53:23 volumio9 volumio[832]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 03 18:53:23 volumio9 volumio[832]: info: Spotify volume: 100 Jul 03 18:53:23 volumio9 volumio[832]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 03 18:53:23 volumio9 volumio[832]: info: CoreCommandRouter::volumioGetState Jul 03 18:53:23 volumio9 volumio[832]: info: CorePlayQueue::getTrack 0 Jul 03 18:53:23 volumio9 volumio[832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 18:53:23 volumio9 volumio[832]: Error: getaddrinfo -3007 Jul 03 18:53:23 volumio9 volumio[832]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jul 03 18:53:23 volumio9 volumio[832]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jul 03 18:53:23 volumio9 volumio[832]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jul 03 18:53:23 volumio9 volumio[832]: code: -3007, Jul 03 18:53:23 volumio9 volumio[832]: errno: -3007, Jul 03 18:53:23 volumio9 volumio[832]: syscall: 'getaddrinfo' Jul 03 18:53:23 volumio9 volumio[832]: } Jul 03 18:53:23 volumio9 volumio[832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 18:53:23 volumio9 systemd[1]: systemd-fsckd.service: Succeeded. Jul 03 18:53:23 volumio9 sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 18:52 Jul 03 18:53:23 volumio9 sudo[1414]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"