-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-09-17 17:07:39 CEST. -- Feb 14 11:11:59 volumio-kitchen systemd[1]: Starting Apply Kernel Variables... Sep 17 17:06:54 volumio-kitchen fake-hwclock[323]: Tue Sep 17 15:06:54 UTC 2024 Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounting Kernel Configuration File System... Sep 17 17:06:54 volumio-kitchen systemd[1]: dynamicswap.service: Succeeded. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Restore / save the current clock. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Load/Save Random Seed. Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounted FUSE Control File System. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Apply Kernel Variables. Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounted Kernel Configuration File System. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Create System Users. Sep 17 17:06:54 volumio-kitchen systemd[1]: Starting Create Static Device Nodes in /dev... Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Create Static Device Nodes in /dev. Sep 17 17:06:54 volumio-kitchen systemd[1]: Reached target Local File Systems (Pre). Sep 17 17:06:54 volumio-kitchen systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounting /tmp... Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounting /var/spool/cups... Sep 17 17:06:54 volumio-kitchen systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounting /var/log... Sep 17 17:06:54 volumio-kitchen systemd[1]: Starting udev Kernel Device Manager... Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounted /tmp. Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounted /var/spool/cups. Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounted /var/log. Sep 17 17:06:54 volumio-kitchen systemd[1]: Starting Flush Journal to Persistent Storage... Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounting /var/spool/cups/tmp... Sep 17 17:06:54 volumio-kitchen systemd[1]: Started udev Coldplug all Devices. Sep 17 17:06:54 volumio-kitchen systemd[1]: Starting Helper to synchronize boot up for ifupdown... Sep 17 17:06:54 volumio-kitchen systemd[1]: Mounted /var/spool/cups/tmp. Sep 17 17:06:54 volumio-kitchen systemd-udevd[354]: Network interface NamePolicy= disabled on kernel command line, ignoring. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Helper to synchronize boot up for ifupdown. Sep 17 17:06:54 volumio-kitchen systemd-journald[309]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 7.5M, max 30.0M, 22.5M free. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Flush Journal to Persistent Storage. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started udev Kernel Device Manager. Sep 17 17:06:54 volumio-kitchen systemd[1]: Starting Show Plymouth Boot Screen... Sep 17 17:06:54 volumio-kitchen systemd[1]: plymouth-start.service: Succeeded. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Show Plymouth Boot Screen. Sep 17 17:06:54 volumio-kitchen systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Sep 17 17:06:54 volumio-kitchen systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 17 17:06:54 volumio-kitchen systemd[1]: Reached target Paths. Sep 17 17:06:54 volumio-kitchen systemd[1]: Reached target Local Encrypted Volumes. Sep 17 17:06:54 volumio-kitchen systemd-udevd[362]: Using default interface naming scheme 'v240'. Sep 17 17:06:54 volumio-kitchen kernel: mc: Linux media interface: v0.10 Sep 17 17:06:54 volumio-kitchen systemd-udevd[362]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 17 17:06:54 volumio-kitchen kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Sep 17 17:06:54 volumio-kitchen kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Sep 17 17:06:54 volumio-kitchen kernel: [vc_sm_connected_init]: start Sep 17 17:06:54 volumio-kitchen kernel: [vc_sm_connected_init]: installed successfully Sep 17 17:06:54 volumio-kitchen kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Sep 17 17:06:54 volumio-kitchen kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Sep 17 17:06:54 volumio-kitchen kernel: videodev: Linux video capture interface: v2.00 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Sep 17 17:06:55 volumio-kitchen kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Sep 17 17:06:55 volumio-kitchen kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Sep 17 17:06:55 volumio-kitchen kernel: rpivid feb10000.codec: Device registered as /dev/video19 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Sep 17 17:06:55 volumio-kitchen kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Sep 17 17:06:55 volumio-kitchen kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Sep 17 17:06:55 volumio-kitchen kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Sep 17 17:06:55 volumio-kitchen kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Sep 17 17:06:55 volumio-kitchen kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Sep 17 17:06:55 volumio-kitchen kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Sep 17 17:06:55 volumio-kitchen kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Sep 17 17:06:55 volumio-kitchen kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Sep 17 17:06:55 volumio-kitchen systemd[1]: Found device /dev/disk/by-uuid/DC0C-AF04. Sep 17 17:06:55 volumio-kitchen systemd[1]: Starting File System Check on /dev/disk/by-uuid/DC0C-AF04... Sep 17 17:06:55 volumio-kitchen kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Sep 17 17:06:55 volumio-kitchen kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Sep 17 17:06:55 volumio-kitchen kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: Core ver 2.22 Sep 17 17:06:55 volumio-kitchen kernel: NET: Registered PF_BLUETOOTH protocol family Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: HCI device and connection manager initialized Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: HCI socket layer initialized Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: L2CAP socket layer initialized Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: SCO socket layer initialized Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: HCI UART driver ver 2.3 Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: HCI UART protocol H4 registered Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Sep 17 17:06:55 volumio-kitchen kernel: Bluetooth: HCI UART protocol Broadcom registered Sep 17 17:06:55 volumio-kitchen kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Sep 17 17:06:55 volumio-kitchen kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Sep 17 17:06:55 volumio-kitchen kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Sep 17 17:06:55 volumio-kitchen kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Sep 17 17:06:55 volumio-kitchen kernel: usbcore: registered new interface driver brcmfmac Sep 17 17:06:56 volumio-kitchen systemd-fsck[445]: fsck.fat 4.1 (2017-01-24) Sep 17 17:06:56 volumio-kitchen systemd-fsck[445]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sep 17 17:06:56 volumio-kitchen systemd-fsck[445]: Automatically removing dirty bit. Sep 17 17:06:56 volumio-kitchen systemd-fsck[445]: Performing changes. Sep 17 17:06:56 volumio-kitchen systemd-fsck[445]: /dev/mmcblk0p1: 378 files, 33709/46774 clusters Sep 17 17:06:56 volumio-kitchen kernel: uart-pl011 fe201000.serial: no DMA platform data Sep 17 17:06:56 volumio-kitchen systemd[1]: Started File System Check on /dev/disk/by-uuid/DC0C-AF04. Sep 17 17:06:56 volumio-kitchen systemd-udevd[368]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 17 17:06:56 volumio-kitchen kernel: snd-allo-boss-dac soc:sound: snd_soc_register_card() failed: -517 Sep 17 17:06:56 volumio-kitchen kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Sep 17 17:06:56 volumio-kitchen kernel: Bluetooth: hci0: BCM: chip id 107 Sep 17 17:06:56 volumio-kitchen kernel: Bluetooth: hci0: BCM: features 0x2f Sep 17 17:06:56 volumio-kitchen kernel: Bluetooth: hci0: BCM4345C0 Sep 17 17:06:56 volumio-kitchen kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Sep 17 17:06:56 volumio-kitchen kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Sep 17 17:06:56 volumio-kitchen systemd-udevd[363]: Using default interface naming scheme 'v240'. Sep 17 17:06:56 volumio-kitchen systemd-udevd[363]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 17 17:06:56 volumio-kitchen kernel: pcm512x 1-004d: supply AVDD not found, using dummy regulator Sep 17 17:06:56 volumio-kitchen kernel: pcm512x 1-004d: supply DVDD not found, using dummy regulator Sep 17 17:06:56 volumio-kitchen kernel: pcm512x 1-004d: supply CPVDD not found, using dummy regulator Sep 17 17:06:57 volumio-kitchen kernel: Bluetooth: hci0: BCM: features 0x2f Sep 17 17:06:57 volumio-kitchen kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Sep 17 17:06:57 volumio-kitchen kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Sep 17 17:06:57 volumio-kitchen systemd-udevd[364]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Sep 17 17:06:57 volumio-kitchen systemd[1]: Created slice system-bthelper.slice. Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 17 17:06:57 volumio-kitchen kernel: hwmon hwmon1: Undervoltage detected! Sep 17 17:06:57 volumio-kitchen systemd[1]: Starting Show Plymouth Boot Screen... Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in Huge Pages File System being skipped. Sep 17 17:06:57 volumio-kitchen systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Sep 17 17:06:57 volumio-kitchen systemd[1]: Mounting /boot... Sep 17 17:06:57 volumio-kitchen systemd[1]: Started File System Check Daemon to report status. Sep 17 17:06:57 volumio-kitchen systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Sep 17 17:06:57 volumio-kitchen systemd[1]: Mounted /boot. Sep 17 17:06:57 volumio-kitchen systemd[1]: plymouth-start.service: Succeeded. Sep 17 17:06:57 volumio-kitchen systemd[1]: Started Show Plymouth Boot Screen. Sep 17 17:06:57 volumio-kitchen systemd[1]: Starting Load/Save RF Kill Switch Status... Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 17 17:06:57 volumio-kitchen systemd[1]: Reached target Local File Systems. Sep 17 17:06:57 volumio-kitchen systemd[1]: Starting Preprocess NFS configuration... Sep 17 17:06:57 volumio-kitchen systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 17 17:06:57 volumio-kitchen systemd[1]: Starting Create Volatile Files and Directories... Sep 17 17:06:57 volumio-kitchen systemd[1]: Starting Raise network interfaces... Sep 17 17:06:57 volumio-kitchen systemd[1]: Started ifup for eth0. Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Sep 17 17:06:57 volumio-kitchen systemd[1]: Started ifup for wlan0. Sep 17 17:06:57 volumio-kitchen systemd[1]: plymouth-read-write.service: Succeeded. Sep 17 17:06:57 volumio-kitchen systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 17 17:06:57 volumio-kitchen systemd[1]: Started Load/Save RF Kill Switch Status. Sep 17 17:06:57 volumio-kitchen systemd[1]: nfs-config.service: Succeeded. Sep 17 17:06:57 volumio-kitchen systemd[1]: Started Preprocess NFS configuration. Sep 17 17:06:57 volumio-kitchen systemd[1]: Received SIGRTMIN+20 from PID 207 (plymouthd). Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Sep 17 17:06:57 volumio-kitchen systemd[1]: Reached target NFS client services. Sep 17 17:06:57 volumio-kitchen systemd[1]: Started Create Volatile Files and Directories. Sep 17 17:06:57 volumio-kitchen systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 17 17:06:57 volumio-kitchen systemd[1]: Starting RPC bind portmap service... Sep 17 17:06:57 volumio-kitchen systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Sep 17 17:06:57 volumio-kitchen systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 17 17:06:58 volumio-kitchen systemd[1]: Reached target System Initialization. Sep 17 17:06:58 volumio-kitchen kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 17:06:58 volumio-kitchen systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2024-11-26 09:24:10 CET as it is in the future. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Daily apt download activities. Sep 17 17:06:58 volumio-kitchen systemd[1]: Listening on triggerhappy.socket. Sep 17 17:06:58 volumio-kitchen kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 17 17:06:58 volumio-kitchen systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 17 17:06:58 volumio-kitchen systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 17 17:06:58 volumio-kitchen systemd[1]: Reached target Sockets. Sep 17 17:06:58 volumio-kitchen systemd[1]: Reached target Basic System. Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting Login Service... Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting Configure Bluetooth Modems connected by UART... Sep 17 17:06:58 volumio-kitchen systemd[1]: Started UPnP Renderer front-end to MPD. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Manage Sound Card State (restore and store). Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting Save/Restore Sound Card State... Sep 17 17:06:58 volumio-kitchen alsactl[621]: alsactl 1.1.8 daemon started Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting triggerhappy global hotkey daemon... Sep 17 17:06:58 volumio-kitchen systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2024-11-26 06:44:48 CET as it is in the future. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Daily apt upgrade and clean activities. Sep 17 17:06:58 volumio-kitchen systemd[1]: Reached target Timers. Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Volumio Iptables Module. Sep 17 17:06:58 volumio-kitchen systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Sep 17 17:06:58 volumio-kitchen systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started D-Bus System Message Bus. Sep 17 17:06:58 volumio-kitchen alsactl[621]: No state is present for card b1 Sep 17 17:06:58 volumio-kitchen alsactl[621]: /usr/share/alsa/init/bcm2835:14: control element not found Sep 17 17:06:58 volumio-kitchen alsactl[621]: No state is present for card b1 Sep 17 17:06:58 volumio-kitchen alsactl[621]: No state is present for card Headphones Sep 17 17:06:58 volumio-kitchen alsactl[621]: /usr/share/alsa/init/bcm2835:20: control element not found Sep 17 17:06:58 volumio-kitchen alsactl[621]: No state is present for card Headphones Sep 17 17:06:58 volumio-kitchen alsactl[621]: No state is present for card BossDAC Sep 17 17:06:58 volumio-kitchen alsactl[621]: Found hardware: "BossDAC" "" "" "" "" Sep 17 17:06:58 volumio-kitchen alsactl[621]: Hardware is initialized using a generic method Sep 17 17:06:58 volumio-kitchen alsactl[621]: No state is present for card BossDAC Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting WPA supplicant... Sep 17 17:06:58 volumio-kitchen systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting dhcpcd on all interfaces... Sep 17 17:06:58 volumio-kitchen thd[625]: Unable to parse trigger line: Sep 17 17:06:58 volumio-kitchen thd[625]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Sep 17 17:06:58 volumio-kitchen thd[625]: Unable to parse trigger line: Sep 17 17:06:58 volumio-kitchen thd[625]: Found socket passed from systemd Sep 17 17:06:58 volumio-kitchen alsactl[623]: No state is present for card b1 Sep 17 17:06:58 volumio-kitchen alsactl[623]: /usr/share/alsa/init/bcm2835:14: control element not found Sep 17 17:06:58 volumio-kitchen alsactl[623]: No state is present for card b1 Sep 17 17:06:58 volumio-kitchen alsactl[623]: No state is present for card Headphones Sep 17 17:06:58 volumio-kitchen alsactl[623]: /usr/share/alsa/init/bcm2835:20: control element not found Sep 17 17:06:58 volumio-kitchen alsactl[623]: No state is present for card Headphones Sep 17 17:06:58 volumio-kitchen alsactl[623]: No state is present for card BossDAC Sep 17 17:06:58 volumio-kitchen alsactl[623]: Found hardware: "BossDAC" "" "" "" "" Sep 17 17:06:58 volumio-kitchen alsactl[623]: Hardware is initialized using a generic method Sep 17 17:06:58 volumio-kitchen alsactl[623]: No state is present for card BossDAC Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting Wireless Services... Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Volumio Log Rotation Service. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started volumio-remote-updater.service. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started RPC bind portmap service. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started triggerhappy global hotkey daemon. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Save/Restore Sound Card State. Sep 17 17:06:58 volumio-kitchen dhcpcd[639]: Not running dhcpcd because /etc/network/interfaces Sep 17 17:06:58 volumio-kitchen dhcpcd[639]: defines some interfaces that will use a Sep 17 17:06:58 volumio-kitchen dhcpcd[639]: DHCP client or static address Sep 17 17:06:58 volumio-kitchen systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Sep 17 17:06:58 volumio-kitchen systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Sep 17 17:06:58 volumio-kitchen systemd[1]: Failed to start dhcpcd on all interfaces. Sep 17 17:06:58 volumio-kitchen systemd[1]: hciuart.service: Succeeded. Sep 17 17:06:58 volumio-kitchen systemd[1]: Started Configure Bluetooth Modems connected by UART. Sep 17 17:06:58 volumio-kitchen avahi-daemon[629]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Sep 17 17:06:58 volumio-kitchen avahi-daemon[629]: Successfully dropped root privileges. Sep 17 17:06:58 volumio-kitchen avahi-daemon[629]: avahi-daemon 0.7 starting up. Sep 17 17:06:58 volumio-kitchen kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Sep 17 17:06:58 volumio-kitchen sh[524]: eth0: waiting for carrier Sep 17 17:06:58 volumio-kitchen dhcpcd[570]: eth0: waiting for carrier Sep 17 17:06:58 volumio-kitchen kernel: bcmgenet fd580000.ethernet eth0: Link is Down Sep 17 17:06:58 volumio-kitchen sh[526]: wlan0=wlan0 Sep 17 17:06:58 volumio-kitchen systemd-logind[608]: New seat seat0. Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting Raspberry Pi bluetooth helper... Sep 17 17:06:58 volumio-kitchen systemd[1]: Reached target Sound Card. Sep 17 17:06:58 volumio-kitchen systemd[1]: Reached target RPC Port Mapper. Sep 17 17:06:58 volumio-kitchen systemd[1]: Reached target Remote File Systems (Pre). Sep 17 17:06:58 volumio-kitchen systemd[1]: Reached target Remote File Systems. Sep 17 17:06:58 volumio-kitchen systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 17 17:06:58 volumio-kitchen bthelper[665]: Raspberry Pi BDADDR already set Sep 17 17:06:59 volumio-kitchen dbus-daemon[636]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 17 17:06:59 volumio-kitchen avahi-daemon[629]: Successfully called chroot(). Sep 17 17:06:59 volumio-kitchen avahi-daemon[629]: Successfully dropped remaining capabilities. Sep 17 17:06:59 volumio-kitchen wpa_supplicant[638]: Successfully initialized wpa_supplicant Sep 17 17:06:59 volumio-kitchen systemd[1]: Started Login Service. Sep 17 17:06:59 volumio-kitchen systemd[1]: Started Raspberry Pi bluetooth helper. Sep 17 17:06:59 volumio-kitchen avahi-daemon[629]: Loading service file /services/volumio.service. Sep 17 17:06:59 volumio-kitchen avahi-daemon[629]: Network interface enumeration completed. Sep 17 17:06:59 volumio-kitchen avahi-daemon[629]: Server startup complete. Host name is volumio-kitchen.local. Local service cookie is 528831448. Sep 17 17:06:59 volumio-kitchen avahi-daemon[629]: Service "Volumio Kitchen" (/services/volumio.service) successfully established. Sep 17 17:06:59 volumio-kitchen systemd[1]: Started Raise network interfaces. Sep 17 17:06:59 volumio-kitchen systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 17 17:06:59 volumio-kitchen systemd[1]: Started WPA supplicant. Sep 17 17:06:59 volumio-kitchen systemd[1]: Reached target Network. Sep 17 17:06:59 volumio-kitchen kernel: hwmon hwmon1: Voltage normalised Sep 17 17:06:59 volumio-kitchen systemd[1]: Starting Permit User Sessions... Sep 17 17:06:59 volumio-kitchen systemd[1]: Starting Network Time Service... Sep 17 17:06:59 volumio-kitchen systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Sep 17 17:06:59 volumio-kitchen systemd[1]: Reached target Network is Online. Sep 17 17:06:59 volumio-kitchen systemd[1]: Starting /etc/rc.local Compatibility... Sep 17 17:06:59 volumio-kitchen systemd[1]: Starting LSB: Brings up/down network automatically... Sep 17 17:06:59 volumio-kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:06:59 volumio-kitchen systemd[1]: Starting Samba NMB Daemon... Sep 17 17:06:59 volumio-kitchen systemd[1]: Starting Bluetooth service... Sep 17 17:06:59 volumio-kitchen systemd[1]: Started /etc/rc.local Compatibility. Sep 17 17:06:59 volumio-kitchen systemd[1]: Started Permit User Sessions. Sep 17 17:06:59 volumio-kitchen volumio-remote-updater[649]: Error: No active session Sep 17 17:06:59 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:06:59] [info] asio async_connect error: system:111 (Connection refused) Sep 17 17:06:59 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:06:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 17 17:06:59 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:06:59] [error] handle_connect error: Underlying Transport Error Sep 17 17:06:59 volumio-kitchen systemd[1]: iptables.service: Succeeded. Sep 17 17:07:00 volumio-kitchen volumio[620]: Could not open config: /tmp/upmpdcli.conf Sep 17 17:07:00 volumio-kitchen systemd[1]: Starting Hold until boot process finishes up... Sep 17 17:07:00 volumio-kitchen systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 17 17:07:00 volumio-kitchen systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 17 17:07:00 volumio-kitchen systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 17 17:07:00 volumio-kitchen systemd[1]: Received SIGRTMIN+21 from PID 207 (plymouthd). Sep 17 17:07:00 volumio-kitchen systemd[1]: Received SIGRTMIN+21 from PID 207 (plymouthd). Sep 17 17:07:00 volumio-kitchen systemd[1]: plymouth-quit.service: Succeeded. Sep 17 17:07:00 volumio-kitchen systemd[1]: Started Terminate Plymouth Boot Screen. Sep 17 17:07:00 volumio-kitchen ntpd[715]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 17 17:07:00 volumio-kitchen ntpd[715]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Sep 17 17:07:00 volumio-kitchen ifplugd[718]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Sep 17 17:07:00 volumio-kitchen systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 17 17:07:00 volumio-kitchen systemd[1]: Started Hold until boot process finishes up. Sep 17 17:07:00 volumio-kitchen systemd[1]: Started Network Time Service. Sep 17 17:07:00 volumio-kitchen ntpd[757]: proto: precision = 2.130 usec (-19) Sep 17 17:07:00 volumio-kitchen ifplugd(eth0)[771]: ifplugd 0.28 initializing. Sep 17 17:07:00 volumio-kitchen ifplugd(eth0)[771]: Using interface eth0/DC:A6:32:AD:76:19 with driver (version: 6.1.69-v7l+) Sep 17 17:07:00 volumio-kitchen ifplugd(eth0)[771]: Using detection mode: SIOCETHTOOL Sep 17 17:07:00 volumio-kitchen ifplugd(eth0)[771]: Initialization complete, link beat not detected. Sep 17 17:07:00 volumio-kitchen ntpd[757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 17 17:07:00 volumio-kitchen ntpd[757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 17 17:07:00 volumio-kitchen systemd[1]: Started Getty on tty1. Sep 17 17:07:00 volumio-kitchen systemd[1]: Reached target Login Prompts. Sep 17 17:07:00 volumio-kitchen ntpd[757]: Listen and drop on 0 v6wildcard [::]:123 Sep 17 17:07:00 volumio-kitchen ntpd[757]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 17 17:07:00 volumio-kitchen ntpd[757]: Listen normally on 2 lo 127.0.0.1:123 Sep 17 17:07:00 volumio-kitchen loadcpufreq[672]: Loading cpufreq kernel modules...done (none). Sep 17 17:07:00 volumio-kitchen ntpd[757]: Listening on routing socket on fd #19 for interface updates Sep 17 17:07:00 volumio-kitchen ntpd[757]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 17 17:07:00 volumio-kitchen ntpd[757]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 17 17:07:00 volumio-kitchen systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 17 17:07:00 volumio-kitchen systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 17 17:07:00 volumio-kitchen ifplugd[718]: start eth0...done. Sep 17 17:07:00 volumio-kitchen systemd[1]: Started LSB: Brings up/down network automatically. Sep 17 17:07:00 volumio-kitchen bluetoothd[727]: Bluetooth daemon 5.50 Sep 17 17:07:00 volumio-kitchen bluetoothd[727]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 17 17:07:00 volumio-kitchen systemd[1]: Started Bluetooth service. Sep 17 17:07:00 volumio-kitchen systemd[1]: Reached target Bluetooth. Sep 17 17:07:00 volumio-kitchen bluetoothd[727]: Starting SDP server Sep 17 17:07:00 volumio-kitchen bluetoothd[727]: Excluding (cli) sap Sep 17 17:07:00 volumio-kitchen bluetoothd[727]: Bluetooth management interface 1.22 initialized Sep 17 17:07:00 volumio-kitchen dbus-daemon[636]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=727 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 17 17:07:00 volumio-kitchen kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 17 17:07:00 volumio-kitchen kernel: Bluetooth: BNEP filters: protocol multicast Sep 17 17:07:00 volumio-kitchen kernel: Bluetooth: BNEP socket layer initialized Sep 17 17:07:00 volumio-kitchen kernel: Bluetooth: MGMT ver 1.22 Sep 17 17:07:00 volumio-kitchen cpufrequtils[775]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Sep 17 17:07:00 volumio-kitchen bluetoothd[727]: Failed to set privacy: Rejected (0x0b) Sep 17 17:07:00 volumio-kitchen systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 17 17:07:00 volumio-kitchen systemd[1]: Starting Hostname Service... Sep 17 17:07:01 volumio-kitchen dbus-daemon[636]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 17 17:07:01 volumio-kitchen systemd[1]: Started Hostname Service. Sep 17 17:07:01 volumio-kitchen ntpd[757]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 17 17:07:01 volumio-kitchen nmbd[731]: [2024/09/17 17:07:01.463105, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 17 17:07:01 volumio-kitchen nmbd[731]: started asyncdns process 798 Sep 17 17:07:01 volumio-kitchen nmbd[731]: [2024/09/17 17:07:01.469027, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 17 17:07:01 volumio-kitchen nmbd[731]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 17 17:07:01 volumio-kitchen nmbd[731]: [2024/09/17 17:07:01.470108, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 17 17:07:01 volumio-kitchen nmbd[731]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 17 17:07:01 volumio-kitchen kernel: hwmon hwmon1: Undervoltage detected! Sep 17 17:07:01 volumio-kitchen haveged[559]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Sep 17 17:07:01 volumio-kitchen haveged[559]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Sep 17 17:07:01 volumio-kitchen haveged[559]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00011 Sep 17 17:07:01 volumio-kitchen haveged[559]: haveged: fills: 0, generated: 0 Sep 17 17:07:02 volumio-kitchen wireless.js[641]: Cleaning previous... Sep 17 17:07:02 volumio-kitchen ntpd[757]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 17 17:07:02 volumio-kitchen sudo[806]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 17 17:07:02 volumio-kitchen sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:02 volumio-kitchen sudo[806]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:02 volumio-kitchen sudo[808]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 17 17:07:02 volumio-kitchen sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:02 volumio-kitchen systemd[1]: systemd-rfkill.service: Succeeded. Sep 17 17:07:02 volumio-kitchen sudo[808]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:02 volumio-kitchen wireless.js[641]: Stopped aP Sep 17 17:07:02 volumio-kitchen sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 17 17:07:02 volumio-kitchen sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:03 volumio-kitchen kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 17:07:03 volumio-kitchen sudo[815]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:03 volumio-kitchen sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 17 17:07:03 volumio-kitchen sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:03 volumio-kitchen sudo[817]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:03 volumio-kitchen sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 17 17:07:03 volumio-kitchen sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:03 volumio-kitchen sudo[824]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:03 volumio-kitchen sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 17 17:07:03 volumio-kitchen sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:03 volumio-kitchen ntpd[757]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 17 17:07:04 volumio-kitchen bthelper[665]: Changing power off succeeded Sep 17 17:07:04 volumio-kitchen ntpd[757]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 17 17:07:04 volumio-kitchen bthelper[665]: [CHG] Controller DC:A6:32:AD:76:1B Class: 0x0000041c Sep 17 17:07:04 volumio-kitchen bthelper[665]: Changing power on succeeded Sep 17 17:07:04 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:04] [info] asio async_connect error: system:111 (Connection refused) Sep 17 17:07:04 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 17 17:07:04 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:04] [error] handle_connect error: Underlying Transport Error Sep 17 17:07:05 volumio-kitchen sudo[826]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:05 volumio-kitchen wireless.js[641]: SETTING APPROPRIATE REG DOMAIN: DE Sep 17 17:07:05 volumio-kitchen sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 17 17:07:05 volumio-kitchen sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:05 volumio-kitchen sudo[837]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:05 volumio-kitchen sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Sep 17 17:07:05 volumio-kitchen sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:05 volumio-kitchen sudo[839]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:05 volumio-kitchen wireless.js[641]: SUCCESSFULLY SET NEW REGDOMAIN: DE Sep 17 17:07:05 volumio-kitchen wireless.js[641]: Start wireless flow Sep 17 17:07:05 volumio-kitchen wireless.js[641]: Stopped hotspot (if there).. Sep 17 17:07:05 volumio-kitchen sudo[844]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 17 17:07:05 volumio-kitchen sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:05 volumio-kitchen sudo[844]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:05 volumio-kitchen sudo[846]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 17 17:07:05 volumio-kitchen sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:05 volumio-kitchen kernel: hwmon hwmon1: Voltage normalised Sep 17 17:07:06 volumio-kitchen sudo[846]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:06 volumio-kitchen wireless.js[641]: DHCP IP Sep 17 17:07:06 volumio-kitchen wireless.js[641]: Start ap Sep 17 17:07:06 volumio-kitchen wpa_supplicant[848]: Successfully initialized wpa_supplicant Sep 17 17:07:06 volumio-kitchen sudo[849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 17 17:07:06 volumio-kitchen sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:06 volumio-kitchen kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 17:07:06 volumio-kitchen dhcpcd[850]: dev: loaded udev Sep 17 17:07:06 volumio-kitchen dhcpcd-run-hooks[868]: wlan0: starting wpa_supplicant Sep 17 17:07:06 volumio-kitchen dhcpcd-run-hooks[873]: wlan0: failed to start wpa_supplicant Sep 17 17:07:06 volumio-kitchen dhcpcd-run-hooks[874]: 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 Sep 17 17:07:06 volumio-kitchen dhcpcd[850]: wlan0: connected to Access Point `' Sep 17 17:07:06 volumio-kitchen dhcpcd[850]: no interfaces have a carrier Sep 17 17:07:06 volumio-kitchen dhcpcd[850]: forked to background, child pid 884 Sep 17 17:07:06 volumio-kitchen dhcpcd[884]: eth0: waiting for carrier Sep 17 17:07:06 volumio-kitchen dhcpcd[884]: wlan0: waiting for carrier Sep 17 17:07:06 volumio-kitchen sudo[849]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:07 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:07 volumio-kitchen sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:07 volumio-kitchen sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:07 volumio-kitchen sudo[886]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:07 volumio-kitchen kernel: hwmon hwmon1: Undervoltage detected! Sep 17 17:07:08 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:08 volumio-kitchen sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:08 volumio-kitchen sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:08 volumio-kitchen sudo[889]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:08 volumio-kitchen wpa_supplicant[872]: wlan0: Trying to associate with SSID 'Y&B-2GHz' Sep 17 17:07:09 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:09 volumio-kitchen sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:09 volumio-kitchen sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:09 volumio-kitchen sudo[892]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:10 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:10 volumio-kitchen sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:10 volumio-kitchen sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:10 volumio-kitchen sudo[895]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:11 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:11 volumio-kitchen sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:11 volumio-kitchen sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: wlan0: carrier acquired Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: wlan0: connected to Access Point `Y&B-2GHz' Sep 17 17:07:11 volumio-kitchen wpa_supplicant[872]: wlan0: Associated with ec:6c:9a:62:e7:9c Sep 17 17:07:11 volumio-kitchen wpa_supplicant[872]: wlan0: CTRL-EVENT-CONNECTED - Connection to ec:6c:9a:62:e7:9c completed [id=0 id_str=] Sep 17 17:07:11 volumio-kitchen wpa_supplicant[872]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 17 17:07:11 volumio-kitchen sudo[898]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: DUID 00:01:00:01:2e:7c:59:28:dc:a6:32:ad:76:1a Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: wlan0: IAID 32:ad:76:1a Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: wlan0: adding address fe80::8efc:c59a:ad99:2dc8 Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: ipv6_addaddr1: Permission denied Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: wlan0: carrier lost Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: wlan0: carrier acquired Sep 17 17:07:11 volumio-kitchen dhcpcd[884]: wlan0: IAID 32:ad:76:1a Sep 17 17:07:12 volumio-kitchen dhcpcd[884]: wlan0: rebinding lease of 192.168.1.36 Sep 17 17:07:12 volumio-kitchen dhcpcd[884]: wlan0: soliciting an IPv6 router Sep 17 17:07:12 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:12 volumio-kitchen sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:12 volumio-kitchen sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:12 volumio-kitchen sudo[913]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:12 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:12] [info] asio async_connect error: system:111 (Connection refused) Sep 17 17:07:12 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 17 17:07:12 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:12] [error] handle_connect error: Underlying Transport Error Sep 17 17:07:12 volumio-kitchen dhcpcd[884]: wlan0: probing address 192.168.1.36/24 Sep 17 17:07:13 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:13 volumio-kitchen sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:13 volumio-kitchen sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:13 volumio-kitchen sudo[916]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:14 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:14 volumio-kitchen sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:14 volumio-kitchen sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:14 volumio-kitchen sudo[919]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:15 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:15 volumio-kitchen sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:15 volumio-kitchen sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:15 volumio-kitchen sudo[922]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:16 volumio-kitchen kernel: hwmon hwmon1: Voltage normalised Sep 17 17:07:16 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:16 volumio-kitchen sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:16 volumio-kitchen sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:16 volumio-kitchen sudo[926]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:17 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:17 volumio-kitchen sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:17 volumio-kitchen sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:17 volumio-kitchen sudo[929]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:17 volumio-kitchen dhcpcd[884]: wlan0: leased 192.168.1.36 for 86400 seconds Sep 17 17:07:17 volumio-kitchen avahi-daemon[629]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.36. Sep 17 17:07:17 volumio-kitchen dhcpcd[884]: wlan0: adding route to 192.168.1.0/24 Sep 17 17:07:17 volumio-kitchen dhcpcd[884]: wlan0: adding default route via 192.168.1.1 Sep 17 17:07:17 volumio-kitchen avahi-daemon[629]: New relevant interface wlan0.IPv4 for mDNS. Sep 17 17:07:17 volumio-kitchen avahi-daemon[629]: Registering new address record for 192.168.1.36 on wlan0.IPv4. Sep 17 17:07:18 volumio-kitchen wireless.js[641]: trying... Sep 17 17:07:18 volumio-kitchen sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 17:07:18 volumio-kitchen sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:18 volumio-kitchen sudo[952]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:18 volumio-kitchen wireless.js[641]: Connected to: ----Y&B-2GHz Sep 17 17:07:18 volumio-kitchen wireless.js[641]: ---- Sep 17 17:07:18 volumio-kitchen sudo[955]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 17:07:18 volumio-kitchen sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:18 volumio-kitchen sudo[955]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:18 volumio-kitchen wireless.js[641]: ... joined AP, wlan0 IPv4 is 192.168.1.36, ipV6 is undefined Sep 17 17:07:18 volumio-kitchen wireless.js[641]: It's done! AP Sep 17 17:07:18 volumio-kitchen systemd[1]: Started Wireless Services. Sep 17 17:07:18 volumio-kitchen kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 17:07:18 volumio-kitchen systemd[1]: Started Volumio Backend Module. Sep 17 17:07:18 volumio-kitchen systemd[1]: Started Volumio Cpu Tweaker. Sep 17 17:07:18 volumio-kitchen volumio-cpu-tweak[961]: Setting RT Priority for mpd Sep 17 17:07:18 volumio-kitchen volumio-cpu-tweak[961]: pid 35's current scheduling policy: SCHED_OTHER Sep 17 17:07:18 volumio-kitchen volumio-cpu-tweak[961]: pid 35's current scheduling priority: 0 Sep 17 17:07:18 volumio-kitchen volumio-cpu-tweak[961]: Setting MPD Affinity Sep 17 17:07:18 volumio-kitchen volumio-cpu-tweak[961]: pid 3's current affinity mask: f Sep 17 17:07:18 volumio-kitchen volumio-cpu-tweak[961]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 17 17:07:18 volumio-kitchen volumio-cpu-tweak[961]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 17 17:07:18 volumio-kitchen systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 17 17:07:19 volumio-kitchen ntpd[757]: Listen normally on 3 wlan0 192.168.1.36:123 Sep 17 17:07:19 volumio-kitchen ntpd[757]: new interface(s) found: waking up resolver Sep 17 17:07:20 volumio-kitchen kernel: hwmon hwmon1: Undervoltage detected! Sep 17 17:07:20 volumio-kitchen volumio[960]: info: ------------------------------------------- Sep 17 17:07:20 volumio-kitchen volumio[960]: info: ----- Volumio3 ---- Sep 17 17:07:20 volumio-kitchen volumio[960]: info: ------------------------------------------- Sep 17 17:07:20 volumio-kitchen volumio[960]: info: ----- System startup ---- Sep 17 17:07:20 volumio-kitchen volumio[960]: info: ------------------------------------------- Sep 17 17:07:21 volumio-kitchen systemd[1]: Started Samba NMB Daemon. Sep 17 17:07:21 volumio-kitchen nmbd[731]: [2024/09/17 17:07:21.485557, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 17 17:07:21 volumio-kitchen nmbd[731]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 17 17:07:21 volumio-kitchen systemd[1]: Starting Samba Winbind Daemon... Sep 17 17:07:22 volumio-kitchen winbindd[986]: [2024/09/17 17:07:22.017465, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 17 17:07:22 volumio-kitchen winbindd[986]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 17 17:07:22 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:22] [connect] Successful connection Sep 17 17:07:22 volumio-kitchen winbindd[986]: [2024/09/17 17:07:22.485820, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 17 17:07:22 volumio-kitchen winbindd[986]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 17 17:07:22 volumio-kitchen systemd[1]: Started Samba Winbind Daemon. Sep 17 17:07:22 volumio-kitchen systemd[1]: Starting Samba SMB Daemon... Sep 17 17:07:22 volumio-kitchen volumio[960]: info: MYVOLUMIO Environment detected Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Plugin folders cleanup Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning into folder /volumio/app/plugins/ Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning category audio_interface Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning category miscellanea Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning category music_service Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning category plugins.json Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning category system_controller Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning category user_interface Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning into folder /data/plugins/ Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning category audio_interface Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Scanning category music_service Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Plugin folders cleanup completed Sep 17 17:07:23 volumio-kitchen volumio[960]: info: ------------------------------------------- Sep 17 17:07:23 volumio-kitchen volumio[960]: info: ----- Core plugins startup ---- Sep 17 17:07:23 volumio-kitchen volumio[960]: info: ------------------------------------------- Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Loading plugins from folder /volumio/app/plugins/ Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Adding plugin upnp to MyMusic Plugins Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Loading plugins from folder /data/plugins/ Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Loading plugin "system"... Sep 17 17:07:23 volumio-kitchen smbd[991]: [2024/09/17 17:07:23.193110, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 17 17:07:23 volumio-kitchen systemd[1]: Started Samba SMB Daemon. Sep 17 17:07:23 volumio-kitchen systemd[1]: Reached target Multi-User System. Sep 17 17:07:23 volumio-kitchen systemd[1]: Reached target Graphical Interface. Sep 17 17:07:23 volumio-kitchen smbd[991]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 17 17:07:23 volumio-kitchen volumio[960]: info: Loading plugin "appearance"... Sep 17 17:07:23 volumio-kitchen systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 17 17:07:23 volumio-kitchen systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 17 17:07:23 volumio-kitchen systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 17 17:07:23 volumio-kitchen systemd[1]: Startup finished in 18.241s (kernel) + 31.102s (userspace) = 49.343s. Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "network"... Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Refreshing Cached IP Addresses Sep 17 17:07:24 volumio-kitchen sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 17:07:24 volumio-kitchen sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:24 volumio-kitchen sudo[1006]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "services"... Sep 17 17:07:24 volumio-kitchen sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 17:07:24 volumio-kitchen sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "alsa_controller"... Sep 17 17:07:24 volumio-kitchen sudo[1008]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:24 volumio-kitchen sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 17 17:07:24 volumio-kitchen sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:24 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "wizard"... Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "networkfs"... Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Starting Udev Watcher for removable devices Sep 17 17:07:24 volumio-kitchen sudo[1017]: 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.35/USB /mnt/NAS/USB Sep 17 17:07:24 volumio-kitchen sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Ignoring mount for partition: boot Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Ignoring mount for partition: volumio Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Ignoring mount for partition: volumio_data Sep 17 17:07:24 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "volumio_command_line_client"... Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "upnp"... Sep 17 17:07:24 volumio-kitchen volumio[960]: info: [1726585644963] Starting Upmpd Daemon Sep 17 17:07:24 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "my_music"... Sep 17 17:07:24 volumio-kitchen volumio[960]: info: Loading plugin "mpd"... Sep 17 17:07:25 volumio-kitchen kernel: Key type cifs.spnego registered Sep 17 17:07:25 volumio-kitchen kernel: Key type cifs.idmap registered Sep 17 17:07:25 volumio-kitchen 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. Sep 17 17:07:25 volumio-kitchen kernel: CIFS: Attempting to mount \\192.168.1.35\USB Sep 17 17:07:25 volumio-kitchen sudo[1017]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:25 volumio-kitchen volumio[960]: info: Loading plugin "upnp_browser"... Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Loading plugin "alarm-clock"... Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Loading plugin "airplay_emulation"... Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Starting Shairport Sync Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Loading plugin "last_100"... Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Loading plugin "webradio"... Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Loading plugin "i2s_dacs"... Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Loading plugin "volumiodiscovery"... Sep 17 17:07:26 volumio-kitchen volumio[960]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 17 17:07:26 volumio-kitchen node[960]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 17 17:07:26 volumio-kitchen volumio[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 17:07:26 volumio-kitchen node[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 17:07:26 volumio-kitchen volumio[960]: *** WARNING *** For more information see Sep 17 17:07:26 volumio-kitchen node[960]: *** WARNING *** For more information see Sep 17 17:07:26 volumio-kitchen volumio[960]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 17 17:07:26 volumio-kitchen node[960]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 17 17:07:26 volumio-kitchen volumio[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 17:07:26 volumio-kitchen node[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 17:07:26 volumio-kitchen volumio[960]: *** WARNING *** For more information see Sep 17 17:07:26 volumio-kitchen node[960]: *** WARNING *** For more information see Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 17 17:07:26 volumio-kitchen volumio[960]: info: Discovery: Started advertising with name: Volumio Kitchen Sep 17 17:07:27 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 17:07:27 volumio-kitchen volumio[960]: info: Loading plugin "spop"... Sep 17 17:07:27 volumio-kitchen systemd[1]: systemd-fsckd.service: Succeeded. Sep 17 17:07:28 volumio-kitchen sudo[1011]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:28 volumio-kitchen sh[524]: timed out Sep 17 17:07:28 volumio-kitchen dhcpcd[570]: timed out Sep 17 17:07:28 volumio-kitchen sh[524]: dhcpcd exited Sep 17 17:07:28 volumio-kitchen dhcpcd[570]: dhcpcd exited Sep 17 17:07:28 volumio-kitchen sh[524]: ifup: failed to bring up eth0 Sep 17 17:07:28 volumio-kitchen systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 17 17:07:28 volumio-kitchen systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 17 17:07:28 volumio-kitchen volumio[960]: info: Loading plugin "ytcr"... Sep 17 17:07:31 volumio-kitchen systemd[1]: systemd-hostnamed.service: Succeeded. Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Loading plugin "outputs"... Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Loading plugin "albumart"... Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Plugin example_plugin is not enabled Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Loading plugin "inputs"... Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Loading plugin "updater_comm"... Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Plugin mpdemulation is not enabled Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Loading plugin "rest_api"... Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Loading plugin "websocket"... Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Starting Socket.io Server version 2.3.0 Sep 17 17:07:32 volumio-kitchen volumio[960]: info: Loading plugin "fusiondsp"... Sep 17 17:07:32 volumio-kitchen volumio[960]: Forking 3 albumart workers Sep 17 17:07:33 volumio-kitchen volumio[960]: info: Applying required configuration parameters for plugin fusiondsp Sep 17 17:07:33 volumio-kitchen volumio[960]: info: Loading i18n strings for locale en Sep 17 17:07:33 volumio-kitchen volumio[960]: Updating browse sources language Sep 17 17:07:33 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::initPlayerControls Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: Express server listening on port 3000 Sep 17 17:07:34 volumio-kitchen volumio[960]: [Metrics] WebUI: 14s 596.72ms Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreStateMachine::resetVolumioState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreStateMachine::getcurrentVolume Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreStateMachine::pushState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CorePlayQueue::getTrack 0 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioPushState Sep 17 17:07:34 volumio-kitchen volumio[960]: Starting albumart workers Sep 17 17:07:34 volumio-kitchen volumio[960]: Starting albumart workers Sep 17 17:07:34 volumio-kitchen volumio[960]: Starting albumart workers Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 17 17:07:34 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Reloading queue from file Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreStateMachine::setRepeat null single undefined Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreStateMachine::pushState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CorePlayQueue::getTrack 0 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioPushState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreStateMachine::setRandom null Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreStateMachine::pushState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CorePlayQueue::getTrack 0 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioPushState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Setting Device type: Raspberry PI Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Completed loading Core Plugins Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Preparing to generate the ALSA configuration file Sep 17 17:07:34 volumio-kitchen volumio[960]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 17 17:07:34 volumio-kitchen volumio[960]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03112 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 17 17:07:34 volumio-kitchen volumio[960]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Reading ALSA contributions from plugins. Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioGetState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CorePlayQueue::getTrack 0 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioGetState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CorePlayQueue::getTrack 0 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Discovery: adding d06fbe2b-3b7b-400e-bc7b-3a8e0c3dfd06 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Discovery: Found device Volumio Kitchen Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioGetState Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CorePlayQueue::getTrack 0 Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Asound.conf file unchanged, so no further update is needed Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Output device has changed, restarting MPD Sep 17 17:07:34 volumio-kitchen volumio[960]: info: Output device has changed, restarting Shairport Sync Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:34 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:35 volumio-kitchen sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 17:07:35 volumio-kitchen sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:35 volumio-kitchen sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 17:07:35 volumio-kitchen sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:35 volumio-kitchen sudo[1085]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 17:07:35 volumio-kitchen volumio[960]: info: ___________ START PLUGINS ___________ Sep 17 17:07:35 volumio-kitchen volumio[960]: info: ControllerMpd::onStart: Initializing MPD Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Creating MPD Configuration file Sep 17 17:07:35 volumio-kitchen systemd[1]: Listening on mpd.socket. Sep 17 17:07:35 volumio-kitchen systemd[1]: Starting Music Player Daemon... Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 17:07:35 volumio-kitchen volumio[960]: info: [1726585655283] CoreMusicLibrary::Adding element Media Servers Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 17:07:35 volumio-kitchen sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 17:07:35 volumio-kitchen sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:35 volumio-kitchen sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 17:07:35 volumio-kitchen sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:35 volumio-kitchen sudo[1114]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:35 volumio-kitchen systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 17 17:07:35 volumio-kitchen systemd[1]: mpd.service: Succeeded. Sep 17 17:07:35 volumio-kitchen systemd[1]: Stopped Music Player Daemon. Sep 17 17:07:35 volumio-kitchen systemd[1]: Starting Music Player Daemon... Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 17:07:35 volumio-kitchen volumio[960]: info: [1726585655563] CoreMusicLibrary::Adding element Last_100 Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 17:07:35 volumio-kitchen volumio[960]: info: [1726585655567] CoreMusicLibrary::Adding element Webradio Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Initializing BBC Radios Sep 17 17:07:35 volumio-kitchen sudo[1126]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 17 17:07:35 volumio-kitchen sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:35 volumio-kitchen sudo[1126]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 17 17:07:35 volumio-kitchen sudo[1126]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Creating Spotify config file Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Loading i18n strings for locale en Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 17 17:07:35 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:35 volumio-kitchen volumio[960]: info: FusionDsp - mixtype--------------------- None Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Volumio Calling Home Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Preparing to generate the ALSA configuration file Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Discovery: adding 9a5587c1-41ba-495f-aa0a-53934c8f046c Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Discovery: Found device Volumio Livingroom Sep 17 17:07:35 volumio-kitchen sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 17:07:35 volumio-kitchen sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Discovery: Connecting to remote: 169.254.211.55 Sep 17 17:07:35 volumio-kitchen volumio[960]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Reading ALSA contributions from plugins. Sep 17 17:07:35 volumio-kitchen sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 17:07:35 volumio-kitchen sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:35 volumio-kitchen sudo[1148]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:35 volumio-kitchen volumio[960]: info: MPD Permissions set Sep 17 17:07:35 volumio-kitchen volumio[960]: info: MPD Permissions set Sep 17 17:07:35 volumio-kitchen sudo[1150]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:35 volumio-kitchen volumio[960]: info: Spotify config file written Sep 17 17:07:36 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Sep 17 17:07:36 volumio-kitchen volumio[960]: info: No need to fix Spotify hosts Sep 17 17:07:36 volumio-kitchen sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 17 17:07:36 volumio-kitchen sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:36 volumio-kitchen volumio[960]: info: An error occurred while refreshing Spotify Token Error: certificate is not yet valid Sep 17 17:07:36 volumio-kitchen systemd[1]: Started go-librespot Daemon. Sep 17 17:07:36 volumio-kitchen go-librespot[1161]: Librespot-go daemon starting... Sep 17 17:07:36 volumio-kitchen sudo[1154]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:36 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (uv9uafuvgl0tsh1v277bgqiuca): Sep 17 17:07:36 volumio-kitchen volumio[960]: (ConnectionError) (YouTube) Connection error in getting lounge token Sep 17 17:07:36 volumio-kitchen volumio[960]: Error info: { Sep 17 17:07:36 volumio-kitchen volumio[960]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Sep 17 17:07:36 volumio-kitchen volumio[960]: } Sep 17 17:07:36 volumio-kitchen volumio[960]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: certificate is not yet valid Sep 17 17:07:36 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:36 volumio-kitchen volumio[960]: ConnectionError: (YouTube) Connection error in getting lounge token Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Sep 17 17:07:36 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async Promise.all (index 0) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Sep 17 17:07:36 volumio-kitchen volumio[960]: Going to generate fresh screen Id and try again... Sep 17 17:07:36 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (1v55huph9iiaovfe1kojepbv5d): Sep 17 17:07:36 volumio-kitchen volumio[960]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Sep 17 17:07:36 volumio-kitchen volumio[960]: Error info: { Sep 17 17:07:36 volumio-kitchen volumio[960]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Sep 17 17:07:36 volumio-kitchen volumio[960]: } Sep 17 17:07:36 volumio-kitchen volumio[960]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: certificate is not yet valid Sep 17 17:07:36 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:36 volumio-kitchen volumio[960]: ConnectionError: (YouTube Music) Connection error in getting lounge token Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Sep 17 17:07:36 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async Promise.all (index 1) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Sep 17 17:07:36 volumio-kitchen volumio[960]: Going to generate fresh screen Id and try again... Sep 17 17:07:36 volumio-kitchen volumio[960]: info: Volumio called home Sep 17 17:07:36 volumio-kitchen volumio[960]: info: Starting Shairport Sync Sep 17 17:07:36 volumio-kitchen volumio[960]: info: Starting Shairport Sync Sep 17 17:07:36 volumio-kitchen sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 17:07:36 volumio-kitchen sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:36 volumio-kitchen volumio[960]: info: Starting Shairport Sync Sep 17 17:07:36 volumio-kitchen sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 17:07:36 volumio-kitchen sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:36 volumio-kitchen systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 17:07:36 volumio-kitchen systemd[1]: shairport-sync.service: Succeeded. Sep 17 17:07:36 volumio-kitchen systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 17:07:36 volumio-kitchen sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 17 17:07:36 volumio-kitchen sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:36 volumio-kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CoreCommandRouter::volumioGetState Sep 17 17:07:36 volumio-kitchen volumio[960]: info: CorePlayQueue::getTrack 0 Sep 17 17:07:36 volumio-kitchen sudo[1172]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:36 volumio-kitchen sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 17:07:36 volumio-kitchen sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:36 volumio-kitchen systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 17:07:36 volumio-kitchen systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 17 17:07:36 volumio-kitchen systemd[1]: shairport-sync.service: Succeeded. Sep 17 17:07:36 volumio-kitchen systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 17:07:36 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:36+02:00" level=info msg="generated new device id: 10be06129b571055be5a6c084a16fa3713da8caa" Sep 17 17:07:36 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Sep 17 17:07:36 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:36 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:36 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:36 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:36 volumio-kitchen volumio[960]: } Sep 17 17:07:36 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:36 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:36 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:36 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:36 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:36 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:36 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:36 volumio-kitchen volumio[960]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Sep 17 17:07:36 volumio-kitchen volumio[960]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Sep 17 17:07:36 volumio-kitchen volumio[960]: at new Promise () Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Sep 17 17:07:36 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async Promise.all (index 1) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Sep 17 17:07:36 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:36+02:00" level=debug msg="stored credentials found for basil85" Sep 17 17:07:36 volumio-kitchen volumio[960]: error: [yt-cast-receiver] Failed to start receiver: Sep 17 17:07:36 volumio-kitchen volumio[960]: (AppError) Failed to start YouTubeApp Sep 17 17:07:36 volumio-kitchen volumio[960]: --->(SessionError) (YouTube Music) Failed to establish session Sep 17 17:07:36 volumio-kitchen volumio[960]: ------>(ConnectionError) (YouTube Music) Connection error in generating screen Id Sep 17 17:07:36 volumio-kitchen volumio[960]: Error info: { Sep 17 17:07:36 volumio-kitchen volumio[960]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Sep 17 17:07:36 volumio-kitchen volumio[960]: } Sep 17 17:07:36 volumio-kitchen volumio[960]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: certificate is not yet valid Sep 17 17:07:36 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:36 volumio-kitchen volumio[960]: AppError: Failed to start YouTubeApp Sep 17 17:07:36 volumio-kitchen volumio[960]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Sep 17 17:07:36 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Sep 17 17:07:36 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Sep 17 17:07:36 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:36 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:36 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:36 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:36 volumio-kitchen volumio[960]: } Sep 17 17:07:36 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:36 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:36 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:36 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:36 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:36 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:36 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:36 volumio-kitchen volumio[960]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Sep 17 17:07:36 volumio-kitchen volumio[960]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Sep 17 17:07:36 volumio-kitchen volumio[960]: at new Promise () Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Sep 17 17:07:36 volumio-kitchen volumio[960]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Sep 17 17:07:36 volumio-kitchen volumio[960]: at Array.forEach () Sep 17 17:07:36 volumio-kitchen volumio[960]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Sep 17 17:07:36 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Sep 17 17:07:36 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Sep 17 17:07:36 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:36 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:36 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:36 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:36 volumio-kitchen volumio[960]: } Sep 17 17:07:36 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:36 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:36 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:36 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:36 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:36 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:36 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:36 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:36 volumio-kitchen volumio[960]: error: [ytcr] Failed to start plugin: Sep 17 17:07:36 volumio-kitchen volumio[960]: (AppError) Failed to start YouTubeApp Sep 17 17:07:36 volumio-kitchen volumio[960]: --->(SessionError) (YouTube Music) Failed to establish session Sep 17 17:07:36 volumio-kitchen volumio[960]: ------>(ConnectionError) (YouTube Music) Connection error in generating screen Id Sep 17 17:07:36 volumio-kitchen volumio[960]: Error info: { Sep 17 17:07:36 volumio-kitchen volumio[960]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Sep 17 17:07:36 volumio-kitchen volumio[960]: } Sep 17 17:07:36 volumio-kitchen volumio[960]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: certificate is not yet valid Sep 17 17:07:36 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:36 volumio-kitchen volumio[960]: AppError: Failed to start YouTubeApp Sep 17 17:07:36 volumio-kitchen volumio[960]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Sep 17 17:07:36 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Sep 17 17:07:36 volumio-kitchen systemd[1]: Started UPnP Renderer front-end to MPD. Sep 17 17:07:36 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Sep 17 17:07:36 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:36 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:36 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:36 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:36 volumio-kitchen volumio[960]: } Sep 17 17:07:36 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:36 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:36 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:36 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:36 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:36 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:36 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:36 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:36 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:36 volumio-kitchen volumio[960]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Sep 17 17:07:36 volumio-kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:07:36 volumio-kitchen sudo[1174]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:36 volumio-kitchen sudo[1177]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:36 volumio-kitchen volumio[960]: info: Asound.conf file unchanged, so no further update is needed Sep 17 17:07:36 volumio-kitchen volumio[960]: info: Output device has changed, restarting MPD Sep 17 17:07:36 volumio-kitchen systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 17:07:36 volumio-kitchen systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 17 17:07:36 volumio-kitchen systemd[1]: shairport-sync.service: Succeeded. Sep 17 17:07:36 volumio-kitchen systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 17:07:37 volumio-kitchen volumio[960]: info: Output device has changed, restarting Shairport Sync Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:37 volumio-kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:07:37 volumio-kitchen sudo[1182]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:37 volumio-kitchen sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 17:07:37 volumio-kitchen sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:37 volumio-kitchen sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 17:07:37 volumio-kitchen sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:37 volumio-kitchen sudo[1188]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:37 volumio-kitchen systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 17 17:07:37 volumio-kitchen systemd[1]: mpd.service: Succeeded. Sep 17 17:07:37 volumio-kitchen systemd[1]: Stopped Music Player Daemon. Sep 17 17:07:37 volumio-kitchen volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 17:07:37 volumio-kitchen volumio[960]: info: Upmpdcli Daemon Started Sep 17 17:07:37 volumio-kitchen volumio[960]: info: Shairport-Sync Started Sep 17 17:07:37 volumio-kitchen volumio[960]: Error adding Membership: Error: addMembership EINVAL Sep 17 17:07:37 volumio-kitchen volumio[960]: info: Shairport-Sync Started Sep 17 17:07:37 volumio-kitchen systemd[1]: Starting Music Player Daemon... Sep 17 17:07:37 volumio-kitchen volumio[960]: info: MPD Permissions set Sep 17 17:07:37 volumio-kitchen volumio[960]: info: Shairport-Sync Started Sep 17 17:07:37 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Sep 17 17:07:37 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:37 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:37 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:37 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:37 volumio-kitchen volumio[960]: } Sep 17 17:07:37 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:37 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:37 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:37 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (Use `node --trace-warnings ...` to show where the warning was created) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:37] [connect] Successful connection Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen sudo[1198]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 17 17:07:37 volumio-kitchen sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:37 volumio-kitchen sudo[1198]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 17 17:07:37 volumio-kitchen sudo[1198]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:37 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Sep 17 17:07:37 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:37 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:37 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:37 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:37 volumio-kitchen volumio[960]: } Sep 17 17:07:37 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:37 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:37 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:37 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 9) Sep 17 17:07:37 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 17 17:07:37 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:37+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 17 17:07:37 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:37+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 17 17:07:37 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:37+02:00" level=debug msg="zeroconf server listening on port 35881" Sep 17 17:07:37 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Sep 17 17:07:37 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:37 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:37 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:37 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:37 volumio-kitchen volumio[960]: } Sep 17 17:07:37 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:37 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:37 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:37 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 11) Sep 17 17:07:37 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Sep 17 17:07:37 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:37 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:37 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:37 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:37 volumio-kitchen volumio[960]: } Sep 17 17:07:37 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:37 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:37 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:37 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 13) Sep 17 17:07:37 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:37+02:00" level=debug msg="obtained new client token: AABcOAWa7O2W34mgBKeEdBcMoHcuw7Az0E8bCHmevjSGyDdC8Nri2kp8+zTYHhE++tBbXCC2Qbr2EAbK6SWRfCJWrz4xh+9WzmrvaCsVqambyDI5vCcOh96okB8M7APAO2PqAxlSsyt/uI8qYhQ23y4pISsn7mDOpSwyHp+Rt+qiq2WB9OPwRCmZjIdlv/u2j0ih8K6lQO1phIwiXI13ybCOOdgYzc2wZZQPg2c6+f44kSG2+mjzTdZH+ANsjA==" Sep 17 17:07:37 volumio-kitchen volumio-remote-updater[649]: [2024-09-17 17:07:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1726585657 101 Sep 17 17:07:37 volumio-kitchen volumio[960]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 12 Sep 17 17:07:37 volumio-kitchen volumio[960]: info: Starting Shairport Sync Sep 17 17:07:37 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:37+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Sep 17 17:07:37 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Sep 17 17:07:37 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:37 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:37 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:37 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:37 volumio-kitchen volumio[960]: } Sep 17 17:07:37 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:37 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:37 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:37 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:37+02:00" level=debug msg="completed keyexchange" Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 15) Sep 17 17:07:37 volumio-kitchen sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 17:07:37 volumio-kitchen sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:37 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Sep 17 17:07:37 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:37 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:37 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:37 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:37 volumio-kitchen volumio[960]: } Sep 17 17:07:37 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:37 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:37 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:37 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 17) Sep 17 17:07:37 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Sep 17 17:07:37 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:37 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:37 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:37 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:37 volumio-kitchen volumio[960]: } Sep 17 17:07:37 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:37 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:37 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:37 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 19) Sep 17 17:07:37 volumio-kitchen systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 17:07:37 volumio-kitchen volumio[960]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Sep 17 17:07:37 volumio-kitchen volumio[960]: LoungeScreenDisconnected { Sep 17 17:07:37 volumio-kitchen volumio[960]: AID: null, Sep 17 17:07:37 volumio-kitchen volumio[960]: name: 'loungeScreenDisconnected', Sep 17 17:07:37 volumio-kitchen volumio[960]: payload: {} Sep 17 17:07:37 volumio-kitchen volumio[960]: } Sep 17 17:07:37 volumio-kitchen volumio[960]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 17 17:07:37 volumio-kitchen volumio[960]: Stack trace: Sep 17 17:07:37 volumio-kitchen volumio[960]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 17 17:07:37 volumio-kitchen volumio[960]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Sep 17 17:07:37 volumio-kitchen volumio[960]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen systemd[1]: shairport-sync.service: Succeeded. Sep 17 17:07:37 volumio-kitchen systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Sep 17 17:07:37 volumio-kitchen volumio[960]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Sep 17 17:07:37 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 17 17:07:37 volumio-kitchen volumio[960]: (node:960) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 21) Sep 17 17:07:37 volumio-kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:07:37 volumio-kitchen sudo[1207]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:37 volumio-kitchen volumio[960]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Sep 17 17:07:37 volumio-kitchen volumio[960]: info: camilladsp service started and running in background, instance 1 Sep 17 17:07:37 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:07:37 volumio-kitchen volumio[960]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 17 17:07:37 volumio-kitchen volumio[960]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 17 17:07:37 volumio-kitchen volumio[960]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 17 17:07:38 volumio-kitchen volumio[960]: info: FusionDsp loaded Sep 17 17:07:38 volumio-kitchen volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 17:07:38 volumio-kitchen sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 17 17:07:38 volumio-kitchen sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:07:38 volumio-kitchen volumio[960]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 17 17:07:38 volumio-kitchen volumio[960]: info: Adding Signal Path Element [object Object] Sep 17 17:07:38 volumio-kitchen volumio[960]: info: Adding fusiondspeq DSP Signal Path Element Sep 17 17:07:38 volumio-kitchen volumio[960]: info: FusionDsp - ---- installed callbackRead Sep 17 17:07:38 volumio-kitchen volumio[960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 17:07:38 volumio-kitchen volumio[960]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 17 17:07:38 volumio-kitchen volumio[960]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 17 17:07:38 volumio-kitchen volumio[960]: at onErrorNT (internal/child_process.js:465:16) Sep 17 17:07:38 volumio-kitchen volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 17 17:07:38 volumio-kitchen volumio[960]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 17 17:07:38 volumio-kitchen volumio[960]: at listOnTimeout (internal/timers.js:523:9) Sep 17 17:07:38 volumio-kitchen volumio[960]: at processTimers (internal/timers.js:497:7) { Sep 17 17:07:38 volumio-kitchen volumio[960]: errno: -2, Sep 17 17:07:38 volumio-kitchen volumio[960]: code: 'ENOENT', Sep 17 17:07:38 volumio-kitchen volumio[960]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 17 17:07:38 volumio-kitchen volumio[960]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 17 17:07:38 volumio-kitchen volumio[960]: spawnargs: [ Sep 17 17:07:38 volumio-kitchen volumio[960]: '-p', Sep 17 17:07:38 volumio-kitchen volumio[960]: 9876, Sep 17 17:07:38 volumio-kitchen volumio[960]: '-o', Sep 17 17:07:38 volumio-kitchen volumio[960]: '/tmp/camilladsp.log', Sep 17 17:07:38 volumio-kitchen volumio[960]: '-l', Sep 17 17:07:38 volumio-kitchen volumio[960]: 'warn', Sep 17 17:07:38 volumio-kitchen volumio[960]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 17 17:07:38 volumio-kitchen volumio[960]: ] Sep 17 17:07:38 volumio-kitchen volumio[960]: } Sep 17 17:07:38 volumio-kitchen volumio[960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 17:07:38 volumio-kitchen systemd[1]: Started FusionDsp Daemon. Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="completed challenge" Sep 17 17:07:38 volumio-kitchen sudo[1222]: pam_unix(sudo:session): session closed for user root Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="authenticated as basil85" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="authenticated as basil85" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="dealer connection opened" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="initializing zeroconf session, username: basil85" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="autoplay enabled: false" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="received connection id: MTNkNTA4ZTctNjFjMC00ZmIyLWJkNDUtODcyZjA5MzhjMzRiK2RlYWxlcit0Y3A6Ly8wYWNhNWEzMC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArODA1NjZBNzFGQUYyQUJBODBCQ0M5QUZERTY4MjZFNjY4RjBENEEwQkExMkQwOEE1NjU1OUY2QzcwOUEzRERCNA==" Sep 17 17:07:38 volumio-kitchen go-librespot[1161]: time="2024-09-17T17:07:38+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 17 17:07:39 volumio-kitchen sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-17 17:06 Sep 17 17:07:39 volumio-kitchen sudo[1235]: 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"