-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Sun 2024-10-13 17:42:33 KST. -- Oct 13 17:41:52 volumio-pi systemd[1]: Started Remount Root and Kernel File Systems. Oct 13 17:41:52 volumio-pi systemd[1]: Mounted RPC Pipe File System. Oct 13 17:41:52 volumio-pi systemd[1]: Started Restore / save the current clock. Oct 13 17:41:52 volumio-pi systemd[1]: Mounted Kernel Debug File System. Oct 13 17:41:52 volumio-pi systemd[1]: Mounted POSIX Message Queue File System. Oct 13 17:41:52 volumio-pi fake-hwclock[304]: Sun Oct 13 08:41:52 UTC 2024 Oct 13 17:41:52 volumio-pi systemd[1]: Mounted Kernel Configuration File System. Oct 13 17:41:52 volumio-pi systemd[1]: Starting Create System Users... Oct 13 17:41:52 volumio-pi systemd[1]: Starting Load/Save Random Seed... Oct 13 17:41:52 volumio-pi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 13 17:41:52 volumio-pi systemd[1]: Mounted FUSE Control File System. Oct 13 17:41:52 volumio-pi systemd[1]: Started Load/Save Random Seed. Oct 13 17:41:52 volumio-pi systemd[1]: Started Apply Kernel Variables. Oct 13 17:41:52 volumio-pi systemd[1]: dynamicswap.service: Succeeded. Oct 13 17:41:52 volumio-pi systemd[1]: Started Create System Users. Oct 13 17:41:52 volumio-pi systemd[1]: Starting Create Static Device Nodes in /dev... Oct 13 17:41:52 volumio-pi systemd[1]: Started udev Coldplug all Devices. Oct 13 17:41:52 volumio-pi systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 13 17:41:52 volumio-pi systemd[1]: Started Create Static Device Nodes in /dev. Oct 13 17:41:52 volumio-pi systemd[1]: Reached target Local File Systems (Pre). Oct 13 17:41:52 volumio-pi systemd[1]: Mounting /var/spool/cups... Oct 13 17:41:52 volumio-pi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 13 17:41:52 volumio-pi systemd[1]: Mounting /var/log... Oct 13 17:41:52 volumio-pi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 13 17:41:52 volumio-pi systemd[1]: Mounting /tmp... Oct 13 17:41:52 volumio-pi systemd[1]: Starting udev Kernel Device Manager... Oct 13 17:41:52 volumio-pi systemd[1]: Mounted /var/spool/cups. Oct 13 17:41:52 volumio-pi systemd[1]: Mounted /var/log. Oct 13 17:41:52 volumio-pi systemd[1]: Mounted /tmp. Oct 13 17:41:52 volumio-pi systemd[1]: Starting Flush Journal to Persistent Storage... Oct 13 17:41:52 volumio-pi systemd[1]: Mounting /var/spool/cups/tmp... Oct 13 17:41:52 volumio-pi systemd[1]: Mounted /var/spool/cups/tmp. Oct 13 17:41:52 volumio-pi systemd-udevd[345]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 13 17:41:52 volumio-pi systemd-journald[307]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 11.2M, max 30.0M, 18.7M free. Oct 13 17:41:52 volumio-pi systemd[1]: Started Flush Journal to Persistent Storage. Oct 13 17:41:52 volumio-pi systemd[1]: Started udev Kernel Device Manager. Oct 13 17:41:52 volumio-pi systemd[1]: Starting Show Plymouth Boot Screen... Oct 13 17:41:52 volumio-pi systemd[1]: plymouth-start.service: Succeeded. Oct 13 17:41:52 volumio-pi systemd[1]: Started Show Plymouth Boot Screen. Oct 13 17:41:52 volumio-pi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 13 17:41:52 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 13 17:41:52 volumio-pi systemd[1]: Reached target Local Encrypted Volumes. Oct 13 17:41:52 volumio-pi systemd[1]: Reached target Paths. Oct 13 17:41:53 volumio-pi kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Oct 13 17:41:53 volumio-pi kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Oct 13 17:41:53 volumio-pi kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:53 volumio-pi kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Oct 13 17:41:53 volumio-pi kernel: [vc_sm_connected_init]: start Oct 13 17:41:53 volumio-pi kernel: [vc_sm_connected_init]: installed successfully Oct 13 17:41:53 volumio-pi kernel: mc: Linux media interface: v0.10 Oct 13 17:41:53 volumio-pi kernel: videodev: Linux video capture interface: v2.00 Oct 13 17:41:53 volumio-pi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:53 volumio-pi kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:53 volumio-pi kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 13 17:41:53 volumio-pi kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Oct 13 17:41:53 volumio-pi kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Oct 13 17:41:53 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Oct 13 17:41:54 volumio-pi systemd[1]: Found device /dev/disk/by-uuid/E8CB-9B11. Oct 13 17:41:54 volumio-pi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 13 17:41:54 volumio-pi systemd-udevd[358]: Using default interface naming scheme 'v240'. Oct 13 17:41:54 volumio-pi systemd-udevd[358]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 13 17:41:54 volumio-pi kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Oct 13 17:41:54 volumio-pi kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Oct 13 17:41:54 volumio-pi systemd[1]: Starting File System Check on /dev/disk/by-uuid/E8CB-9B11... Oct 13 17:41:54 volumio-pi kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 13 17:41:54 volumio-pi kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:54 volumio-pi kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Oct 13 17:41:54 volumio-pi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Oct 13 17:41:54 volumio-pi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Oct 13 17:41:54 volumio-pi systemd-udevd[357]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 13 17:41:54 volumio-pi kernel: Bluetooth: Core ver 2.22 Oct 13 17:41:54 volumio-pi kernel: NET: Registered PF_BLUETOOTH protocol family Oct 13 17:41:54 volumio-pi kernel: Bluetooth: HCI device and connection manager initialized Oct 13 17:41:54 volumio-pi kernel: Bluetooth: HCI socket layer initialized Oct 13 17:41:54 volumio-pi kernel: Bluetooth: L2CAP socket layer initialized Oct 13 17:41:54 volumio-pi kernel: Bluetooth: SCO socket layer initialized Oct 13 17:41:54 volumio-pi systemd-fsck[424]: fsck.fat 4.1 (2017-01-24) Oct 13 17:41:54 volumio-pi systemd-fsck[424]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Oct 13 17:41:54 volumio-pi systemd-fsck[424]: Automatically removing dirty bit. Oct 13 17:41:54 volumio-pi systemd-fsck[424]: Performing changes. Oct 13 17:41:54 volumio-pi systemd-fsck[424]: /dev/mmcblk0p1: 375 files, 33705/46774 clusters Oct 13 17:41:54 volumio-pi systemd[1]: Started File System Check on /dev/disk/by-uuid/E8CB-9B11. Oct 13 17:41:54 volumio-pi kernel: Bluetooth: HCI UART driver ver 2.3 Oct 13 17:41:54 volumio-pi kernel: Bluetooth: HCI UART protocol H4 registered Oct 13 17:41:54 volumio-pi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Oct 13 17:41:54 volumio-pi kernel: Bluetooth: HCI UART protocol Broadcom registered Oct 13 17:41:54 volumio-pi kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Oct 13 17:41:54 volumio-pi kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Oct 13 17:41:54 volumio-pi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Oct 13 17:41:54 volumio-pi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Oct 13 17:41:54 volumio-pi kernel: uart-pl011 3f201000.serial: no DMA platform data Oct 13 17:41:54 volumio-pi kernel: usbcore: registered new interface driver brcmfmac Oct 13 17:41:55 volumio-pi kernel: Bluetooth: hci0: BCM: chip id 107 Oct 13 17:41:55 volumio-pi kernel: Bluetooth: hci0: BCM: features 0x2f Oct 13 17:41:55 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 Oct 13 17:41:55 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Oct 13 17:41:55 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Oct 13 17:41:55 volumio-pi 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 Oct 13 17:41:56 volumio-pi systemd-udevd[348]: Using default interface naming scheme 'v240'. Oct 13 17:41:56 volumio-pi systemd-udevd[348]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 13 17:41:56 volumio-pi kernel: Bluetooth: hci0: BCM: features 0x2f Oct 13 17:41:56 volumio-pi kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Oct 13 17:41:56 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Oct 13 17:41:56 volumio-pi kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Oct 13 17:41:56 volumio-pi systemd[1]: Created slice system-bthelper.slice. Oct 13 17:41:56 volumio-pi systemd[1]: Starting Show Plymouth Boot Screen... Oct 13 17:41:56 volumio-pi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 13 17:41:56 volumio-pi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 13 17:41:56 volumio-pi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Oct 13 17:41:56 volumio-pi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 13 17:41:56 volumio-pi systemd[1]: Mounting /boot... Oct 13 17:41:56 volumio-pi systemd[1]: Started File System Check Daemon to report status. Oct 13 17:41:56 volumio-pi systemd[1]: Found device 7800. Oct 13 17:41:56 volumio-pi systemd[1]: Mounted /boot. Oct 13 17:41:56 volumio-pi systemd[1]: plymouth-start.service: Succeeded. Oct 13 17:41:56 volumio-pi systemd[1]: Started Show Plymouth Boot Screen. Oct 13 17:41:56 volumio-pi systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Oct 13 17:41:56 volumio-pi systemd[1]: Starting Load/Save RF Kill Switch Status... Oct 13 17:41:56 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 13 17:41:56 volumio-pi systemd[1]: Reached target Local File Systems. Oct 13 17:41:56 volumio-pi systemd[1]: Starting Preprocess NFS configuration... Oct 13 17:41:56 volumio-pi systemd[1]: Started ifup for wlan0. Oct 13 17:41:56 volumio-pi systemd[1]: Starting Create Volatile Files and Directories... Oct 13 17:41:56 volumio-pi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 13 17:41:56 volumio-pi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 13 17:41:57 volumio-pi systemd[1]: Started ifup for eth0. Oct 13 17:41:57 volumio-pi systemd[1]: nfs-config.service: Succeeded. Oct 13 17:41:57 volumio-pi systemd[1]: Started Preprocess NFS configuration. Oct 13 17:41:57 volumio-pi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 13 17:41:57 volumio-pi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 13 17:41:57 volumio-pi systemd[1]: Reached target NFS client services. Oct 13 17:41:57 volumio-pi systemd[1]: Started Load/Save RF Kill Switch Status. Oct 13 17:41:57 volumio-pi systemd[1]: Received SIGRTMIN+20 from PID 193 (plymouthd). Oct 13 17:41:57 volumio-pi systemd[1]: plymouth-read-write.service: Succeeded. Oct 13 17:41:57 volumio-pi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 13 17:41:57 volumio-pi systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 13 17:41:57 volumio-pi systemd[1]: Starting Raise network interfaces... Oct 13 17:41:57 volumio-pi systemd[1]: Started Create Volatile Files and Directories. Oct 13 17:41:57 volumio-pi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 13 17:41:57 volumio-pi systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Oct 13 17:41:57 volumio-pi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 13 17:41:57 volumio-pi systemd[1]: Starting RPC bind portmap service... Oct 13 17:41:57 volumio-pi kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 13 17:41:57 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 13 17:41:57 volumio-pi systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 13 17:41:57 volumio-pi systemd[1]: Reached target System Initialization. Oct 13 17:41:57 volumio-pi systemd[1]: Started Daily apt download activities. Oct 13 17:41:57 volumio-pi systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 13 17:41:57 volumio-pi systemd[1]: Listening on triggerhappy.socket. Oct 13 17:41:57 volumio-pi systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 13 17:41:57 volumio-pi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 13 17:41:57 volumio-pi systemd[1]: Reached target Sockets. Oct 13 17:41:57 volumio-pi systemd[1]: Reached target Basic System. Oct 13 17:41:57 volumio-pi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 13 17:41:57 volumio-pi systemd[1]: Started Volumio Log Rotation Service. Oct 13 17:41:57 volumio-pi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 13 17:41:57 volumio-pi systemd[1]: Starting dhcpcd on all interfaces... Oct 13 17:41:57 volumio-pi systemd[1]: Starting Configure Bluetooth Modems connected by UART... Oct 13 17:41:57 volumio-pi systemd[1]: Started volumio-remote-updater.service. Oct 13 17:41:57 volumio-pi systemd[1]: Started D-Bus System Message Bus. Oct 13 17:41:57 volumio-pi dhcpcd[582]: Not running dhcpcd because /etc/network/interfaces Oct 13 17:41:57 volumio-pi dhcpcd[582]: defines some interfaces that will use a Oct 13 17:41:57 volumio-pi dhcpcd[582]: DHCP client or static address Oct 13 17:41:57 volumio-pi systemd[1]: Starting WPA supplicant... Oct 13 17:41:57 volumio-pi systemd[1]: Started Manage Sound Card State (restore and store). Oct 13 17:41:57 volumio-pi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 13 17:41:57 volumio-pi sh[497]: eth0: waiting for carrier Oct 13 17:41:57 volumio-pi dhcpcd[525]: eth0: waiting for carrier Oct 13 17:41:57 volumio-pi kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Oct 13 17:41:57 volumio-pi kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Oct 13 17:41:57 volumio-pi systemd[1]: Starting Login Service... Oct 13 17:41:57 volumio-pi alsactl[614]: alsactl 1.1.8 daemon started Oct 13 17:41:57 volumio-pi systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 13 17:41:57 volumio-pi systemd[1]: Starting Wireless Services... Oct 13 17:41:57 volumio-pi systemd[1]: Starting triggerhappy global hotkey daemon... Oct 13 17:41:57 volumio-pi systemd[1]: Starting Save/Restore Sound Card State... Oct 13 17:41:57 volumio-pi systemd[1]: Started Daily apt upgrade and clean activities. Oct 13 17:41:57 volumio-pi systemd[1]: Reached target Timers. Oct 13 17:41:57 volumio-pi systemd[1]: Started Volumio Iptables Module. Oct 13 17:41:57 volumio-pi systemd[1]: Started UPnP Renderer front-end to MPD. Oct 13 17:41:57 volumio-pi systemd[1]: Started RPC bind portmap service. Oct 13 17:41:57 volumio-pi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Oct 13 17:41:57 volumio-pi systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Oct 13 17:41:57 volumio-pi systemd[1]: Failed to start dhcpcd on all interfaces. Oct 13 17:41:58 volumio-pi systemd[1]: Reached target RPC Port Mapper. Oct 13 17:41:58 volumio-pi systemd[1]: Reached target Remote File Systems (Pre). Oct 13 17:41:58 volumio-pi systemd[1]: Reached target Remote File Systems. Oct 13 17:41:58 volumio-pi thd[628]: Unable to parse trigger line: Oct 13 17:41:58 volumio-pi thd[628]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 13 17:41:58 volumio-pi thd[628]: Unable to parse trigger line: Oct 13 17:41:58 volumio-pi thd[628]: Found socket passed from systemd Oct 13 17:41:58 volumio-pi ifup[514]: ifup: waiting for lock on /run/network/ifstate.wlan0 Oct 13 17:41:58 volumio-pi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 13 17:41:58 volumio-pi systemd[1]: Started triggerhappy global hotkey daemon. Oct 13 17:41:58 volumio-pi systemd[1]: Started Save/Restore Sound Card State. Oct 13 17:41:58 volumio-pi avahi-daemon[615]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Oct 13 17:41:58 volumio-pi systemd[1]: Reached target Sound Card. Oct 13 17:41:58 volumio-pi avahi-daemon[615]: Successfully dropped root privileges. Oct 13 17:41:58 volumio-pi avahi-daemon[615]: avahi-daemon 0.7 starting up. Oct 13 17:41:58 volumio-pi sh[489]: wlan0=wlan0 Oct 13 17:41:58 volumio-pi systemd-logind[617]: New seat seat0. Oct 13 17:41:59 volumio-pi systemd[1]: hciuart.service: Succeeded. Oct 13 17:41:59 volumio-pi systemd[1]: Started Configure Bluetooth Modems connected by UART. Oct 13 17:41:59 volumio-pi avahi-daemon[615]: Successfully called chroot(). Oct 13 17:41:59 volumio-pi avahi-daemon[615]: Successfully dropped remaining capabilities. Oct 13 17:41:59 volumio-pi avahi-daemon[615]: Loading service file /services/volumio.service. Oct 13 17:41:59 volumio-pi wpa_supplicant[610]: Successfully initialized wpa_supplicant Oct 13 17:41:59 volumio-pi avahi-daemon[615]: Network interface enumeration completed. Oct 13 17:41:59 volumio-pi avahi-daemon[615]: Server startup complete. Host name is volumio-pi.local. Local service cookie is 888761618. Oct 13 17:41:59 volumio-pi avahi-daemon[615]: Service "Volumio Pi" (/services/volumio.service) successfully established. Oct 13 17:41:59 volumio-pi systemd[1]: Started Raise network interfaces. Oct 13 17:41:59 volumio-pi systemd[1]: Started Login Service. Oct 13 17:41:59 volumio-pi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 13 17:41:59 volumio-pi systemd[1]: Started WPA supplicant. Oct 13 17:41:59 volumio-pi systemd[1]: Reached target Network. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 13 17:41:59 volumio-pi volumio-remote-updater[590]: Error: No active session Oct 13 17:41:59 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:41:59] [info] asio async_connect error: system:111 (Connection refused) Oct 13 17:41:59 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:41:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 13 17:41:59 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:41:59] [error] handle_connect error: Underlying Transport Error Oct 13 17:41:59 volumio-pi systemd[1]: Starting Network Time Service... Oct 13 17:41:59 volumio-pi systemd[1]: Starting Permit User Sessions... Oct 13 17:41:59 volumio-pi systemd[1]: Reached target Network is Online. Oct 13 17:41:59 volumio-pi systemd[1]: Starting Samba NMB Daemon... Oct 13 17:41:59 volumio-pi systemd[1]: Starting /etc/rc.local Compatibility... Oct 13 17:41:59 volumio-pi volumio[633]: Could not open config: /tmp/upmpdcli.conf Oct 13 17:41:59 volumio-pi systemd[1]: Starting LSB: Brings up/down network automatically... Oct 13 17:41:59 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 17:41:59 volumio-pi systemd[1]: Starting Raspberry Pi bluetooth helper... Oct 13 17:41:59 volumio-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 17:41:59 volumio-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 17:41:59 volumio-pi systemd[1]: Started Permit User Sessions. Oct 13 17:41:59 volumio-pi systemd[1]: Started /etc/rc.local Compatibility. Oct 13 17:41:59 volumio-pi systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 13 17:41:59 volumio-pi systemd[1]: Starting Hold until boot process finishes up... Oct 13 17:42:00 volumio-pi bthelper[695]: Raspberry Pi BDADDR already set Oct 13 17:42:00 volumio-pi systemd[1]: Started Raspberry Pi bluetooth helper. Oct 13 17:42:00 volumio-pi systemd[1]: plymouth-quit.service: Succeeded. Oct 13 17:42:00 volumio-pi systemd[1]: Started Terminate Plymouth Boot Screen. Oct 13 17:42:00 volumio-pi systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 13 17:42:00 volumio-pi systemd[1]: Started Hold until boot process finishes up. Oct 13 17:42:00 volumio-pi systemd[1]: iptables.service: Succeeded. Oct 13 17:42:00 volumio-pi systemd[1]: Received SIGRTMIN+21 from PID 193 (n/a). Oct 13 17:42:00 volumio-pi systemd[1]: Received SIGRTMIN+21 from PID 193 (n/a). Oct 13 17:42:00 volumio-pi ntpd[683]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 13 17:42:00 volumio-pi systemd[1]: Started Getty on tty1. Oct 13 17:42:00 volumio-pi systemd[1]: Reached target Login Prompts. Oct 13 17:42:00 volumio-pi ntpd[683]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Oct 13 17:42:00 volumio-pi ifplugd(eth0)[745]: ifplugd 0.28 initializing. Oct 13 17:42:00 volumio-pi ifplugd(eth0)[745]: Using interface eth0/B8:27:EB:F0:15:9C with driver (version: 6.1.69-v7+) Oct 13 17:42:00 volumio-pi ifplugd(eth0)[745]: Using detection mode: SIOCETHTOOL Oct 13 17:42:00 volumio-pi ifplugd(eth0)[745]: Initialization complete, link beat not detected. Oct 13 17:42:00 volumio-pi systemd[1]: Starting Bluetooth service... Oct 13 17:42:00 volumio-pi ntpd[741]: proto: precision = 1.458 usec (-19) Oct 13 17:42:00 volumio-pi systemd[1]: Started Network Time Service. Oct 13 17:42:00 volumio-pi loadcpufreq[639]: Loading cpufreq kernel modules...done (none). Oct 13 17:42:00 volumio-pi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 13 17:42:00 volumio-pi ntpd[741]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 13 17:42:00 volumio-pi ntpd[741]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 13 17:42:00 volumio-pi ntpd[741]: Listen and drop on 0 v6wildcard [::]:123 Oct 13 17:42:00 volumio-pi ntpd[741]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 13 17:42:00 volumio-pi ntpd[741]: Listen normally on 2 lo 127.0.0.1:123 Oct 13 17:42:00 volumio-pi ntpd[741]: Listening on routing socket on fd #19 for interface updates Oct 13 17:42:00 volumio-pi ntpd[741]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 17:42:00 volumio-pi ntpd[741]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 17:42:00 volumio-pi ifplugd[690]: Network Interface Plugging Daemon...start eth0...done. Oct 13 17:42:00 volumio-pi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 13 17:42:00 volumio-pi systemd[1]: Started LSB: Brings up/down network automatically. Oct 13 17:42:00 volumio-pi cpufrequtils[752]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 13 17:42:00 volumio-pi systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 13 17:42:00 volumio-pi haveged[533]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Oct 13 17:42:00 volumio-pi haveged[533]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Oct 13 17:42:00 volumio-pi haveged[533]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00089 Oct 13 17:42:00 volumio-pi haveged[533]: haveged: fills: 0, generated: 0 Oct 13 17:42:00 volumio-pi bluetoothd[748]: Bluetooth daemon 5.50 Oct 13 17:42:00 volumio-pi bluetoothd[748]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 13 17:42:00 volumio-pi systemd[1]: Started Bluetooth service. Oct 13 17:42:00 volumio-pi systemd[1]: Reached target Bluetooth. Oct 13 17:42:00 volumio-pi bluetoothd[748]: Starting SDP server Oct 13 17:42:00 volumio-pi bluetoothd[748]: Excluding (cli) sap Oct 13 17:42:00 volumio-pi bluetoothd[748]: Bluetooth management interface 1.22 initialized Oct 13 17:42:00 volumio-pi dbus-daemon[592]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=748 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 13 17:42:00 volumio-pi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 13 17:42:00 volumio-pi kernel: Bluetooth: BNEP filters: protocol multicast Oct 13 17:42:00 volumio-pi kernel: Bluetooth: BNEP socket layer initialized Oct 13 17:42:00 volumio-pi kernel: Bluetooth: MGMT ver 1.22 Oct 13 17:42:01 volumio-pi bluetoothd[748]: Failed to set privacy: Rejected (0x0b) Oct 13 17:42:01 volumio-pi systemd[1]: Starting Hostname Service... Oct 13 17:42:01 volumio-pi dbus-daemon[592]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 13 17:42:01 volumio-pi systemd[1]: Started Hostname Service. Oct 13 17:42:01 volumio-pi ntpd[741]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 13 17:42:01 volumio-pi nmbd[702]: [2024/10/13 17:42:01.448273, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 13 17:42:01 volumio-pi nmbd[702]: started asyncdns process 774 Oct 13 17:42:01 volumio-pi nmbd[702]: [2024/10/13 17:42:01.452143, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 13 17:42:01 volumio-pi nmbd[702]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 13 17:42:01 volumio-pi nmbd[702]: [2024/10/13 17:42:01.452357, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 13 17:42:01 volumio-pi nmbd[702]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 13 17:42:01 volumio-pi wireless.js[621]: Cleaning previous... Oct 13 17:42:02 volumio-pi sudo[782]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 13 17:42:02 volumio-pi sudo[782]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:02 volumio-pi systemd[1]: systemd-rfkill.service: Succeeded. Oct 13 17:42:02 volumio-pi sudo[782]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:02 volumio-pi sudo[784]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 13 17:42:02 volumio-pi sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:02 volumio-pi ntpd[741]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 13 17:42:02 volumio-pi sudo[784]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:02 volumio-pi wireless.js[621]: Stopped aP Oct 13 17:42:02 volumio-pi sudo[791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 13 17:42:02 volumio-pi sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:02 volumio-pi sudo[791]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:02 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 13 17:42:02 volumio-pi sudo[793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 13 17:42:02 volumio-pi sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:02 volumio-pi sudo[793]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:02 volumio-pi sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 13 17:42:02 volumio-pi sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:02 volumio-pi sudo[800]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:02 volumio-pi sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 13 17:42:02 volumio-pi sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:03 volumio-pi ntpd[741]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 13 17:42:04 volumio-pi ntpd[741]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 13 17:42:04 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:42:04] [info] asio async_connect error: system:111 (Connection refused) Oct 13 17:42:04 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:42:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 13 17:42:04 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:42:04] [error] handle_connect error: Underlying Transport Error Oct 13 17:42:05 volumio-pi bthelper[695]: Changing power off succeeded Oct 13 17:42:05 volumio-pi bthelper[695]: [CHG] Controller B8:27:EB:5A:BF:36 Class: 0x0000041c Oct 13 17:42:05 volumio-pi bthelper[695]: Changing power on succeeded Oct 13 17:42:05 volumio-pi bthelper[695]: [CHG] Controller B8:27:EB:5A:BF:36 Powered: yes Oct 13 17:42:05 volumio-pi sudo[802]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi wireless.js[621]: SETTING APPROPRIATE REG DOMAIN: KR Oct 13 17:42:05 volumio-pi sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 13 17:42:05 volumio-pi sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:05 volumio-pi sudo[814]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set KR Oct 13 17:42:05 volumio-pi sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:05 volumio-pi sudo[816]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi wireless.js[621]: SUCCESSFULLY SET NEW REGDOMAIN: KR Oct 13 17:42:05 volumio-pi wireless.js[621]: Start wireless flow Oct 13 17:42:05 volumio-pi wireless.js[621]: Stopped hotspot (if there).. Oct 13 17:42:05 volumio-pi sudo[821]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 13 17:42:05 volumio-pi sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:05 volumio-pi sudo[821]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi sudo[823]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 13 17:42:05 volumio-pi sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:06 volumio-pi sudo[823]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:06 volumio-pi wireless.js[621]: DHCP IP Oct 13 17:42:06 volumio-pi wireless.js[621]: Start ap Oct 13 17:42:06 volumio-pi wpa_supplicant[825]: Successfully initialized wpa_supplicant Oct 13 17:42:06 volumio-pi sudo[826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 13 17:42:06 volumio-pi sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:06 volumio-pi dhcpcd[827]: dev: loaded udev Oct 13 17:42:06 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 13 17:42:06 volumio-pi dhcpcd-run-hooks[845]: wlan0: starting wpa_supplicant Oct 13 17:42:06 volumio-pi dhcpcd-run-hooks[850]: wlan0: failed to start wpa_supplicant Oct 13 17:42:06 volumio-pi dhcpcd-run-hooks[851]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Oct 13 17:42:06 volumio-pi dhcpcd[827]: wlan0: connected to Access Point `' Oct 13 17:42:06 volumio-pi dhcpcd[827]: no interfaces have a carrier Oct 13 17:42:06 volumio-pi dhcpcd[827]: forked to background, child pid 861 Oct 13 17:42:06 volumio-pi dhcpcd[861]: eth0: waiting for carrier Oct 13 17:42:06 volumio-pi dhcpcd[861]: wlan0: waiting for carrier Oct 13 17:42:06 volumio-pi sudo[826]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:07 volumio-pi wireless.js[621]: trying... Oct 13 17:42:07 volumio-pi sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:07 volumio-pi sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:07 volumio-pi sudo[863]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:08 volumio-pi wireless.js[621]: trying... Oct 13 17:42:08 volumio-pi sudo[866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:08 volumio-pi sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:08 volumio-pi sudo[866]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:09 volumio-pi wpa_supplicant[849]: wlan0: Trying to associate with SSID 'Linksys47057_5GHz' Oct 13 17:42:09 volumio-pi wireless.js[621]: trying... Oct 13 17:42:09 volumio-pi sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:09 volumio-pi sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:09 volumio-pi sudo[869]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:10 volumio-pi wireless.js[621]: trying... Oct 13 17:42:10 volumio-pi sudo[872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:10 volumio-pi sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:10 volumio-pi sudo[872]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:11 volumio-pi wireless.js[621]: trying... Oct 13 17:42:11 volumio-pi sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:11 volumio-pi sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:11 volumio-pi sudo[875]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:12 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:42:12] [info] asio async_connect error: system:111 (Connection refused) Oct 13 17:42:12 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:42:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 13 17:42:12 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:42:12] [error] handle_connect error: Underlying Transport Error Oct 13 17:42:12 volumio-pi dhcpcd[861]: wlan0: carrier acquired Oct 13 17:42:12 volumio-pi dhcpcd[861]: wlan0: connected to Access Point `Linksys47057_5GHz' Oct 13 17:42:12 volumio-pi wpa_supplicant[849]: wlan0: Associated with 60:38:e0:32:6f:9e Oct 13 17:42:12 volumio-pi wpa_supplicant[849]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:38:e0:32:6f:9e completed [id=0 id_str=] Oct 13 17:42:12 volumio-pi wpa_supplicant[849]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 13 17:42:12 volumio-pi wpa_supplicant[849]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Oct 13 17:42:12 volumio-pi dhcpcd[861]: DUID 00:01:00:01:2b:b8:6d:99:b8:27:eb:f0:15:9c Oct 13 17:42:12 volumio-pi dhcpcd[861]: wlan0: IAID eb:a5:40:c9 Oct 13 17:42:12 volumio-pi dhcpcd[861]: wlan0: adding address fe80::ba27:ebff:fea5:40c9 Oct 13 17:42:12 volumio-pi dhcpcd[861]: ipv6_addaddr1: Permission denied Oct 13 17:42:12 volumio-pi dhcpcd[861]: wlan0: carrier lost Oct 13 17:42:12 volumio-pi wireless.js[621]: trying... Oct 13 17:42:12 volumio-pi dhcpcd[861]: wlan0: carrier acquired Oct 13 17:42:12 volumio-pi dhcpcd[861]: wlan0: IAID eb:a5:40:c9 Oct 13 17:42:12 volumio-pi sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:12 volumio-pi sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:12 volumio-pi sudo[889]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:12 volumio-pi dhcpcd[861]: wlan0: soliciting an IPv6 router Oct 13 17:42:13 volumio-pi dhcpcd[861]: wlan0: rebinding lease of 192.168.1.128 Oct 13 17:42:13 volumio-pi dhcpcd[861]: wlan0: probing address 192.168.1.128/24 Oct 13 17:42:13 volumio-pi wireless.js[621]: trying... Oct 13 17:42:13 volumio-pi sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:13 volumio-pi sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:13 volumio-pi sudo[893]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:14 volumio-pi wireless.js[621]: trying... Oct 13 17:42:14 volumio-pi sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:14 volumio-pi sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:14 volumio-pi sudo[896]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:15 volumio-pi wireless.js[621]: trying... Oct 13 17:42:15 volumio-pi sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:15 volumio-pi sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:15 volumio-pi sudo[899]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:16 volumio-pi wireless.js[621]: trying... Oct 13 17:42:16 volumio-pi sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:16 volumio-pi sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:16 volumio-pi sudo[902]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:17 volumio-pi wireless.js[621]: trying... Oct 13 17:42:17 volumio-pi sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:17 volumio-pi sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:17 volumio-pi sudo[905]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:18 volumio-pi wireless.js[621]: trying... Oct 13 17:42:18 volumio-pi sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:18 volumio-pi sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:18 volumio-pi sudo[908]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:19 volumio-pi dhcpcd[861]: wlan0: leased 192.168.1.128 for 86400 seconds Oct 13 17:42:19 volumio-pi avahi-daemon[615]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.128. Oct 13 17:42:19 volumio-pi dhcpcd[861]: wlan0: adding route to 192.168.1.0/24 Oct 13 17:42:19 volumio-pi avahi-daemon[615]: New relevant interface wlan0.IPv4 for mDNS. Oct 13 17:42:19 volumio-pi avahi-daemon[615]: Registering new address record for 192.168.1.128 on wlan0.IPv4. Oct 13 17:42:19 volumio-pi dhcpcd[861]: wlan0: adding default route via 192.168.1.1 Oct 13 17:42:19 volumio-pi wireless.js[621]: trying... Oct 13 17:42:19 volumio-pi sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:19 volumio-pi sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:19 volumio-pi sudo[932]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:19 volumio-pi wireless.js[621]: Connected to: ----Linksys47057_5GHz Oct 13 17:42:19 volumio-pi wireless.js[621]: ---- Oct 13 17:42:19 volumio-pi sudo[935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 17:42:19 volumio-pi sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:19 volumio-pi sudo[935]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:19 volumio-pi wireless.js[621]: ... joined AP, wlan0 IPv4 is 192.168.1.128, ipV6 is undefined Oct 13 17:42:19 volumio-pi wireless.js[621]: It's done! AP Oct 13 17:42:19 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 13 17:42:19 volumio-pi systemd[1]: Started Wireless Services. Oct 13 17:42:19 volumio-pi systemd[1]: Started Volumio Backend Module. Oct 13 17:42:19 volumio-pi systemd[1]: Started Volumio Cpu Tweaker. Oct 13 17:42:19 volumio-pi volumio-cpu-tweak[941]: Setting RT Priority for mpd Oct 13 17:42:19 volumio-pi volumio-cpu-tweak[941]: pid 35's current scheduling policy: SCHED_OTHER Oct 13 17:42:19 volumio-pi volumio-cpu-tweak[941]: pid 35's current scheduling priority: 0 Oct 13 17:42:19 volumio-pi volumio-cpu-tweak[941]: Setting MPD Affinity Oct 13 17:42:19 volumio-pi volumio-cpu-tweak[941]: pid 3's current affinity mask: f Oct 13 17:42:19 volumio-pi volumio-cpu-tweak[941]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 13 17:42:19 volumio-pi volumio-cpu-tweak[941]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 13 17:42:19 volumio-pi systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 13 17:42:20 volumio-pi ntpd[741]: Listen normally on 3 wlan0 192.168.1.128:123 Oct 13 17:42:20 volumio-pi ntpd[741]: new interface(s) found: waking up resolver Oct 13 17:42:21 volumio-pi nmbd[702]: [2024/10/13 17:42:21.457189, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 13 17:42:21 volumio-pi systemd[1]: Started Samba NMB Daemon. Oct 13 17:42:21 volumio-pi nmbd[702]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 13 17:42:21 volumio-pi systemd[1]: Starting Samba Winbind Daemon... Oct 13 17:42:21 volumio-pi winbindd[957]: [2024/10/13 17:42:21.869638, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 13 17:42:21 volumio-pi winbindd[957]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 13 17:42:21 volumio-pi systemd[1]: Started Samba Winbind Daemon. Oct 13 17:42:21 volumio-pi winbindd[957]: [2024/10/13 17:42:21.969510, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 13 17:42:21 volumio-pi winbindd[957]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 13 17:42:22 volumio-pi volumio-remote-updater[590]: [2024-10-13 17:42:22] [connect] Successful connection Oct 13 17:42:22 volumio-pi systemd[1]: Starting Samba SMB Daemon... Oct 13 17:42:22 volumio-pi volumio[940]: info: ------------------------------------------- Oct 13 17:42:22 volumio-pi volumio[940]: info: ----- Volumio3 ---- Oct 13 17:42:22 volumio-pi volumio[940]: info: ------------------------------------------- Oct 13 17:42:22 volumio-pi volumio[940]: info: ----- System startup ---- Oct 13 17:42:22 volumio-pi volumio[940]: info: ------------------------------------------- Oct 13 17:42:22 volumio-pi winbindd[957]: [2024/10/13 17:42:22.611396, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Oct 13 17:42:22 volumio-pi winbindd[957]: res_names->count = 3, expected 4 Oct 13 17:42:22 volumio-pi smbd[970]: [2024/10/13 17:42:22.639130, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 13 17:42:22 volumio-pi smbd[970]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 13 17:42:22 volumio-pi systemd[1]: Started Samba SMB Daemon. Oct 13 17:42:22 volumio-pi systemd[1]: Reached target Multi-User System. Oct 13 17:42:22 volumio-pi systemd[1]: Reached target Graphical Interface. Oct 13 17:42:22 volumio-pi systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 13 17:42:22 volumio-pi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 13 17:42:22 volumio-pi systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 13 17:42:22 volumio-pi systemd[1]: Startup finished in 25.357s (kernel) + 33.066s (userspace) = 58.423s. Oct 13 17:42:23 volumio-pi volumio[940]: info: MYVOLUMIO Environment detected Oct 13 17:42:23 volumio-pi volumio[940]: info: Plugin folders cleanup Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning into folder /volumio/app/plugins/ Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning category audio_interface Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning category miscellanea Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning category music_service Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning category plugins.json Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning category system_controller Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning category user_interface Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning into folder /data/plugins/ Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning category audio_interface Oct 13 17:42:23 volumio-pi volumio[940]: info: Scanning category music_service Oct 13 17:42:23 volumio-pi volumio[940]: info: Plugin folders cleanup completed Oct 13 17:42:23 volumio-pi volumio[940]: info: ------------------------------------------- Oct 13 17:42:23 volumio-pi volumio[940]: info: ----- Core plugins startup ---- Oct 13 17:42:23 volumio-pi volumio[940]: info: ------------------------------------------- Oct 13 17:42:23 volumio-pi volumio[940]: info: Loading plugins from folder /volumio/app/plugins/ Oct 13 17:42:23 volumio-pi volumio[940]: info: Adding plugin upnp to MyMusic Plugins Oct 13 17:42:23 volumio-pi volumio[940]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 13 17:42:23 volumio-pi volumio[940]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 13 17:42:23 volumio-pi volumio[940]: info: Loading plugins from folder /data/plugins/ Oct 13 17:42:23 volumio-pi volumio[940]: info: Loading plugin "system"... Oct 13 17:42:23 volumio-pi volumio[940]: info: Loading plugin "appearance"... Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "network"... Oct 13 17:42:25 volumio-pi volumio[940]: info: Refreshing Cached IP Addresses Oct 13 17:42:25 volumio-pi sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 17:42:25 volumio-pi sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "services"... Oct 13 17:42:25 volumio-pi sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 17:42:25 volumio-pi sudo[985]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:25 volumio-pi sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "alsa_controller"... Oct 13 17:42:25 volumio-pi sudo[987]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:25 volumio-pi sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 13 17:42:25 volumio-pi sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:25 volumio-pi volumio[940]: warn: Unable to locate the audio output device FiiO K3. Please configure a valid output device. Oct 13 17:42:25 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "wizard"... Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "networkfs"... Oct 13 17:42:25 volumio-pi volumio[940]: info: Starting Udev Watcher for removable devices Oct 13 17:42:25 volumio-pi sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.115/05_Music /mnt/NAS/05_Music Oct 13 17:42:25 volumio-pi sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:25 volumio-pi volumio[940]: info: Ignoring mount for partition: boot Oct 13 17:42:25 volumio-pi volumio[940]: info: Ignoring mount for partition: volumio Oct 13 17:42:25 volumio-pi volumio[940]: info: Ignoring mount for partition: volumio_data Oct 13 17:42:25 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "volumio_command_line_client"... Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "upnp"... Oct 13 17:42:25 volumio-pi volumio[940]: info: [1728808945543] Starting Upmpd Daemon Oct 13 17:42:25 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "my_music"... Oct 13 17:42:25 volumio-pi volumio[940]: info: Loading plugin "mpd"... Oct 13 17:42:25 volumio-pi kernel: Key type cifs.spnego registered Oct 13 17:42:25 volumio-pi kernel: Key type cifs.idmap registered Oct 13 17:42:25 volumio-pi 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. Oct 13 17:42:25 volumio-pi kernel: CIFS: Attempting to mount \\192.168.1.115\05_Music Oct 13 17:42:25 volumio-pi sudo[996]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "upnp_browser"... Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "alarm-clock"... Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "airplay_emulation"... Oct 13 17:42:26 volumio-pi volumio[940]: info: Starting Shairport Sync Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "last_100"... Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "webradio"... Oct 13 17:42:26 volumio-pi systemd[1]: systemd-fsckd.service: Succeeded. Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "i2s_dacs"... Oct 13 17:42:26 volumio-pi volumio[940]: info: I2S DAC not set, start Auto-detection Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "volumiodiscovery"... Oct 13 17:42:26 volumio-pi volumio[940]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 17:42:26 volumio-pi volumio[940]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 17:42:26 volumio-pi volumio[940]: *** WARNING *** For more information see Oct 13 17:42:26 volumio-pi volumio[940]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 17:42:26 volumio-pi node[940]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 17:42:26 volumio-pi volumio[940]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 17:42:26 volumio-pi volumio[940]: *** WARNING *** For more information see Oct 13 17:42:26 volumio-pi node[940]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 17:42:26 volumio-pi node[940]: *** WARNING *** For more information see Oct 13 17:42:26 volumio-pi node[940]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 17:42:26 volumio-pi node[940]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 17:42:26 volumio-pi node[940]: *** WARNING *** For more information see Oct 13 17:42:26 volumio-pi volumio[940]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 17:42:26 volumio-pi volumio[940]: info: Discovery: Started advertising with name: Volumio Pi Oct 13 17:42:26 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "outputs"... Oct 13 17:42:26 volumio-pi volumio[940]: info: Loading plugin "albumart"... Oct 13 17:42:27 volumio-pi volumio[940]: info: Plugin example_plugin is not enabled Oct 13 17:42:27 volumio-pi volumio[940]: info: Loading plugin "inputs"... Oct 13 17:42:27 volumio-pi volumio[940]: info: Loading plugin "updater_comm"... Oct 13 17:42:27 volumio-pi volumio[940]: info: Plugin mpdemulation is not enabled Oct 13 17:42:27 volumio-pi volumio[940]: info: Loading plugin "rest_api"... Oct 13 17:42:27 volumio-pi volumio[940]: info: Loading plugin "websocket"... Oct 13 17:42:27 volumio-pi volumio[940]: info: Starting Socket.io Server version 2.3.0 Oct 13 17:42:27 volumio-pi volumio[940]: info: Loading plugin "fusiondsp"... Oct 13 17:42:27 volumio-pi volumio[940]: Forking 3 albumart workers Oct 13 17:42:27 volumio-pi sh[497]: timed out Oct 13 17:42:27 volumio-pi dhcpcd[525]: timed out Oct 13 17:42:27 volumio-pi sh[497]: dhcpcd exited Oct 13 17:42:27 volumio-pi dhcpcd[525]: dhcpcd exited Oct 13 17:42:27 volumio-pi sh[497]: ifup: failed to bring up eth0 Oct 13 17:42:27 volumio-pi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 13 17:42:27 volumio-pi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 13 17:42:27 volumio-pi volumio[940]: info: Applying required configuration parameters for plugin fusiondsp Oct 13 17:42:28 volumio-pi volumio[940]: info: Loading plugin "80s80s"... Oct 13 17:42:28 volumio-pi volumio[940]: Starting albumart workers Oct 13 17:42:28 volumio-pi volumio[940]: Starting albumart workers Oct 13 17:42:28 volumio-pi volumio[940]: Starting albumart workers Oct 13 17:42:28 volumio-pi volumio[940]: info: Applying required configuration parameters for plugin 80s80s Oct 13 17:42:28 volumio-pi volumio[940]: info: [1728808948661] [80s80s] API delay: 30 Oct 13 17:42:28 volumio-pi volumio[940]: info: Loading plugin "radio_paradise"... Oct 13 17:42:29 volumio-pi sudo[990]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:29 volumio-pi volumio[940]: info: Applying required configuration parameters for plugin radio_paradise Oct 13 17:42:29 volumio-pi volumio[940]: info: [1728808949528] [RadioParadise] API delay: 5 Oct 13 17:42:29 volumio-pi volumio[940]: info: Loading i18n strings for locale ko Oct 13 17:42:29 volumio-pi volumio[940]: Updating browse sources language Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::initPlayerControls Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: Express server listening on port 3000 Oct 13 17:42:29 volumio-pi volumio[940]: [Metrics] WebUI: 8s 577.67ms Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreStateMachine::resetVolumioState Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreStateMachine::getcurrentVolume Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 17:42:29 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:29 volumio-pi volumio[940]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 13 17:42:30 volumio-pi volumio[940]: info: VolumeController:: Volume=undefined Mute =false Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreStateMachine::pushState Oct 13 17:42:30 volumio-pi volumio[940]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioPushState Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreStateMachine::updateTrackBlock Oct 13 17:42:30 volumio-pi volumio[940]: info: CorePlayQueue::getTrackBlock Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 17:42:30 volumio-pi volumio[940]: info: Reloading queue from file Oct 13 17:42:30 volumio-pi volumio[940]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreStateMachine::setRepeat true single undefined Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreStateMachine::pushState Oct 13 17:42:30 volumio-pi volumio[940]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioPushState Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreStateMachine::setRandom false Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreStateMachine::pushState Oct 13 17:42:30 volumio-pi volumio[940]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioPushState Oct 13 17:42:30 volumio-pi volumio[940]: info: Setting Device type: Raspberry PI Oct 13 17:42:30 volumio-pi volumio[940]: info: VolumeController:: Volume=undefined Mute =false Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreStateMachine::pushState Oct 13 17:42:30 volumio-pi volumio[940]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioPushState Oct 13 17:42:30 volumio-pi volumio[940]: info: Completed loading Core Plugins Oct 13 17:42:30 volumio-pi volumio[940]: info: Preparing to generate the ALSA configuration file Oct 13 17:42:30 volumio-pi volumio[940]: info: Discovery: adding 1bdf6a08-5168-44e1-8ec9-05aa93dafdae Oct 13 17:42:30 volumio-pi volumio[940]: info: Discovery: Found device Volumio Pi Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioGetState Oct 13 17:42:30 volumio-pi volumio[940]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:30 volumio-pi volumio[940]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 13 17:42:30 volumio-pi volumio[940]: info: Reading ALSA contributions from plugins. Oct 13 17:42:30 volumio-pi volumio[940]: info: Asound.conf file unchanged, so no further update is needed Oct 13 17:42:30 volumio-pi volumio[940]: info: Output device has changed, restarting MPD Oct 13 17:42:30 volumio-pi volumio[940]: info: Output device has changed, restarting Shairport Sync Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:30 volumio-pi sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:30 volumio-pi sudo[1089]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:30 volumio-pi sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 17:42:30 volumio-pi sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:30 volumio-pi volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 17:42:30 volumio-pi volumio[940]: info: ___________ START PLUGINS ___________ Oct 13 17:42:30 volumio-pi volumio[940]: info: ControllerMpd::onStart: Initializing MPD Oct 13 17:42:30 volumio-pi volumio[940]: info: Creating MPD Configuration file Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:30 volumio-pi sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 17:42:30 volumio-pi sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:30 volumio-pi volumio[940]: info: [1728808950306] CoreMusicLibrary::Adding element 미디어 서버 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:30 volumio-pi sudo[1099]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:30 volumio-pi sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 17:42:30 volumio-pi sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:30 volumio-pi systemd[1]: Listening on mpd.socket. Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:30 volumio-pi systemd[1]: Starting Music Player Daemon... Oct 13 17:42:30 volumio-pi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 13 17:42:30 volumio-pi systemd[1]: mpd.service: Succeeded. Oct 13 17:42:30 volumio-pi systemd[1]: Stopped Music Player Daemon. Oct 13 17:42:30 volumio-pi systemd[1]: Starting Music Player Daemon... Oct 13 17:42:30 volumio-pi volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:30 volumio-pi volumio[940]: info: [1728808950430] CoreMusicLibrary::Adding element Last_100 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:30 volumio-pi volumio[940]: info: [1728808950433] CoreMusicLibrary::Adding element Webradio Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:42:30 volumio-pi volumio[940]: info: Initializing BBC Radios Oct 13 17:42:30 volumio-pi sudo[1108]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 17:42:30 volumio-pi sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:30 volumio-pi sudo[1108]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 17:42:30 volumio-pi sudo[1108]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: Loading i18n strings for locale ko Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: FusionDsp - mixtype--------------------- undefined Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:30 volumio-pi volumio[940]: info: [1728808950528] CoreMusicLibrary::Adding element 80s80s Radio Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:30 volumio-pi volumio[940]: Cannot find translation for source 80s80s Radio Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:30 volumio-pi volumio[940]: info: [1728808950537] CoreMusicLibrary::Adding element Radio Paradise Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:30 volumio-pi volumio[940]: Cannot find translation for source 80s80s Radio Oct 13 17:42:30 volumio-pi volumio[940]: Cannot find translation for source Radio Paradise Oct 13 17:42:30 volumio-pi volumio[940]: info: Volumio Calling Home Oct 13 17:42:30 volumio-pi volumio[940]: info: Preparing to generate the ALSA configuration file Oct 13 17:42:30 volumio-pi volumio[940]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 13 17:42:30 volumio-pi volumio[940]: info: Reading ALSA contributions from plugins. Oct 13 17:42:30 volumio-pi volumio[940]: info: MPD Permissions set Oct 13 17:42:30 volumio-pi volumio[940]: info: MPD Permissions set Oct 13 17:42:30 volumio-pi volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 17:42:30 volumio-pi volumio[940]: info: Discovery: Getting this device information Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::volumioGetState Oct 13 17:42:30 volumio-pi volumio[940]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:30 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:42:31 volumio-pi volumio[940]: info: Starting Shairport Sync Oct 13 17:42:31 volumio-pi volumio[940]: info: Starting Shairport Sync Oct 13 17:42:31 volumio-pi volumio[940]: info: Starting Shairport Sync Oct 13 17:42:31 volumio-pi sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 17:42:31 volumio-pi sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:31 volumio-pi sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 17:42:31 volumio-pi sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:31 volumio-pi sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::volumioGetState Oct 13 17:42:31 volumio-pi volumio[940]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:31 volumio-pi sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:31 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 17:42:31 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Oct 13 17:42:31 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:31 volumio-pi volumio[940]: verbose: New Socket.io Connection to 192.168.1.128:3000 from 192.168.1.121 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::volumioGetState Oct 13 17:42:31 volumio-pi volumio[940]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:31 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:31 volumio-pi sudo[1136]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:31 volumio-pi sudo[1138]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:31 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 17:42:31 volumio-pi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 13 17:42:31 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Oct 13 17:42:31 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:31 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:31 volumio-pi sudo[1141]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:31 volumio-pi volumio[940]: info: Asound.conf file unchanged, so no further update is needed Oct 13 17:42:31 volumio-pi volumio[940]: info: Output device has changed, restarting MPD Oct 13 17:42:31 volumio-pi systemd[1]: systemd-hostnamed.service: Succeeded. Oct 13 17:42:31 volumio-pi volumio[940]: info: Output device has changed, restarting Shairport Sync Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:31 volumio-pi sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 17:42:31 volumio-pi sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:31 volumio-pi sudo[1148]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:31 volumio-pi sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 17:42:31 volumio-pi sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:31 volumio-pi volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 17:42:31 volumio-pi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 17:42:31 volumio-pi systemd[1]: mpd.service: Succeeded. Oct 13 17:42:31 volumio-pi systemd[1]: Stopped Music Player Daemon. Oct 13 17:42:31 volumio-pi volumio[940]: info: Shairport-Sync Started Oct 13 17:42:31 volumio-pi volumio[940]: Error adding Membership: Error: addMembership EINVAL Oct 13 17:42:31 volumio-pi volumio[940]: info: Shairport-Sync Started Oct 13 17:42:31 volumio-pi volumio[940]: info: Shairport-Sync Started Oct 13 17:42:31 volumio-pi volumio[940]: info: Volumio called home Oct 13 17:42:31 volumio-pi volumio[940]: info: MPD Permissions set Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi systemd[1]: Starting Music Player Daemon... Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:31 volumio-pi volumio[940]: info: Starting Shairport Sync Oct 13 17:42:31 volumio-pi sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 17:42:31 volumio-pi sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:31 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 17:42:31 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Oct 13 17:42:31 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:31 volumio-pi sudo[1160]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 17:42:31 volumio-pi sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:31 volumio-pi sudo[1160]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 17:42:31 volumio-pi sudo[1160]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:31 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:31 volumio-pi sudo[1167]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:31 volumio-pi volumio[940]: info: Shairport-Sync Started Oct 13 17:42:32 volumio-pi volumio[940]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 13 17:42:32 volumio-pi volumio[940]: info: camilladsp service started and running in background, instance 1 Oct 13 17:42:32 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[940]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 13 17:42:32 volumio-pi volumio[940]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 13 17:42:32 volumio-pi volumio[940]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 13 17:42:32 volumio-pi volumio[940]: info: FusionDsp loaded Oct 13 17:42:32 volumio-pi volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:42:32 volumio-pi sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 13 17:42:32 volumio-pi sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:32 volumio-pi volumio[940]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 13 17:42:32 volumio-pi volumio[940]: info: Adding Signal Path Element [object Object] Oct 13 17:42:32 volumio-pi volumio[940]: info: Adding fusiondspeq DSP Signal Path Element Oct 13 17:42:32 volumio-pi volumio[940]: info: FusionDsp - ---- installed callbackRead Oct 13 17:42:32 volumio-pi volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:42:32 volumio-pi volumio[940]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 13 17:42:32 volumio-pi volumio[940]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 13 17:42:32 volumio-pi volumio[940]: at onErrorNT (internal/child_process.js:465:16) Oct 13 17:42:32 volumio-pi volumio[940]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 13 17:42:32 volumio-pi volumio[940]: errno: -2, Oct 13 17:42:32 volumio-pi volumio[940]: code: 'ENOENT', Oct 13 17:42:32 volumio-pi volumio[940]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 13 17:42:32 volumio-pi volumio[940]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 13 17:42:32 volumio-pi volumio[940]: spawnargs: [ Oct 13 17:42:32 volumio-pi volumio[940]: '-p', Oct 13 17:42:32 volumio-pi volumio[940]: 9876, Oct 13 17:42:32 volumio-pi volumio[940]: '-o', Oct 13 17:42:32 volumio-pi volumio[940]: '/tmp/camilladsp.log', Oct 13 17:42:32 volumio-pi volumio[940]: '-l', Oct 13 17:42:32 volumio-pi volumio[940]: 'warn', Oct 13 17:42:32 volumio-pi volumio[940]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 13 17:42:32 volumio-pi volumio[940]: ] Oct 13 17:42:32 volumio-pi volumio[940]: } Oct 13 17:42:32 volumio-pi volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:42:32 volumio-pi systemd[1]: Started FusionDsp Daemon. Oct 13 17:42:32 volumio-pi sudo[1186]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:33 volumio-pi sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 17:41 Oct 13 17:42:33 volumio-pi sudo[1198]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"