-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-05-14 16:41:00 CEST. -- May 14 16:40:10 volumiosabre fake-hwclock[188]: Tue May 14 14:40:10 UTC 2024 May 14 16:40:10 volumiosabre systemd[1]: Starting Create System Users... May 14 16:40:10 volumiosabre systemd[1]: Starting Load/Save Random Seed... May 14 16:40:10 volumiosabre systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 14 16:40:10 volumiosabre systemd[1]: Started Restore / save the current clock. May 14 16:40:10 volumiosabre systemd-modules-load[203]: Inserted module 'i2c_dev' May 14 16:40:10 volumiosabre kernel: i2c_dev: i2c /dev entries driver May 14 16:40:10 volumiosabre systemd[1]: Started Load Kernel Modules. May 14 16:40:10 volumiosabre systemd[1]: Starting Apply Kernel Variables... May 14 16:40:10 volumiosabre systemd[1]: Mounting Kernel Configuration File System... May 14 16:40:10 volumiosabre systemd[1]: Condition check resulted in FUSE Control File System being skipped. May 14 16:40:10 volumiosabre systemd[1]: Started Load/Save Random Seed. May 14 16:40:10 volumiosabre systemd[1]: Mounted Kernel Configuration File System. May 14 16:40:10 volumiosabre systemd[1]: Started Create System Users. May 14 16:40:10 volumiosabre systemd[1]: Starting Create Static Device Nodes in /dev... May 14 16:40:10 volumiosabre systemd[1]: dynamicswap.service: Succeeded. May 14 16:40:10 volumiosabre systemd[1]: Started Apply Kernel Variables. May 14 16:40:10 volumiosabre systemd[1]: Started udev Coldplug all Devices. May 14 16:40:10 volumiosabre systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 14 16:40:10 volumiosabre systemd[1]: Started Create Static Device Nodes in /dev. May 14 16:40:10 volumiosabre systemd[1]: Reached target Local File Systems (Pre). May 14 16:40:10 volumiosabre systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 14 16:40:10 volumiosabre systemd[1]: Mounting /tmp... May 14 16:40:10 volumiosabre systemd[1]: Mounting /var/spool/cups... May 14 16:40:10 volumiosabre systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 14 16:40:10 volumiosabre systemd[1]: Mounting /var/log... May 14 16:40:10 volumiosabre systemd[1]: Starting udev Kernel Device Manager... May 14 16:40:10 volumiosabre systemd[1]: Mounted /tmp. May 14 16:40:10 volumiosabre systemd[1]: Mounted /var/spool/cups. May 14 16:40:10 volumiosabre systemd[1]: Mounted /var/log. May 14 16:40:10 volumiosabre systemd[1]: Starting Flush Journal to Persistent Storage... May 14 16:40:10 volumiosabre systemd[1]: Mounting /var/spool/cups/tmp... May 14 16:40:10 volumiosabre systemd[1]: Mounted /var/spool/cups/tmp. May 14 16:40:10 volumiosabre systemd-udevd[240]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 14 16:40:10 volumiosabre systemd-journald[192]: Runtime journal (/run/log/journal/c1d531165394c79721acb5a765d65e88) is 7.5M, max 30.0M, 22.5M free. May 14 16:40:10 volumiosabre systemd[1]: Started Flush Journal to Persistent Storage. May 14 16:40:10 volumiosabre systemd[1]: Started udev Kernel Device Manager. May 14 16:40:10 volumiosabre systemd[1]: Starting Show Plymouth Boot Screen... May 14 16:40:10 volumiosabre systemd[1]: Received SIGRTMIN+20 from PID 257 (plymouthd). May 14 16:40:10 volumiosabre kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 14 16:40:10 volumiosabre kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 14 16:40:10 volumiosabre kernel: [vc_sm_connected_init]: start May 14 16:40:10 volumiosabre kernel: mc: Linux media interface: v0.10 May 14 16:40:10 volumiosabre kernel: [vc_sm_connected_init]: installed successfully May 14 16:40:10 volumiosabre systemd[1]: Started Show Plymouth Boot Screen. May 14 16:40:10 volumiosabre systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 14 16:40:10 volumiosabre systemd[1]: Reached target Paths. May 14 16:40:10 volumiosabre systemd[1]: Reached target Local Encrypted Volumes. May 14 16:40:10 volumiosabre systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 14 16:40:11 volumiosabre kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 May 14 16:40:11 volumiosabre kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem May 14 16:40:11 volumiosabre kernel: videodev: Linux video capture interface: v2.00 May 14 16:40:11 volumiosabre kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 14 16:40:11 volumiosabre kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 14 16:40:11 volumiosabre kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 14 16:40:11 volumiosabre kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 14 16:40:11 volumiosabre kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 14 16:40:11 volumiosabre kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp May 14 16:40:11 volumiosabre kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 14 16:40:11 volumiosabre kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 14 16:40:11 volumiosabre kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored May 14 16:40:11 volumiosabre kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 14 16:40:11 volumiosabre kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 May 14 16:40:11 volumiosabre kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image May 14 16:40:11 volumiosabre kernel: Bluetooth: Core ver 2.22 May 14 16:40:11 volumiosabre kernel: NET: Registered PF_BLUETOOTH protocol family May 14 16:40:11 volumiosabre kernel: Bluetooth: HCI device and connection manager initialized May 14 16:40:11 volumiosabre kernel: Bluetooth: HCI socket layer initialized May 14 16:40:11 volumiosabre kernel: Bluetooth: L2CAP socket layer initialized May 14 16:40:11 volumiosabre kernel: Bluetooth: SCO socket layer initialized May 14 16:40:11 volumiosabre kernel: Bluetooth: HCI UART driver ver 2.3 May 14 16:40:11 volumiosabre kernel: Bluetooth: HCI UART protocol H4 registered May 14 16:40:11 volumiosabre kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered May 14 16:40:11 volumiosabre kernel: Bluetooth: HCI UART protocol Broadcom registered May 14 16:40:11 volumiosabre kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator May 14 16:40:11 volumiosabre kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator May 14 16:40:11 volumiosabre systemd[1]: Found device /dev/mmcblk0p1. May 14 16:40:11 volumiosabre kernel: snd-rpi-i-sabre-q2m soc:sound: snd_soc_register_card() failed: -517 May 14 16:40:11 volumiosabre kernel: snd-rpi-i-sabre-q2m soc:sound: snd_soc_register_card() failed: -517 May 14 16:40:11 volumiosabre kernel: uart-pl011 3f201000.serial: no DMA platform data May 14 16:40:12 volumiosabre systemd-udevd[245]: Using default interface naming scheme 'v240'. May 14 16:40:12 volumiosabre kernel: snd-rpi-i-sabre-q2m soc:sound: snd_soc_register_card() failed: -517 May 14 16:40:12 volumiosabre systemd-udevd[245]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 14 16:40:12 volumiosabre kernel: Bluetooth: hci0: BCM: chip id 94 May 14 16:40:12 volumiosabre kernel: Bluetooth: hci0: BCM: features 0x2e May 14 16:40:12 volumiosabre kernel: Bluetooth: hci0: BCM43430A1 May 14 16:40:12 volumiosabre kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 May 14 16:40:12 volumiosabre kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch May 14 16:40:12 volumiosabre systemd-udevd[244]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 14 16:40:12 volumiosabre kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 14 16:40:12 volumiosabre kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' May 14 16:40:12 volumiosabre kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' May 14 16:40:12 volumiosabre kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 14 16:40:12 volumiosabre kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 May 14 16:40:12 volumiosabre kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 May 14 16:40:12 volumiosabre kernel: usbcore: registered new interface driver brcmfmac May 14 16:40:12 volumiosabre kernel: snd-rpi-i-sabre-q2m soc:sound: snd_soc_register_card() failed: -517 May 14 16:40:13 volumiosabre kernel: snd-rpi-i-sabre-q2m soc:sound: snd_soc_register_card() failed: -517 May 14 16:40:13 volumiosabre kernel: Bluetooth: hci0: BCM: features 0x2e May 14 16:40:13 volumiosabre kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 May 14 16:40:13 volumiosabre kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 May 14 16:40:13 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000001] -121 May 14 16:40:13 volumiosabre kernel: snd-rpi-i-sabre-q2m soc:sound: Audiophonics Device ID : FFFFFF87 May 14 16:40:13 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000002] -121 May 14 16:40:13 volumiosabre kernel: snd-rpi-i-sabre-q2m soc:sound: Audiophonics API revision : FFFFFF87 May 14 16:40:13 volumiosabre kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 May 14 16:40:13 volumiosabre systemd-udevd[249]: Using default interface naming scheme 'v240'. May 14 16:40:13 volumiosabre systemd-udevd[249]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 14 16:40:13 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -5 May 14 16:40:13 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:13 volumiosabre systemd-udevd[248]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 17. May 14 16:40:13 volumiosabre systemd-udevd[247]: Process '/sbin/crda' failed with exit code 255. May 14 16:40:14 volumiosabre systemd[1]: Started Helper to synchronize boot up for ifupdown. May 14 16:40:14 volumiosabre systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. May 14 16:40:14 volumiosabre systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. May 14 16:40:14 volumiosabre systemd[1]: Created slice system-bthelper.slice. May 14 16:40:14 volumiosabre systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 14 16:40:14 volumiosabre systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 14 16:40:14 volumiosabre systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 14 16:40:14 volumiosabre systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 14 16:40:14 volumiosabre systemd[1]: Condition check resulted in FUSE Control File System being skipped. May 14 16:40:14 volumiosabre systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 14 16:40:14 volumiosabre systemd[1]: Starting File System Check on /dev/mmcblk0p1... May 14 16:40:14 volumiosabre systemd[1]: Starting Load/Save RF Kill Switch Status... May 14 16:40:14 volumiosabre systemd[1]: Started File System Check Daemon to report status. May 14 16:40:14 volumiosabre systemd[1]: Started Load/Save RF Kill Switch Status. May 14 16:40:14 volumiosabre systemd-fsck[414]: fsck.fat 4.1 (2017-01-24) May 14 16:40:14 volumiosabre systemd-fsck[414]: /dev/mmcblk0p1: 367 files, 33349/46774 clusters May 14 16:40:14 volumiosabre systemd[1]: Started File System Check on /dev/mmcblk0p1. May 14 16:40:14 volumiosabre systemd[1]: Mounting /boot... May 14 16:40:14 volumiosabre systemd[1]: Mounted /boot. May 14 16:40:14 volumiosabre systemd[1]: Reached target Local File Systems. May 14 16:40:14 volumiosabre systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 14 16:40:14 volumiosabre systemd[1]: Started ifup for wlan0. May 14 16:40:14 volumiosabre systemd[1]: Starting Create Volatile Files and Directories... May 14 16:40:14 volumiosabre systemd[1]: Starting Raise network interfaces... May 14 16:40:14 volumiosabre systemd[1]: Started ifup for eth0. May 14 16:40:14 volumiosabre systemd[1]: Starting Preprocess NFS configuration... May 14 16:40:15 volumiosabre systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 14 16:40:15 volumiosabre systemd[1]: Received SIGRTMIN+20 from PID 257 (plymouthd). May 14 16:40:15 volumiosabre systemd[1]: plymouth-read-write.service: Succeeded. May 14 16:40:15 volumiosabre systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 14 16:40:15 volumiosabre systemd[1]: nfs-config.service: Succeeded. May 14 16:40:15 volumiosabre systemd[1]: Started Preprocess NFS configuration. May 14 16:40:15 volumiosabre systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 14 16:40:15 volumiosabre systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 14 16:40:15 volumiosabre systemd[1]: Reached target NFS client services. May 14 16:40:15 volumiosabre systemd[1]: Started Create Volatile Files and Directories. May 14 16:40:15 volumiosabre systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 14 16:40:15 volumiosabre systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 14 16:40:15 volumiosabre systemd[1]: Starting RPC bind portmap service... May 14 16:40:15 volumiosabre systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 14 16:40:15 volumiosabre kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 14 16:40:15 volumiosabre systemd[1]: Started Update UTMP about System Boot/Shutdown. May 14 16:40:15 volumiosabre systemd[1]: Reached target System Initialization. May 14 16:40:15 volumiosabre kernel: 8021q: 802.1Q VLAN Support v1.8 May 14 16:40:15 volumiosabre systemd[1]: Started Daily apt download activities. May 14 16:40:15 volumiosabre systemd[1]: Listening on D-Bus System Message Bus Socket. May 14 16:40:15 volumiosabre systemd[1]: Started Daily apt upgrade and clean activities. May 14 16:40:15 volumiosabre systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 14 16:40:15 volumiosabre systemd[1]: Started Volumio Splash. May 14 16:40:15 volumiosabre systemd[1]: Started Daily Cleanup of Temporary Directories. May 14 16:40:15 volumiosabre systemd[1]: Reached target Timers. May 14 16:40:15 volumiosabre systemd[1]: Listening on triggerhappy.socket. May 14 16:40:15 volumiosabre systemd[1]: Reached target Sockets. May 14 16:40:15 volumiosabre systemd[1]: Reached target Basic System. May 14 16:40:15 volumiosabre systemd[508]: volumiosplash.service: Failed to execute command: No such file or directory May 14 16:40:15 volumiosabre systemd[508]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory May 14 16:40:15 volumiosabre systemd[1]: Started Manage Sound Card State (restore and store). May 14 16:40:15 volumiosabre systemd[1]: Started UPnP Renderer front-end to MPD. May 14 16:40:15 volumiosabre alsactl[521]: alsactl 1.1.8 daemon started May 14 16:40:15 volumiosabre systemd[1]: Started volumio-remote-updater.service. May 14 16:40:15 volumiosabre systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 14 16:40:15 volumiosabre systemd[1]: Starting Wireless Services... May 14 16:40:15 volumiosabre systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 14 16:40:15 volumiosabre systemd[1]: Starting Login Service... May 14 16:40:15 volumiosabre systemd[1]: Starting Configure Bluetooth Modems connected by UART... May 14 16:40:15 volumiosabre systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 14 16:40:15 volumiosabre systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 14 16:40:15 volumiosabre systemd[1]: Starting dhcpcd on all interfaces... May 14 16:40:15 volumiosabre systemd[1]: Started Volumio Iptables Module. May 14 16:40:15 volumiosabre systemd[1]: Starting triggerhappy global hotkey daemon... May 14 16:40:15 volumiosabre systemd[1]: Starting Save/Restore Sound Card State... May 14 16:40:15 volumiosabre systemd[1]: Started Volumio Log Rotation Service. May 14 16:40:15 volumiosabre systemd[1]: Started D-Bus System Message Bus. May 14 16:40:15 volumiosabre alsactl[521]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error May 14 16:40:15 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 14 16:40:15 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:15 volumiosabre alsactl[521]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error May 14 16:40:15 volumiosabre dhcpcd[528]: Not running dhcpcd because /etc/network/interfaces May 14 16:40:15 volumiosabre dhcpcd[528]: defines some interfaces that will use a May 14 16:40:15 volumiosabre dhcpcd[528]: DHCP client or static address May 14 16:40:15 volumiosabre systemd[1]: Starting WPA supplicant... May 14 16:40:15 volumiosabre systemd[1]: Started RPC bind portmap service. May 14 16:40:15 volumiosabre systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC May 14 16:40:15 volumiosabre systemd[1]: volumiosplash.service: Failed with result 'exit-code'. May 14 16:40:15 volumiosabre systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED May 14 16:40:15 volumiosabre systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 14 16:40:15 volumiosabre systemd[1]: Failed to start dhcpcd on all interfaces. May 14 16:40:15 volumiosabre alsactl[531]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error May 14 16:40:15 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 14 16:40:15 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:15 volumiosabre alsactl[531]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error May 14 16:40:15 volumiosabre thd[530]: Unable to parse trigger line: May 14 16:40:15 volumiosabre thd[530]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 14 16:40:15 volumiosabre thd[530]: Unable to parse trigger line: May 14 16:40:15 volumiosabre thd[530]: Found socket passed from systemd May 14 16:40:15 volumiosabre systemd[1]: Started triggerhappy global hotkey daemon. May 14 16:40:15 volumiosabre systemd[1]: Started Save/Restore Sound Card State. May 14 16:40:15 volumiosabre ifup[423]: ifup: waiting for lock on /run/network/ifstate.wlan0 May 14 16:40:15 volumiosabre systemd[1]: Reached target Sound Card. May 14 16:40:15 volumiosabre systemd[1]: Reached target RPC Port Mapper. May 14 16:40:15 volumiosabre systemd[1]: Reached target Remote File Systems (Pre). May 14 16:40:15 volumiosabre systemd[1]: Reached target Remote File Systems. May 14 16:40:15 volumiosabre sh[421]: wlan0=wlan0 May 14 16:40:16 volumiosabre systemd-logind[526]: New seat seat0. May 14 16:40:16 volumiosabre systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 14 16:40:16 volumiosabre avahi-daemon[525]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). May 14 16:40:16 volumiosabre avahi-daemon[525]: Successfully dropped root privileges. May 14 16:40:16 volumiosabre avahi-daemon[525]: avahi-daemon 0.7 starting up. May 14 16:40:16 volumiosabre sh[425]: eth0: waiting for carrier May 14 16:40:16 volumiosabre dhcpcd[470]: eth0: waiting for carrier May 14 16:40:16 volumiosabre kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 14 16:40:16 volumiosabre kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 14 16:40:16 volumiosabre dbus-daemon[533]: [system] Successfully activated service 'org.freedesktop.systemd1' May 14 16:40:16 volumiosabre avahi-daemon[525]: Successfully called chroot(). May 14 16:40:16 volumiosabre avahi-daemon[525]: Successfully dropped remaining capabilities. May 14 16:40:16 volumiosabre systemd[1]: Started Login Service. May 14 16:40:16 volumiosabre systemd[1]: hciuart.service: Succeeded. May 14 16:40:16 volumiosabre avahi-daemon[525]: Loading service file /services/volumio.service. May 14 16:40:16 volumiosabre systemd[1]: Started Configure Bluetooth Modems connected by UART. May 14 16:40:16 volumiosabre avahi-daemon[525]: Network interface enumeration completed. May 14 16:40:16 volumiosabre avahi-daemon[525]: Server startup complete. Host name is volumiosabre.local. Local service cookie is 250207040. May 14 16:40:16 volumiosabre avahi-daemon[525]: Service "VolumioSabre" (/services/volumio.service) successfully established. May 14 16:40:16 volumiosabre wpa_supplicant[541]: Successfully initialized wpa_supplicant May 14 16:40:17 volumiosabre systemd[1]: Started Raise network interfaces. May 14 16:40:17 volumiosabre systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 14 16:40:17 volumiosabre systemd[1]: Started WPA supplicant. May 14 16:40:17 volumiosabre systemd[1]: Reached target Network. May 14 16:40:17 volumiosabre systemd[1]: Starting Permit User Sessions... May 14 16:40:17 volumiosabre systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 14 16:40:17 volumiosabre systemd[1]: Starting Network Time Service... May 14 16:40:17 volumiosabre systemd[1]: Reached target Network is Online. May 14 16:40:17 volumiosabre systemd[1]: Starting /etc/rc.local Compatibility... May 14 16:40:17 volumiosabre systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 14 16:40:17 volumiosabre systemd[1]: Starting LSB: Brings up/down network automatically... May 14 16:40:17 volumiosabre systemd[1]: Starting Samba NMB Daemon... May 14 16:40:17 volumiosabre systemd[1]: Starting Raspberry Pi bluetooth helper... May 14 16:40:17 volumiosabre systemd[1]: Started /etc/rc.local Compatibility. May 14 16:40:17 volumiosabre volumio-remote-updater[523]: Error: No active session May 14 16:40:17 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:17] [info] asio async_connect error: system:111 (Connection refused) May 14 16:40:17 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 14 16:40:17 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:17] [error] handle_connect error: Underlying Transport Error May 14 16:40:17 volumiosabre systemd[1]: Started Permit User Sessions. May 14 16:40:17 volumiosabre systemd[1]: Starting Hold until boot process finishes up... May 14 16:40:17 volumiosabre systemd[1]: Starting Terminate Plymouth Boot Screen... May 14 16:40:17 volumiosabre systemd[1]: Received SIGRTMIN+21 from PID 257 (plymouthd). May 14 16:40:17 volumiosabre systemd[1]: Received SIGRTMIN+21 from PID 257 (plymouthd). May 14 16:40:17 volumiosabre systemd[1]: plymouth-quit-wait.service: Succeeded. May 14 16:40:17 volumiosabre systemd[1]: Started Hold until boot process finishes up. May 14 16:40:17 volumiosabre volumio[522]: Could not open config: /tmp/upmpdcli.conf May 14 16:40:17 volumiosabre systemd[1]: plymouth-quit.service: Succeeded. May 14 16:40:17 volumiosabre systemd[1]: Started Terminate Plymouth Boot Screen. May 14 16:40:17 volumiosabre systemd[1]: plymouth-start.service: Succeeded. May 14 16:40:17 volumiosabre systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 14 16:40:17 volumiosabre systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 14 16:40:17 volumiosabre bthelper[626]: Raspberry Pi BDADDR already set May 14 16:40:17 volumiosabre systemd[1]: Started Raspberry Pi bluetooth helper. May 14 16:40:17 volumiosabre systemd[1]: Starting Bluetooth service... May 14 16:40:17 volumiosabre systemd[1]: Started Getty on tty1. May 14 16:40:17 volumiosabre systemd[1]: Reached target Login Prompts. May 14 16:40:17 volumiosabre systemd[1]: iptables.service: Succeeded. May 14 16:40:17 volumiosabre loadcpufreq[562]: Loading cpufreq kernel modules...done (none). May 14 16:40:17 volumiosabre systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 14 16:40:18 volumiosabre systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 14 16:40:18 volumiosabre ifplugd(eth0)[688]: ifplugd 0.28 initializing. May 14 16:40:18 volumiosabre ifplugd(eth0)[688]: Using interface eth0/B8:27:EB:AA:B5:65 with driver (version: 6.1.69-v7+) May 14 16:40:18 volumiosabre ifplugd(eth0)[688]: Using detection mode: SIOCETHTOOL May 14 16:40:18 volumiosabre ifplugd(eth0)[688]: Initialization complete, link beat not detected. May 14 16:40:18 volumiosabre ntpd[616]: ntpd 4.2.8p12@1.3728-o (1): Starting May 14 16:40:18 volumiosabre ntpd[616]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 May 14 16:40:18 volumiosabre ifplugd[622]: Network Interface Plugging Daemon...start eth0...done. May 14 16:40:18 volumiosabre ntpd[689]: proto: precision = 1.406 usec (-19) May 14 16:40:18 volumiosabre systemd[1]: Started Network Time Service. May 14 16:40:18 volumiosabre systemd[1]: Started LSB: Brings up/down network automatically. May 14 16:40:18 volumiosabre bluetoothd[669]: Bluetooth daemon 5.50 May 14 16:40:18 volumiosabre ntpd[689]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 14 16:40:18 volumiosabre ntpd[689]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 14 16:40:18 volumiosabre ntpd[689]: Listen and drop on 0 v6wildcard [::]:123 May 14 16:40:18 volumiosabre ntpd[689]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 14 16:40:18 volumiosabre ntpd[689]: Listen normally on 2 lo 127.0.0.1:123 May 14 16:40:18 volumiosabre ntpd[689]: Listening on routing socket on fd #19 for interface updates May 14 16:40:18 volumiosabre ntpd[689]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 16:40:18 volumiosabre ntpd[689]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 16:40:18 volumiosabre bluetoothd[669]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 14 16:40:18 volumiosabre systemd[1]: Started Bluetooth service. May 14 16:40:18 volumiosabre systemd[1]: Reached target Bluetooth. May 14 16:40:18 volumiosabre bluetoothd[669]: Starting SDP server May 14 16:40:18 volumiosabre bluetoothd[669]: Excluding (cli) sap May 14 16:40:18 volumiosabre cpufrequtils[676]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 14 16:40:18 volumiosabre systemd[1]: Started LSB: set CPUFreq kernel parameters. May 14 16:40:18 volumiosabre haveged[465]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K May 14 16:40:18 volumiosabre haveged[465]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 May 14 16:40:18 volumiosabre haveged[465]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99734 May 14 16:40:18 volumiosabre haveged[465]: haveged: fills: 0, generated: 0 May 14 16:40:18 volumiosabre bluetoothd[669]: Bluetooth management interface 1.22 initialized May 14 16:40:18 volumiosabre dbus-daemon[533]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=669 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 14 16:40:18 volumiosabre kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 14 16:40:18 volumiosabre kernel: Bluetooth: BNEP filters: protocol multicast May 14 16:40:18 volumiosabre kernel: Bluetooth: BNEP socket layer initialized May 14 16:40:18 volumiosabre kernel: Bluetooth: MGMT ver 1.22 May 14 16:40:18 volumiosabre bluetoothd[669]: Failed to set privacy: Rejected (0x0b) May 14 16:40:18 volumiosabre systemd[1]: Starting Hostname Service... May 14 16:40:18 volumiosabre dbus-daemon[533]: [system] Successfully activated service 'org.freedesktop.hostname1' May 14 16:40:18 volumiosabre systemd[1]: Started Hostname Service. May 14 16:40:19 volumiosabre ntpd[689]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 14 16:40:19 volumiosabre nmbd[629]: [2024/05/14 16:40:19.185067, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 14 16:40:19 volumiosabre nmbd[629]: started asyncdns process 708 May 14 16:40:19 volumiosabre nmbd[629]: [2024/05/14 16:40:19.187559, 0] ../lib/util/become_daemon.c:149(daemon_status) May 14 16:40:19 volumiosabre nmbd[629]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 14 16:40:19 volumiosabre nmbd[629]: [2024/05/14 16:40:19.187730, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 14 16:40:19 volumiosabre nmbd[629]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 14 16:40:19 volumiosabre wireless.js[524]: Cleaning previous... May 14 16:40:19 volumiosabre systemd[1]: systemd-rfkill.service: Succeeded. May 14 16:40:19 volumiosabre sudo[716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 14 16:40:19 volumiosabre sudo[716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:19 volumiosabre sudo[716]: pam_unix(sudo:session): session closed for user root May 14 16:40:19 volumiosabre sudo[718]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 14 16:40:19 volumiosabre sudo[718]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:20 volumiosabre ntpd[689]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 14 16:40:20 volumiosabre sudo[718]: pam_unix(sudo:session): session closed for user root May 14 16:40:20 volumiosabre wireless.js[524]: Stopped aP May 14 16:40:20 volumiosabre sudo[725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 14 16:40:20 volumiosabre sudo[725]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:20 volumiosabre sudo[725]: pam_unix(sudo:session): session closed for user root May 14 16:40:20 volumiosabre kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 14 16:40:20 volumiosabre sudo[727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 14 16:40:20 volumiosabre sudo[727]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:20 volumiosabre sudo[727]: pam_unix(sudo:session): session closed for user root May 14 16:40:20 volumiosabre sudo[734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 14 16:40:20 volumiosabre sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:20 volumiosabre sudo[734]: pam_unix(sudo:session): session closed for user root May 14 16:40:20 volumiosabre sudo[736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 14 16:40:20 volumiosabre sudo[736]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:21 volumiosabre ntpd[689]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 14 16:40:21 volumiosabre sudo[736]: pam_unix(sudo:session): session closed for user root May 14 16:40:21 volumiosabre wireless.js[524]: SETTING APPROPRIATE REG DOMAIN: EU May 14 16:40:21 volumiosabre sudo[741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 14 16:40:21 volumiosabre sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:21 volumiosabre sudo[741]: pam_unix(sudo:session): session closed for user root May 14 16:40:21 volumiosabre sudo[743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set EU May 14 16:40:21 volumiosabre sudo[743]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:21 volumiosabre sudo[743]: pam_unix(sudo:session): session closed for user root May 14 16:40:21 volumiosabre wireless.js[524]: SUCCESSFULLY SET NEW REGDOMAIN: EU May 14 16:40:21 volumiosabre wireless.js[524]: Start wireless flow May 14 16:40:21 volumiosabre wireless.js[524]: Stopped hotspot (if there).. May 14 16:40:21 volumiosabre systemd-udevd[247]: Process '/sbin/crda' failed with exit code 255. May 14 16:40:21 volumiosabre sudo[749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 14 16:40:21 volumiosabre sudo[749]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:21 volumiosabre sudo[749]: pam_unix(sudo:session): session closed for user root May 14 16:40:21 volumiosabre sudo[751]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 14 16:40:21 volumiosabre sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:21 volumiosabre sudo[751]: pam_unix(sudo:session): session closed for user root May 14 16:40:21 volumiosabre wireless.js[524]: DHCP IP May 14 16:40:21 volumiosabre wireless.js[524]: Start ap May 14 16:40:21 volumiosabre wpa_supplicant[753]: Successfully initialized wpa_supplicant May 14 16:40:21 volumiosabre sudo[754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 14 16:40:21 volumiosabre sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:21 volumiosabre kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 14 16:40:21 volumiosabre dhcpcd[755]: dev: loaded udev May 14 16:40:22 volumiosabre dhcpcd-run-hooks[773]: wlan0: starting wpa_supplicant May 14 16:40:22 volumiosabre ntpd[689]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 14 16:40:22 volumiosabre dhcpcd-run-hooks[778]: wlan0: failed to start wpa_supplicant May 14 16:40:22 volumiosabre dhcpcd-run-hooks[779]: 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 May 14 16:40:22 volumiosabre dhcpcd[755]: wlan0: connected to Access Point `' May 14 16:40:22 volumiosabre dhcpcd[755]: no interfaces have a carrier May 14 16:40:22 volumiosabre dhcpcd[755]: forked to background, child pid 789 May 14 16:40:22 volumiosabre dhcpcd[789]: eth0: waiting for carrier May 14 16:40:22 volumiosabre dhcpcd[789]: wlan0: waiting for carrier May 14 16:40:22 volumiosabre sudo[754]: pam_unix(sudo:session): session closed for user root May 14 16:40:22 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:22] [info] asio async_connect error: system:111 (Connection refused) May 14 16:40:22 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:22] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 14 16:40:22 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:22] [error] handle_connect error: Underlying Transport Error May 14 16:40:22 volumiosabre wpa_supplicant[777]: wlan0: Trying to associate with SSID 'DingoNET2' May 14 16:40:22 volumiosabre bthelper[626]: Changing power off succeeded May 14 16:40:22 volumiosabre wireless.js[524]: trying... May 14 16:40:22 volumiosabre sudo[792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 16:40:22 volumiosabre sudo[792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:22 volumiosabre sudo[792]: pam_unix(sudo:session): session closed for user root May 14 16:40:22 volumiosabre bthelper[626]: [CHG] Controller B8:27:EB:00:1F:CF Class: 0x0000041c May 14 16:40:22 volumiosabre bthelper[626]: Changing power on succeeded May 14 16:40:23 volumiosabre dhcpcd[789]: wlan0: carrier acquired May 14 16:40:23 volumiosabre dhcpcd[789]: wlan0: connected to Access Point `DingoNET2' May 14 16:40:23 volumiosabre wpa_supplicant[777]: wlan0: Associated with 04:d9:f5:1a:37:90 May 14 16:40:23 volumiosabre wpa_supplicant[777]: wlan0: CTRL-EVENT-CONNECTED - Connection to 04:d9:f5:1a:37:90 completed [id=0 id_str=] May 14 16:40:23 volumiosabre wpa_supplicant[777]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 14 16:40:23 volumiosabre dhcpcd[789]: DUID 00:01:00:01:2d:69:1e:29:b8:27:eb:ff:e0:30 May 14 16:40:23 volumiosabre dhcpcd[789]: wlan0: IAID eb:ff:e0:30 May 14 16:40:23 volumiosabre dhcpcd[789]: wlan0: adding address fe80::ba27:ebff:feff:e030 May 14 16:40:23 volumiosabre dhcpcd[789]: ipv6_addaddr1: Permission denied May 14 16:40:23 volumiosabre dhcpcd[789]: wlan0: carrier lost May 14 16:40:23 volumiosabre dhcpcd[789]: wlan0: carrier acquired May 14 16:40:23 volumiosabre dhcpcd[789]: wlan0: IAID eb:ff:e0:30 May 14 16:40:23 volumiosabre dhcpcd[789]: wlan0: soliciting an IPv6 router May 14 16:40:23 volumiosabre wireless.js[524]: trying... May 14 16:40:23 volumiosabre sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 16:40:23 volumiosabre sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:23 volumiosabre sudo[812]: pam_unix(sudo:session): session closed for user root May 14 16:40:24 volumiosabre dhcpcd[789]: wlan0: rebinding lease of 192.168.2.217 May 14 16:40:24 volumiosabre dhcpcd[789]: wlan0: probing address 192.168.2.217/24 May 14 16:40:24 volumiosabre wpa_supplicant[777]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 14 16:40:24 volumiosabre wireless.js[524]: trying... May 14 16:40:24 volumiosabre sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 16:40:24 volumiosabre sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:24 volumiosabre sudo[815]: pam_unix(sudo:session): session closed for user root May 14 16:40:25 volumiosabre wireless.js[524]: trying... May 14 16:40:25 volumiosabre sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 16:40:25 volumiosabre sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:25 volumiosabre sudo[818]: pam_unix(sudo:session): session closed for user root May 14 16:40:26 volumiosabre wireless.js[524]: trying... May 14 16:40:26 volumiosabre sudo[821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 16:40:26 volumiosabre sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:26 volumiosabre sudo[821]: pam_unix(sudo:session): session closed for user root May 14 16:40:27 volumiosabre wireless.js[524]: trying... May 14 16:40:27 volumiosabre sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 16:40:27 volumiosabre sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:27 volumiosabre sudo[824]: pam_unix(sudo:session): session closed for user root May 14 16:40:28 volumiosabre dhcpcd[789]: wlan0: leased 192.168.2.217 for 86400 seconds May 14 16:40:28 volumiosabre avahi-daemon[525]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.217. May 14 16:40:28 volumiosabre dhcpcd[789]: wlan0: adding route to 192.168.2.0/24 May 14 16:40:28 volumiosabre dhcpcd[789]: wlan0: adding default route via 192.168.2.1 May 14 16:40:28 volumiosabre avahi-daemon[525]: New relevant interface wlan0.IPv4 for mDNS. May 14 16:40:28 volumiosabre avahi-daemon[525]: Registering new address record for 192.168.2.217 on wlan0.IPv4. May 14 16:40:28 volumiosabre wireless.js[524]: trying... May 14 16:40:28 volumiosabre sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 16:40:28 volumiosabre sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:28 volumiosabre sudo[847]: pam_unix(sudo:session): session closed for user root May 14 16:40:28 volumiosabre wireless.js[524]: Connected to: ----DingoNET2 May 14 16:40:28 volumiosabre wireless.js[524]: ---- May 14 16:40:28 volumiosabre sudo[850]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 16:40:28 volumiosabre sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:28 volumiosabre sudo[850]: pam_unix(sudo:session): session closed for user root May 14 16:40:28 volumiosabre wireless.js[524]: ... joined AP, wlan0 IPv4 is 192.168.2.217, ipV6 is undefined May 14 16:40:28 volumiosabre wireless.js[524]: It's done! AP May 14 16:40:29 volumiosabre systemd[1]: Started Wireless Services. May 14 16:40:29 volumiosabre kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 14 16:40:29 volumiosabre systemd[1]: Started Volumio Backend Module. May 14 16:40:29 volumiosabre systemd[1]: Started Volumio Cpu Tweaker. May 14 16:40:29 volumiosabre volumio-cpu-tweak[856]: Setting RT Priority for mpd May 14 16:40:29 volumiosabre volumio-cpu-tweak[856]: pid 35's current scheduling policy: SCHED_OTHER May 14 16:40:29 volumiosabre volumio-cpu-tweak[856]: pid 35's current scheduling priority: 0 May 14 16:40:29 volumiosabre volumio-cpu-tweak[856]: Setting MPD Affinity May 14 16:40:29 volumiosabre volumio-cpu-tweak[856]: pid 3's current affinity mask: f May 14 16:40:29 volumiosabre volumio-cpu-tweak[856]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 14 16:40:29 volumiosabre volumio-cpu-tweak[856]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 14 16:40:29 volumiosabre nmbd[629]: [2024/05/14 16:40:29.202933, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 14 16:40:29 volumiosabre nmbd[629]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 14 16:40:29 volumiosabre systemd[1]: Started Samba NMB Daemon. May 14 16:40:29 volumiosabre systemd[1]: Starting Samba Winbind Daemon... May 14 16:40:29 volumiosabre systemd[1]: volumio_cpu_tweak.service: Succeeded. May 14 16:40:29 volumiosabre winbindd[872]: [2024/05/14 16:40:29.614867, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 14 16:40:29 volumiosabre winbindd[872]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 14 16:40:29 volumiosabre winbindd[872]: [2024/05/14 16:40:29.637776, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 14 16:40:29 volumiosabre winbindd[872]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 14 16:40:29 volumiosabre systemd[1]: Started Samba Winbind Daemon. May 14 16:40:29 volumiosabre systemd[1]: Starting Samba SMB Daemon... May 14 16:40:29 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:29] [info] asio async_connect error: system:111 (Connection refused) May 14 16:40:29 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 14 16:40:29 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:29] [error] handle_connect error: Underlying Transport Error May 14 16:40:30 volumiosabre ntpd[689]: Listen normally on 3 wlan0 192.168.2.217:123 May 14 16:40:30 volumiosabre ntpd[689]: new interface(s) found: waking up resolver May 14 16:40:30 volumiosabre smbd[877]: [2024/05/14 16:40:30.230589, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 14 16:40:30 volumiosabre smbd[877]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 14 16:40:30 volumiosabre systemd[1]: Started Samba SMB Daemon. May 14 16:40:30 volumiosabre systemd[1]: Reached target Multi-User System. May 14 16:40:30 volumiosabre systemd[1]: Reached target Graphical Interface. May 14 16:40:30 volumiosabre systemd[1]: Starting Update UTMP about System Runlevel Changes... May 14 16:40:30 volumiosabre systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 14 16:40:30 volumiosabre systemd[1]: Started Update UTMP about System Runlevel Changes. May 14 16:40:30 volumiosabre systemd[1]: Startup finished in 10.116s (kernel) + 22.340s (userspace) = 32.456s. May 14 16:40:32 volumiosabre volumio[855]: info: ------------------------------------------- May 14 16:40:32 volumiosabre volumio[855]: info: ----- Volumio3 ---- May 14 16:40:32 volumiosabre volumio[855]: info: ------------------------------------------- May 14 16:40:32 volumiosabre volumio[855]: info: ----- System startup ---- May 14 16:40:32 volumiosabre volumio[855]: info: ------------------------------------------- May 14 16:40:33 volumiosabre volumio[855]: info: MYVOLUMIO Environment detected May 14 16:40:33 volumiosabre volumio[855]: info: Plugin folders cleanup May 14 16:40:33 volumiosabre volumio[855]: info: Scanning into folder /volumio/app/plugins/ May 14 16:40:33 volumiosabre volumio[855]: info: Scanning category audio_interface May 14 16:40:33 volumiosabre volumio[855]: info: Scanning category miscellanea May 14 16:40:33 volumiosabre volumio[855]: info: Scanning category music_service May 14 16:40:33 volumiosabre volumio[855]: info: Scanning category plugins.json May 14 16:40:33 volumiosabre volumio[855]: info: Scanning category system_controller May 14 16:40:33 volumiosabre volumio[855]: info: Scanning category user_interface May 14 16:40:33 volumiosabre volumio[855]: info: Scanning into folder /data/plugins/ May 14 16:40:33 volumiosabre volumio[855]: info: Plugin folders cleanup completed May 14 16:40:33 volumiosabre volumio[855]: info: ------------------------------------------- May 14 16:40:33 volumiosabre volumio[855]: info: ----- Core plugins startup ---- May 14 16:40:33 volumiosabre volumio[855]: info: ------------------------------------------- May 14 16:40:33 volumiosabre volumio[855]: info: Loading plugins from folder /volumio/app/plugins/ May 14 16:40:33 volumiosabre volumio[855]: info: Adding plugin upnp to MyMusic Plugins May 14 16:40:33 volumiosabre volumio[855]: info: Adding plugin airplay_emulation to MyMusic Plugins May 14 16:40:33 volumiosabre volumio[855]: info: Adding plugin upnp_browser to MyMusic Plugins May 14 16:40:33 volumiosabre volumio[855]: info: Loading plugins from folder /data/plugins/ May 14 16:40:33 volumiosabre volumio[855]: info: Loading plugin "system"... May 14 16:40:33 volumiosabre volumio[855]: info: Loading plugin "appearance"... May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "network"... May 14 16:40:35 volumiosabre volumio[855]: info: Refreshing Cached IP Addresses May 14 16:40:35 volumiosabre sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 16:40:35 volumiosabre sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "services"... May 14 16:40:35 volumiosabre sudo[899]: pam_unix(sudo:session): session closed for user root May 14 16:40:35 volumiosabre sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 16:40:35 volumiosabre sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "alsa_controller"... May 14 16:40:35 volumiosabre sudo[901]: pam_unix(sudo:session): session closed for user root May 14 16:40:35 volumiosabre sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 14 16:40:35 volumiosabre sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:35 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 16:40:35 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:35 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 14 16:40:35 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "wizard"... May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "networkfs"... May 14 16:40:35 volumiosabre sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=media,password=media1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.200/music/FLAC /mnt/NAS/FLAC May 14 16:40:35 volumiosabre volumio[855]: info: Starting Udev Watcher for removable devices May 14 16:40:35 volumiosabre sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:35 volumiosabre sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=media,password=media1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.200/music/SACD /mnt/NAS/SACD May 14 16:40:35 volumiosabre sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:35 volumiosabre volumio[855]: info: Ignoring mount for partition: boot May 14 16:40:35 volumiosabre volumio[855]: info: Ignoring mount for partition: volumio May 14 16:40:35 volumiosabre volumio[855]: info: Ignoring mount for partition: volumio_data May 14 16:40:35 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "volumio_command_line_client"... May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "upnp"... May 14 16:40:35 volumiosabre volumio[855]: info: [1715697635649] Starting Upmpd Daemon May 14 16:40:35 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "my_music"... May 14 16:40:35 volumiosabre volumio[855]: info: Loading plugin "mpd"... May 14 16:40:35 volumiosabre kernel: Key type cifs.spnego registered May 14 16:40:35 volumiosabre kernel: Key type cifs.idmap registered May 14 16:40:35 volumiosabre 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. May 14 16:40:35 volumiosabre kernel: CIFS: Attempting to mount \\192.168.2.200\music May 14 16:40:36 volumiosabre sudo[904]: pam_unix(sudo:session): session closed for user root May 14 16:40:36 volumiosabre volumio[855]: info: Loading plugin "upnp_browser"... May 14 16:40:36 volumiosabre kernel: CIFS: Attempting to mount \\192.168.2.200\music May 14 16:40:36 volumiosabre sudo[912]: pam_unix(sudo:session): session closed for user root May 14 16:40:36 volumiosabre sudo[914]: pam_unix(sudo:session): session closed for user root May 14 16:40:36 volumiosabre volumio[855]: info: Loading plugin "alarm-clock"... May 14 16:40:36 volumiosabre volumio[855]: info: Loading plugin "airplay_emulation"... May 14 16:40:36 volumiosabre volumio[855]: info: Starting Shairport Sync May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "last_100"... May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "webradio"... May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "i2s_dacs"... May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "volumiodiscovery"... May 14 16:40:37 volumiosabre volumio[855]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 14 16:40:37 volumiosabre volumio[855]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 16:40:37 volumiosabre volumio[855]: *** WARNING *** For more information see May 14 16:40:37 volumiosabre volumio[855]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 14 16:40:37 volumiosabre node[855]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 14 16:40:37 volumiosabre volumio[855]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 16:40:37 volumiosabre volumio[855]: *** WARNING *** For more information see May 14 16:40:37 volumiosabre node[855]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 16:40:37 volumiosabre node[855]: *** WARNING *** For more information see May 14 16:40:37 volumiosabre node[855]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 14 16:40:37 volumiosabre node[855]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 16:40:37 volumiosabre node[855]: *** WARNING *** For more information see May 14 16:40:37 volumiosabre volumio[855]: info: Applying required configuration parameters for plugin volumiodiscovery May 14 16:40:37 volumiosabre volumio[855]: info: Discovery: Started advertising with name: VolumioSabre May 14 16:40:37 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "outputs"... May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "albumart"... May 14 16:40:37 volumiosabre volumio[855]: info: Plugin example_plugin is not enabled May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "inputs"... May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "updater_comm"... May 14 16:40:37 volumiosabre volumio[855]: info: Plugin mpdemulation is not enabled May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "rest_api"... May 14 16:40:37 volumiosabre volumio[855]: info: Loading plugin "websocket"... May 14 16:40:37 volumiosabre volumio[855]: info: Loading i18n strings for locale nl May 14 16:40:37 volumiosabre volumio[855]: Updating browse sources language May 14 16:40:37 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:40:37 volumiosabre volumio[855]: Forking 3 albumart workers May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::initPlayerControls May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: Express server listening on port 3000 May 14 16:40:38 volumiosabre volumio[855]: [Metrics] WebUI: 6s 887.92ms May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::resetVolumioState May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::getcurrentVolume May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::volumioRetrievevolume May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:38 volumiosabre volumio[855]: verbose: New Socket.io Connection to 192.168.2.217 from 192.168.2.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 1 May 14 16:40:38 volumiosabre volumio[855]: verbose: New Socket.io Connection to 192.168.2.217 from 192.168.2.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 2 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:40:38 volumiosabre volumio[855]: info: Reloading queue from file May 14 16:40:38 volumiosabre volumio[855]: info: VolumeController:: Volume=76 Mute =true May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::pushState May 14 16:40:38 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::volumioPushState May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::updateTrackBlock May 14 16:40:38 volumiosabre volumio[855]: info: CorePlayQueue::getTrackBlock May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::volumioRetrievevolume May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::setRepeat null single undefined May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::pushState May 14 16:40:38 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::volumioPushState May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::setRandom null May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::pushState May 14 16:40:38 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::volumioPushState May 14 16:40:38 volumiosabre volumio[855]: info: Setting Device type: Raspberry PI May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:38 volumiosabre volumio[855]: info: Discovery: adding 48fb95a7-cf67-444b-b3e0-50b43781cbd7 May 14 16:40:38 volumiosabre volumio[855]: info: Discovery: Found device VolumioSabre May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetState May 14 16:40:38 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 14 16:40:38 volumiosabre kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 14 16:40:38 volumiosabre volumio[855]: info: VolumeController:: Volume=76 Mute =true May 14 16:40:38 volumiosabre volumio[855]: info: CoreStateMachine::pushState May 14 16:40:38 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:38 volumiosabre volumio[855]: info: CoreCommandRouter::volumioPushState May 14 16:40:38 volumiosabre volumio[855]: Starting albumart workers May 14 16:40:38 volumiosabre volumio[855]: Starting albumart workers May 14 16:40:38 volumiosabre volumio[855]: Starting albumart workers May 14 16:40:39 volumiosabre volumio[855]: verbose: New Socket.io Connection to 192.168.2.217 from 192.168.2.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 3 May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetVisibleSources May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetState May 14 16:40:39 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetState May 14 16:40:39 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 16:40:39 volumiosabre volumio[855]: info: Received Get System Info May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 16:40:39 volumiosabre volumio[855]: info: Discovery: Getting this device information May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetState May 14 16:40:39 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 16:40:39 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetState May 14 16:40:39 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:39 volumiosabre volumio[855]: info: Listing playlists May 14 16:40:39 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:39] [connect] Successful connection May 14 16:40:39 volumiosabre volumio-remote-updater[523]: [2024-05-14 16:40:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715697639 101 May 14 16:40:39 volumiosabre volumio[855]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 May 14 16:40:44 volumiosabre systemd[1]: systemd-fsckd.service: Succeeded. May 14 16:40:45 volumiosabre sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 16:40:45 volumiosabre sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:45 volumiosabre sudo[1011]: pam_unix(sudo:session): session closed for user root May 14 16:40:45 volumiosabre sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 16:40:45 volumiosabre sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:45 volumiosabre sudo[1013]: pam_unix(sudo:session): session closed for user root May 14 16:40:45 volumiosabre sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 14 16:40:45 volumiosabre sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 16:40:45 volumiosabre systemd[1]: Started UPnP Renderer front-end to MPD. May 14 16:40:45 volumiosabre sudo[1016]: pam_unix(sudo:session): session closed for user root May 14 16:40:45 volumiosabre volumio[855]: info: Upmpdcli Daemon Started May 14 16:40:45 volumiosabre volumio[855]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 14 16:40:46 volumiosabre sh[425]: timed out May 14 16:40:46 volumiosabre dhcpcd[470]: timed out May 14 16:40:46 volumiosabre sh[425]: dhcpcd exited May 14 16:40:46 volumiosabre dhcpcd[470]: dhcpcd exited May 14 16:40:46 volumiosabre sh[425]: ifup: failed to bring up eth0 May 14 16:40:46 volumiosabre systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 14 16:40:46 volumiosabre systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 14 16:40:48 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetState May 14 16:40:48 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:48 volumiosabre systemd[1]: systemd-hostnamed.service: Succeeded. May 14 16:40:49 volumiosabre volumio[855]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetBrowseSources May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetBrowseSources May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetBrowseSources May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 16:40:50 volumiosabre volumio[855]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 14 16:40:54 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetState May 14 16:40:54 volumiosabre volumio[855]: info: CorePlayQueue::getTrack 0 May 14 16:40:55 volumiosabre volumio[855]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 14 16:40:56 volumiosabre volumio[855]: info: CoreCommandRouter::volumioGetQueue May 14 16:40:56 volumiosabre volumio[855]: info: CoreStateMachine::getQueue May 14 16:40:56 volumiosabre volumio[855]: info: CorePlayQueue::getQueue May 14 16:40:59 volumiosabre volumio[855]: info: Preload queue cleared May 14 16:40:59 volumiosabre volumio[855]: info: CoreCommandRouter::volumioReplaceandPlayItems May 14 16:40:59 volumiosabre volumio[855]: info: CoreStateMachine::ClearQueue May 14 16:40:59 volumiosabre volumio[855]: info: CoreStateMachine::stop May 14 16:40:59 volumiosabre volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 16:40:59 volumiosabre volumio[855]: info: CorePlayQueue::clearPlayQueue May 14 16:40:59 volumiosabre volumio[855]: info: CorePlayQueue::saveQueue May 14 16:40:59 volumiosabre volumio[855]: info: CoreCommandRouter::volumioPushQueue May 14 16:40:59 volumiosabre volumio[855]: info: CoreStateMachine::addQueueItems May 14 16:40:59 volumiosabre volumio[855]: info: CorePlayQueue::addQueueItems May 14 16:40:59 volumiosabre volumio[855]: info: Preload queue cleared May 14 16:40:59 volumiosabre volumio[855]: info: Adding Item to queue: mnt/NAS/SACD/Depeche Mode/Exciter [SACD DSF][2007]/07 - Depeche Mode - Freelove.dsf May 14 16:40:59 volumiosabre volumio[855]: info: Exploding uri mnt/NAS/SACD/Depeche Mode/Exciter [SACD DSF][2007]/07 - Depeche Mode - Freelove.dsf in service mpd May 14 16:40:59 volumiosabre volumio[855]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 16:40:59 volumiosabre volumio[855]: TypeError: Cannot read property 'then' of undefined May 14 16:40:59 volumiosabre volumio[855]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) May 14 16:40:59 volumiosabre volumio[855]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) May 14 16:40:59 volumiosabre volumio[855]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) May 14 16:40:59 volumiosabre volumio[855]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 14 16:40:59 volumiosabre volumio[855]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 14 16:40:59 volumiosabre volumio[855]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 14 16:40:59 volumiosabre volumio[855]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) May 14 16:40:59 volumiosabre volumio[855]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) May 14 16:40:59 volumiosabre volumio[855]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) May 14 16:40:59 volumiosabre volumio[855]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) May 14 16:40:59 volumiosabre volumio[855]: at Socket.emit (events.js:315:20) May 14 16:40:59 volumiosabre volumio[855]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 14 16:40:59 volumiosabre volumio[855]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 14 16:40:59 volumiosabre volumio[855]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 16:40:59 volumiosabre sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-14 16:39 May 14 16:41:00 volumiosabre sudo[1038]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"