-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Fri 2024-03-08 10:22:29 CET. -- Mar 08 10:21:56 kueche fake-hwclock[195]: Fri Mar 8 09:21:56 UTC 2024 Mar 08 10:21:56 kueche systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 08 10:21:56 kueche systemd[1]: Starting Apply Kernel Variables... Mar 08 10:21:56 kueche systemd[1]: Mounting Kernel Configuration File System... Mar 08 10:21:56 kueche systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 08 10:21:56 kueche systemd[1]: Started Restore / save the current clock. Mar 08 10:21:56 kueche systemd[1]: Mounted Kernel Configuration File System. Mar 08 10:21:56 kueche volumio[186]: 512 MB or less RAM Detected, need to enable swap Mar 08 10:21:56 kueche systemd[1]: Started Load/Save Random Seed. Mar 08 10:21:56 kueche systemd[1]: Started Apply Kernel Variables. Mar 08 10:21:56 kueche systemd[1]: Started Create System Users. Mar 08 10:21:56 kueche systemd[1]: Starting Create Static Device Nodes in /dev... Mar 08 10:21:56 kueche systemd[1]: Started udev Coldplug all Devices. Mar 08 10:21:56 kueche systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 08 10:21:56 kueche systemd[1]: Started Create Static Device Nodes in /dev. Mar 08 10:21:56 kueche systemd[1]: Starting udev Kernel Device Manager... Mar 08 10:21:56 kueche systemd[1]: Reached target Local File Systems (Pre). Mar 08 10:21:56 kueche systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 08 10:21:56 kueche systemd[1]: Mounting /tmp... Mar 08 10:21:56 kueche systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 08 10:21:56 kueche systemd[1]: Mounting /var/log... Mar 08 10:21:56 kueche systemd[1]: Mounting /var/spool/cups... Mar 08 10:21:56 kueche systemd[1]: Mounted /tmp. Mar 08 10:21:56 kueche systemd[1]: Mounted /var/log. Mar 08 10:21:56 kueche systemd[1]: Mounted /var/spool/cups. Mar 08 10:21:56 kueche systemd[1]: Mounting /var/spool/cups/tmp... Mar 08 10:21:56 kueche systemd[1]: Starting Flush Journal to Persistent Storage... Mar 08 10:21:56 kueche systemd[1]: Mounted /var/spool/cups/tmp. Mar 08 10:21:56 kueche systemd-udevd[222]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 08 10:21:56 kueche systemd-journald[189]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Mar 08 10:21:56 kueche systemd[1]: Started Flush Journal to Persistent Storage. Mar 08 10:21:56 kueche systemd[1]: Started udev Kernel Device Manager. Mar 08 10:21:56 kueche kernel: ext4 filesystem being mounted at /swap supports timestamps until 2038 (0x7fffffff) Mar 08 10:21:56 kueche volumio[186]: Enabling Swap Mar 08 10:21:56 kueche systemd[1]: Starting Show Plymouth Boot Screen... Mar 08 10:21:56 kueche volumio[186]: Setting swappiness to 40 Mar 08 10:21:56 kueche kernel: Adding 524236k swap on /swap/swapfile. Priority:-2 extents:12 across:575480k SSFS Mar 08 10:21:56 kueche volumio[186]: vm.swappiness = 40 Mar 08 10:21:56 kueche systemd[1]: dynamicswap.service: Succeeded. Mar 08 10:21:56 kueche kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Mar 08 10:21:56 kueche kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Mar 08 10:21:57 kueche kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 10:21:57 kueche kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 08 10:21:57 kueche kernel: [vc_sm_connected_init]: start Mar 08 10:21:57 kueche systemd[1]: Received SIGRTMIN+20 from PID 241 (plymouthd). Mar 08 10:21:57 kueche kernel: mc: Linux media interface: v0.10 Mar 08 10:21:57 kueche kernel: [vc_sm_connected_init]: installed successfully Mar 08 10:21:57 kueche systemd[1]: Started Show Plymouth Boot Screen. Mar 08 10:21:57 kueche kernel: videodev: Linux video capture interface: v2.00 Mar 08 10:21:57 kueche systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 08 10:21:57 kueche systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 08 10:21:57 kueche systemd[1]: Reached target Local Encrypted Volumes. Mar 08 10:21:57 kueche systemd[1]: Reached target Paths. Mar 08 10:21:57 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 10:21:57 kueche kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 10:21:57 kueche kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 08 10:21:57 kueche kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 08 10:21:57 kueche kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 10:21:57 kueche kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Mar 08 10:21:57 kueche kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 08 10:21:57 kueche kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 08 10:21:57 kueche kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Mar 08 10:21:57 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Mar 08 10:21:57 kueche kernel: Bluetooth: Core ver 2.22 Mar 08 10:21:57 kueche kernel: NET: Registered PF_BLUETOOTH protocol family Mar 08 10:21:57 kueche kernel: Bluetooth: HCI device and connection manager initialized Mar 08 10:21:57 kueche kernel: Bluetooth: HCI socket layer initialized Mar 08 10:21:57 kueche kernel: Bluetooth: L2CAP socket layer initialized Mar 08 10:21:57 kueche kernel: Bluetooth: SCO socket layer initialized Mar 08 10:21:57 kueche kernel: Bluetooth: HCI UART driver ver 2.3 Mar 08 10:21:57 kueche kernel: Bluetooth: HCI UART protocol H4 registered Mar 08 10:21:57 kueche kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Mar 08 10:21:57 kueche kernel: Bluetooth: HCI UART protocol Broadcom registered Mar 08 10:21:57 kueche kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Mar 08 10:21:57 kueche kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Mar 08 10:21:57 kueche kernel: uart-pl011 3f201000.serial: no DMA platform data Mar 08 10:21:58 kueche systemd-udevd[238]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 10:21:58 kueche kernel: Bluetooth: hci0: BCM: chip id 115 Mar 08 10:21:58 kueche kernel: Bluetooth: hci0: BCM: features 0x0e Mar 08 10:21:58 kueche kernel: Bluetooth: hci0: BCM43430B0 Mar 08 10:21:58 kueche kernel: Bluetooth: hci0: BCM43430B0 (002.001.012) build 0000 Mar 08 10:21:58 kueche kernel: Bluetooth: hci0: BCM43430B0 'brcm/BCM43430B0.hcd' Patch Mar 08 10:21:58 kueche kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 08 10:21:58 kueche kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Mar 08 10:21:58 kueche kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Mar 08 10:21:58 kueche kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 08 10:21:58 kueche kernel: brcmfmac: F1 signature read @0x18000000=0x1542a9a6 Mar 08 10:21:58 kueche kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430b0-sdio for chip BCM43430/2 Mar 08 10:21:58 kueche kernel: usbcore: registered new interface driver brcmfmac Mar 08 10:21:58 kueche systemd[1]: Found device /dev/mmcblk0p1. Mar 08 10:21:58 kueche kernel: Bluetooth: hci0: BCM: features 0x0e Mar 08 10:21:58 kueche kernel: Bluetooth: hci0: BCM4343B0 37.4MHz wlbga_iLNA_iTR [Baseline: 0092] Mar 08 10:21:58 kueche kernel: Bluetooth: hci0: BCM43430B0 (002.001.012) build 0092 Mar 08 10:21:58 kueche kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/2 wl0: Mar 29 2022 19:22:43 version 9.88.4.77 (g58bc5cc) FWID 01-623162e Mar 08 10:21:59 kueche systemd-udevd[230]: Using default interface naming scheme 'v240'. Mar 08 10:21:59 kueche systemd-udevd[230]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 10:21:59 kueche systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Mar 08 10:22:00 kueche systemd[1]: Created slice system-bthelper.slice. Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 08 10:22:00 kueche systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 08 10:22:00 kueche systemd[1]: Starting File System Check on /dev/mmcblk0p1... Mar 08 10:22:00 kueche systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 08 10:22:00 kueche systemd[1]: Started File System Check Daemon to report status. Mar 08 10:22:00 kueche systemd[1]: Started Load/Save RF Kill Switch Status. Mar 08 10:22:00 kueche systemd-fsck[353]: fsck.fat 4.1 (2017-01-24) Mar 08 10:22:00 kueche systemd-fsck[353]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mar 08 10:22:00 kueche systemd-fsck[353]: Automatically removing dirty bit. Mar 08 10:22:00 kueche systemd-fsck[353]: Performing changes. Mar 08 10:22:00 kueche systemd-fsck[353]: /dev/mmcblk0p1: 373 files, 33360/46774 clusters Mar 08 10:22:00 kueche systemd[1]: Started File System Check on /dev/mmcblk0p1. Mar 08 10:22:00 kueche systemd[1]: Mounting /boot... Mar 08 10:22:00 kueche systemd[1]: Mounted /boot. Mar 08 10:22:00 kueche systemd[1]: Reached target Local File Systems. Mar 08 10:22:00 kueche systemd[1]: Starting Raise network interfaces... Mar 08 10:22:00 kueche systemd[1]: Started ifup for wlan0. Mar 08 10:22:00 kueche systemd[1]: Starting Preprocess NFS configuration... Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 08 10:22:00 kueche systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 08 10:22:00 kueche systemd[1]: Starting Create Volatile Files and Directories... Mar 08 10:22:00 kueche systemd[1]: Received SIGRTMIN+20 from PID 241 (plymouthd). Mar 08 10:22:00 kueche systemd[1]: plymouth-read-write.service: Succeeded. Mar 08 10:22:00 kueche systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 08 10:22:00 kueche systemd[1]: nfs-config.service: Succeeded. Mar 08 10:22:00 kueche systemd[1]: Started Preprocess NFS configuration. Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 08 10:22:00 kueche systemd[1]: Reached target NFS client services. Mar 08 10:22:00 kueche systemd[1]: Started Create Volatile Files and Directories. Mar 08 10:22:00 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 10:22:00 kueche systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 08 10:22:00 kueche systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 08 10:22:00 kueche systemd[1]: Starting RPC bind portmap service... Mar 08 10:22:00 kueche systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 08 10:22:00 kueche systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 08 10:22:01 kueche ifup[360]: ifup: waiting for lock on /run/network/ifstate.wlan0 Mar 08 10:22:01 kueche systemd[1]: Reached target System Initialization. Mar 08 10:22:01 kueche systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 08 10:22:01 kueche sh[361]: wlan0=wlan0 Mar 08 10:22:01 kueche systemd[1]: Listening on triggerhappy.socket. Mar 08 10:22:01 kueche systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 08 10:22:01 kueche systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 08 10:22:01 kueche systemd[1]: Reached target Sockets. Mar 08 10:22:01 kueche systemd[1]: Started Volumio Splash. Mar 08 10:22:01 kueche systemd[458]: volumiosplash.service: Failed to execute command: No such file or directory Mar 08 10:22:01 kueche systemd[458]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Mar 08 10:22:01 kueche systemd[1]: apt-daily.timer: Not using persistent file timestamp Mon 2024-04-22 18:34:40 CEST as it is in the future. Mar 08 10:22:01 kueche systemd[1]: Started Daily apt download activities. Mar 08 10:22:01 kueche systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Mon 2024-04-22 06:23:49 CEST as it is in the future. Mar 08 10:22:01 kueche systemd[1]: Started Daily apt upgrade and clean activities. Mar 08 10:22:01 kueche systemd[1]: Reached target Timers. Mar 08 10:22:01 kueche systemd[1]: Reached target Basic System. Mar 08 10:22:01 kueche systemd[1]: Started Volumio Iptables Module. Mar 08 10:22:01 kueche systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 08 10:22:01 kueche systemd[1]: Starting Wireless Services... Mar 08 10:22:01 kueche systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 08 10:22:01 kueche systemd[1]: Started Volumio Log Rotation Service. Mar 08 10:22:01 kueche systemd[1]: Started volumio-remote-updater.service. Mar 08 10:22:01 kueche systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 08 10:22:01 kueche systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 08 10:22:01 kueche systemd[1]: Started D-Bus System Message Bus. Mar 08 10:22:01 kueche systemd[1]: Starting WPA supplicant... Mar 08 10:22:01 kueche systemd[1]: Started Manage Sound Card State (restore and store). Mar 08 10:22:01 kueche systemd[1]: Starting Save/Restore Sound Card State... Mar 08 10:22:01 kueche alsactl[474]: alsactl 1.1.8 daemon started Mar 08 10:22:01 kueche systemd[1]: Starting Login Service... Mar 08 10:22:01 kueche systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 08 10:22:01 kueche systemd[1]: Started UPnP Renderer front-end to MPD. Mar 08 10:22:01 kueche systemd[1]: Starting dhcpcd on all interfaces... Mar 08 10:22:01 kueche systemd[1]: Starting triggerhappy global hotkey daemon... Mar 08 10:22:01 kueche systemd[1]: Started RPC bind portmap service. Mar 08 10:22:01 kueche systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Mar 08 10:22:01 kueche systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Mar 08 10:22:01 kueche systemd[1]: Reached target RPC Port Mapper. Mar 08 10:22:01 kueche systemd[1]: Reached target Remote File Systems (Pre). Mar 08 10:22:01 kueche systemd[1]: Reached target Remote File Systems. Mar 08 10:22:01 kueche dhcpcd[481]: Not running dhcpcd because /etc/network/interfaces Mar 08 10:22:01 kueche dhcpcd[481]: defines some interfaces that will use a Mar 08 10:22:01 kueche dhcpcd[481]: DHCP client or static address Mar 08 10:22:01 kueche systemd[1]: Started Regular background program processing daemon. Mar 08 10:22:01 kueche systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 08 10:22:01 kueche systemd[1]: Started Raise network interfaces. Mar 08 10:22:01 kueche cron[502]: (CRON) INFO (pidfile fd = 3) Mar 08 10:22:01 kueche thd[482]: Unable to parse trigger line: Mar 08 10:22:01 kueche thd[482]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 08 10:22:01 kueche thd[482]: Unable to parse trigger line: Mar 08 10:22:01 kueche thd[482]: Found socket passed from systemd Mar 08 10:22:01 kueche systemd[1]: Started triggerhappy global hotkey daemon. Mar 08 10:22:01 kueche systemd[1]: Started Save/Restore Sound Card State. Mar 08 10:22:01 kueche systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 08 10:22:01 kueche systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 08 10:22:01 kueche systemd[1]: Failed to start dhcpcd on all interfaces. Mar 08 10:22:01 kueche systemd[1]: Reached target Sound Card. Mar 08 10:22:01 kueche cron[502]: Error: bad username; while reading /etc/crontab Mar 08 10:22:01 kueche cron[502]: (*system*) ERROR (Syntax error, this crontab file will be ignored) Mar 08 10:22:01 kueche cron[502]: (CRON) INFO (Running @reboot jobs) Mar 08 10:22:01 kueche avahi-daemon[479]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 08 10:22:01 kueche avahi-daemon[479]: Successfully dropped root privileges. Mar 08 10:22:01 kueche avahi-daemon[479]: avahi-daemon 0.7 starting up. Mar 08 10:22:01 kueche systemd-logind[478]: New seat seat0. Mar 08 10:22:02 kueche systemd[1]: Started Login Service. Mar 08 10:22:02 kueche avahi-daemon[479]: Successfully called chroot(). Mar 08 10:22:02 kueche avahi-daemon[479]: Successfully dropped remaining capabilities. Mar 08 10:22:02 kueche systemd[1]: hciuart.service: Succeeded. Mar 08 10:22:02 kueche systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 08 10:22:02 kueche wpa_supplicant[472]: Successfully initialized wpa_supplicant Mar 08 10:22:02 kueche avahi-daemon[479]: Loading service file /services/volumio.service. Mar 08 10:22:02 kueche avahi-daemon[479]: Network interface enumeration completed. Mar 08 10:22:02 kueche avahi-daemon[479]: Server startup complete. Host name is kueche.local. Local service cookie is 564902563. Mar 08 10:22:02 kueche avahi-daemon[479]: Service "kueche" (/services/volumio.service) successfully established. Mar 08 10:22:02 kueche systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 08 10:22:02 kueche systemd[1]: Started WPA supplicant. Mar 08 10:22:02 kueche systemd[1]: Reached target Network. Mar 08 10:22:02 kueche systemd[1]: Starting Network Time Service... Mar 08 10:22:02 kueche systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 08 10:22:02 kueche systemd[1]: Starting OpenBSD Secure Shell server... Mar 08 10:22:02 kueche systemd[1]: Reached target Network is Online. Mar 08 10:22:02 kueche systemd[1]: Starting Samba NMB Daemon... Mar 08 10:22:02 kueche systemd[1]: Starting LSB: exim Mail Transport Agent... Mar 08 10:22:02 kueche volumio-remote-updater[469]: Error: No active session Mar 08 10:22:02 kueche systemd[1]: Starting LSB: Brings up/down network automatically... Mar 08 10:22:03 kueche volumio-remote-updater[469]: [2024-03-08 10:22:03] [info] asio async_connect error: system:111 (Connection refused) Mar 08 10:22:03 kueche volumio-remote-updater[469]: [2024-03-08 10:22:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 10:22:03 kueche volumio-remote-updater[469]: [2024-03-08 10:22:03] [error] handle_connect error: Underlying Transport Error Mar 08 10:22:03 kueche systemd[1]: Starting /etc/rc.local Compatibility... Mar 08 10:22:03 kueche systemd[1]: Starting Permit User Sessions... Mar 08 10:22:03 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 08 10:22:03 kueche systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 08 10:22:03 kueche systemd[1]: Started /etc/rc.local Compatibility. Mar 08 10:22:03 kueche volumio[480]: Could not open config: /tmp/upmpdcli.conf Mar 08 10:22:03 kueche systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 08 10:22:03 kueche systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 08 10:22:03 kueche systemd[1]: Started Permit User Sessions. Mar 08 10:22:03 kueche systemd[1]: iptables.service: Succeeded. Mar 08 10:22:03 kueche bthelper[549]: Raspberry Pi BDADDR already set Mar 08 10:22:03 kueche loadcpufreq[503]: Loading cpufreq kernel modules...done (none). Mar 08 10:22:03 kueche ntpd[524]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 08 10:22:03 kueche ntpd[524]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 08 10:22:03 kueche systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 08 10:22:03 kueche systemd[1]: Started Network Time Service. Mar 08 10:22:03 kueche systemd[1]: Started Raspberry Pi bluetooth helper. Mar 08 10:22:03 kueche ntpd[583]: proto: precision = 1.406 usec (-19) Mar 08 10:22:03 kueche ifplugd[540]: Network Interface Plugging Daemon...skip eth0...done. Mar 08 10:22:03 kueche systemd[1]: Starting Bluetooth service... Mar 08 10:22:03 kueche systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 08 10:22:03 kueche systemd[1]: Starting Hold until boot process finishes up... Mar 08 10:22:03 kueche systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 08 10:22:03 kueche systemd[1]: Started LSB: Brings up/down network automatically. Mar 08 10:22:03 kueche systemd[1]: Received SIGRTMIN+21 from PID 241 (plymouthd). Mar 08 10:22:03 kueche systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 08 10:22:03 kueche systemd[1]: Started Hold until boot process finishes up. Mar 08 10:22:03 kueche systemd[1]: plymouth-quit.service: Succeeded. Mar 08 10:22:03 kueche systemd[1]: Started Terminate Plymouth Boot Screen. Mar 08 10:22:03 kueche systemd[1]: plymouth-start.service: Succeeded. Mar 08 10:22:03 kueche systemd[1]: Received SIGRTMIN+21 from PID 241 (n/a). Mar 08 10:22:03 kueche haveged[424]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 08 10:22:03 kueche haveged[424]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 08 10:22:03 kueche haveged[424]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00166 Mar 08 10:22:03 kueche haveged[424]: haveged: fills: 0, generated: 0 Mar 08 10:22:03 kueche systemd[1]: Started Getty on tty1. Mar 08 10:22:03 kueche systemd[1]: Reached target Login Prompts. Mar 08 10:22:03 kueche ntpd[583]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 08 10:22:03 kueche ntpd[583]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 08 10:22:03 kueche ntpd[583]: Listen and drop on 0 v6wildcard [::]:123 Mar 08 10:22:03 kueche ntpd[583]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 08 10:22:03 kueche ntpd[583]: Listen normally on 2 lo 127.0.0.1:123 Mar 08 10:22:03 kueche ntpd[583]: Listening on routing socket on fd #19 for interface updates Mar 08 10:22:03 kueche ntpd[583]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 08 10:22:03 kueche ntpd[583]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 08 10:22:03 kueche sshd[610]: Server listening on 0.0.0.0 port 22. Mar 08 10:22:03 kueche sshd[610]: Server listening on :: port 22. Mar 08 10:22:04 kueche systemd[1]: Started OpenBSD Secure Shell server. Mar 08 10:22:04 kueche cpufrequtils[591]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 08 10:22:04 kueche systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 08 10:22:04 kueche bluetoothd[588]: Bluetooth daemon 5.50 Mar 08 10:22:04 kueche bluetoothd[588]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 08 10:22:04 kueche systemd[1]: Started Bluetooth service. Mar 08 10:22:04 kueche systemd[1]: Reached target Bluetooth. Mar 08 10:22:04 kueche bluetoothd[588]: Starting SDP server Mar 08 10:22:04 kueche bluetoothd[588]: Excluding (cli) sap Mar 08 10:22:04 kueche kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 08 10:22:04 kueche kernel: Bluetooth: BNEP filters: protocol multicast Mar 08 10:22:04 kueche kernel: Bluetooth: BNEP socket layer initialized Mar 08 10:22:04 kueche bluetoothd[588]: Bluetooth management interface 1.22 initialized Mar 08 10:22:04 kueche dbus-daemon[470]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=588 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 08 10:22:04 kueche kernel: Bluetooth: MGMT ver 1.22 Mar 08 10:22:04 kueche bluetoothd[588]: Failed to set privacy: Rejected (0x0b) Mar 08 10:22:04 kueche ntpd[583]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 08 10:22:04 kueche systemd[1]: Starting Hostname Service... Mar 08 10:22:04 kueche dbus-daemon[470]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 08 10:22:04 kueche systemd[1]: Started Hostname Service. Mar 08 10:22:05 kueche exim4[536]: Starting MTA: exim4. Mar 08 10:22:05 kueche systemd[1]: Started LSB: exim Mail Transport Agent. Mar 08 10:22:05 kueche exim[867]: 2024-03-08 10:22:05 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) Mar 08 10:22:05 kueche exim[867]: 2024-03-08 10:22:05 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=124 Mar 08 10:22:05 kueche exim[867]: exim: could not open panic log - aborting: see message(s) above Mar 08 10:22:05 kueche nmbd[550]: [2024/03/08 10:22:05.225950, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 08 10:22:05 kueche nmbd[550]: started asyncdns process 870 Mar 08 10:22:05 kueche nmbd[550]: [2024/03/08 10:22:05.230034, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 08 10:22:05 kueche nmbd[550]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 08 10:22:05 kueche nmbd[550]: [2024/03/08 10:22:05.230440, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 08 10:22:05 kueche nmbd[550]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 08 10:22:05 kueche systemd[1]: systemd-rfkill.service: Succeeded. Mar 08 10:22:05 kueche ntpd[583]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 08 10:22:05 kueche wireless.js[466]: Cleaning previous... Mar 08 10:22:05 kueche sudo[878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 10:22:05 kueche sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:05 kueche sudo[878]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:05 kueche sudo[880]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 10:22:05 kueche sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:06 kueche sudo[880]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:06 kueche ntpd[583]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 08 10:22:06 kueche wireless.js[466]: Stopped aP Mar 08 10:22:06 kueche sudo[887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 10:22:06 kueche sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:06 kueche sudo[887]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:06 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 10:22:06 kueche sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 08 10:22:06 kueche sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:06 kueche sudo[889]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:06 kueche sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 10:22:06 kueche sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:06 kueche sudo[896]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:06 kueche sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 08 10:22:06 kueche sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:07 kueche sudo[898]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:07 kueche wireless.js[466]: Start wireless flow Mar 08 10:22:07 kueche wireless.js[466]: Stopped hotspot (if there).. Mar 08 10:22:07 kueche sudo[903]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 10:22:07 kueche sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:07 kueche sudo[903]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:07 kueche sudo[905]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 10:22:07 kueche sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:07 kueche ntpd[583]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 08 10:22:07 kueche sudo[905]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:07 kueche wireless.js[466]: DHCP IP Mar 08 10:22:08 kueche wireless.js[466]: Start ap Mar 08 10:22:08 kueche wpa_supplicant[907]: Successfully initialized wpa_supplicant Mar 08 10:22:08 kueche volumio-remote-updater[469]: [2024-03-08 10:22:08] [info] asio async_connect error: system:111 (Connection refused) Mar 08 10:22:08 kueche volumio-remote-updater[469]: [2024-03-08 10:22:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 10:22:08 kueche volumio-remote-updater[469]: [2024-03-08 10:22:08] [error] handle_connect error: Underlying Transport Error Mar 08 10:22:08 kueche sudo[908]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 08 10:22:08 kueche sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:08 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 10:22:08 kueche dhcpcd[909]: dev: loaded udev Mar 08 10:22:08 kueche kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 08 10:22:08 kueche dhcpcd[909]: wlan0: connected to Access Point `' Mar 08 10:22:08 kueche dhcpcd[909]: no interfaces have a carrier Mar 08 10:22:08 kueche dhcpcd[909]: forked to background, child pid 931 Mar 08 10:22:08 kueche dhcpcd[931]: wlan0: waiting for carrier Mar 08 10:22:08 kueche sudo[908]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:08 kueche bthelper[549]: Changing power off succeeded Mar 08 10:22:08 kueche bthelper[549]: [CHG] Controller E4:5F:01:77:2E:9D Class: 0x0000041c Mar 08 10:22:08 kueche bthelper[549]: Changing power on succeeded Mar 08 10:22:08 kueche wpa_supplicant[919]: wlan0: Trying to associate with SSID 'AnJo' Mar 08 10:22:09 kueche wireless.js[466]: trying... Mar 08 10:22:09 kueche sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 10:22:09 kueche sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:09 kueche sudo[939]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:09 kueche wpa_supplicant[919]: wlan0: Associated with b4:b0:24:0f:86:9c Mar 08 10:22:09 kueche wpa_supplicant[919]: wlan0: CTRL-EVENT-CONNECTED - Connection to b4:b0:24:0f:86:9c completed [id=0 id_str=] Mar 08 10:22:09 kueche dhcpcd[931]: wlan0: carrier acquired Mar 08 10:22:09 kueche wpa_supplicant[919]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 08 10:22:09 kueche dhcpcd[931]: wlan0: connected to Access Point `AnJo' Mar 08 10:22:09 kueche dhcpcd[931]: DUID 00:01:00:01:2b:1f:b7:25:e4:5f:01:7e:4a:8c Mar 08 10:22:09 kueche dhcpcd[931]: wlan0: IAID 01:77:2e:9b Mar 08 10:22:09 kueche dhcpcd[931]: wlan0: adding address fe80::e65f:1ff:fe77:2e9b Mar 08 10:22:09 kueche dhcpcd[931]: ipv6_addaddr1: Permission denied Mar 08 10:22:09 kueche dhcpcd[931]: wlan0: probing address 192.168.0.133/24 Mar 08 10:22:10 kueche wireless.js[466]: trying... Mar 08 10:22:10 kueche sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 10:22:10 kueche sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:10 kueche sudo[943]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:10 kueche dhcpcd[931]: wlan0: soliciting an IPv6 router Mar 08 10:22:11 kueche wireless.js[466]: trying... Mar 08 10:22:11 kueche sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 10:22:11 kueche sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:11 kueche sudo[946]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:12 kueche wireless.js[466]: trying... Mar 08 10:22:12 kueche sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 10:22:12 kueche sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:12 kueche sudo[949]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:13 kueche wireless.js[466]: trying... Mar 08 10:22:13 kueche sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 10:22:13 kueche sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:13 kueche sudo[952]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:14 kueche wireless.js[466]: trying... Mar 08 10:22:14 kueche sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 10:22:14 kueche sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:14 kueche sudo[955]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:14 kueche dhcpcd[931]: wlan0: using static address 192.168.0.133/24 Mar 08 10:22:14 kueche avahi-daemon[479]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.133. Mar 08 10:22:14 kueche dhcpcd[931]: wlan0: adding route to 192.168.0.0/24 Mar 08 10:22:14 kueche dhcpcd[931]: wlan0: adding default route via 192.168.0.1 Mar 08 10:22:14 kueche avahi-daemon[479]: New relevant interface wlan0.IPv4 for mDNS. Mar 08 10:22:14 kueche avahi-daemon[479]: Registering new address record for 192.168.0.133 on wlan0.IPv4. Mar 08 10:22:15 kueche wireless.js[466]: trying... Mar 08 10:22:15 kueche sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 10:22:15 kueche sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:15 kueche sudo[978]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:15 kueche wireless.js[466]: Connected to: ----AnJo Mar 08 10:22:15 kueche wireless.js[466]: ---- Mar 08 10:22:15 kueche sudo[981]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 10:22:15 kueche sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:15 kueche sudo[981]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:15 kueche wireless.js[466]: ... joined AP, wlan0 IPv4 is 192.168.0.133, ipV6 is undefined Mar 08 10:22:15 kueche wireless.js[466]: It's done! AP Mar 08 10:22:15 kueche systemd[1]: Started Wireless Services. Mar 08 10:22:15 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 10:22:15 kueche systemd[1]: Started Volumio Backend Module. Mar 08 10:22:15 kueche systemd[1]: Started Volumio Cpu Tweaker. Mar 08 10:22:15 kueche nmbd[550]: [2024/03/08 10:22:15.241036, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 10:22:15 kueche nmbd[550]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 08 10:22:15 kueche systemd[1]: Started Samba NMB Daemon. Mar 08 10:22:15 kueche systemd[1]: Starting Samba Winbind Daemon... Mar 08 10:22:15 kueche volumio-cpu-tweak[987]: Setting RT Priority for mpd Mar 08 10:22:15 kueche volumio-cpu-tweak[987]: pid 35's current scheduling policy: SCHED_OTHER Mar 08 10:22:15 kueche volumio-cpu-tweak[987]: pid 35's current scheduling priority: 0 Mar 08 10:22:15 kueche volumio-cpu-tweak[987]: Setting MPD Affinity Mar 08 10:22:15 kueche volumio-cpu-tweak[987]: pid 3's current affinity mask: f Mar 08 10:22:15 kueche volumio-cpu-tweak[987]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 08 10:22:15 kueche volumio-cpu-tweak[987]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 08 10:22:15 kueche systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 08 10:22:15 kueche volumio-remote-updater[469]: [2024-03-08 10:22:15] [info] asio async_connect error: system:111 (Connection refused) Mar 08 10:22:15 kueche volumio-remote-updater[469]: [2024-03-08 10:22:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 10:22:15 kueche volumio-remote-updater[469]: [2024-03-08 10:22:15] [error] handle_connect error: Underlying Transport Error Mar 08 10:22:15 kueche winbindd[992]: [2024/03/08 10:22:15.700771, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 08 10:22:15 kueche winbindd[992]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 08 10:22:15 kueche winbindd[992]: [2024/03/08 10:22:15.751083, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 10:22:15 kueche winbindd[992]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 08 10:22:15 kueche systemd[1]: Started Samba Winbind Daemon. Mar 08 10:22:15 kueche systemd[1]: Starting Samba SMB Daemon... Mar 08 10:22:16 kueche winbindd[992]: [2024/03/08 10:22:16.477112, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Mar 08 10:22:16 kueche winbindd[992]: res_names->count = 3, expected 4 Mar 08 10:22:16 kueche smbd[1008]: [2024/03/08 10:22:16.516760, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 10:22:16 kueche smbd[1008]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 08 10:22:16 kueche systemd[1]: Started Samba SMB Daemon. Mar 08 10:22:16 kueche systemd[1]: Reached target Multi-User System. Mar 08 10:22:16 kueche systemd[1]: Reached target Graphical Interface. Mar 08 10:22:16 kueche ntpd[583]: Listen normally on 3 wlan0 192.168.0.133:123 Mar 08 10:22:16 kueche ntpd[583]: new interface(s) found: waking up resolver Mar 08 10:22:16 kueche systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 08 10:22:16 kueche systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 08 10:22:16 kueche systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 08 10:22:16 kueche systemd[1]: Startup finished in 10.338s (kernel) + 22.995s (userspace) = 33.333s. Mar 08 10:22:18 kueche volumio[986]: info: ------------------------------------------- Mar 08 10:22:18 kueche volumio[986]: info: ----- Volumio3 ---- Mar 08 10:22:18 kueche volumio[986]: info: ------------------------------------------- Mar 08 10:22:18 kueche volumio[986]: info: ----- System startup ---- Mar 08 10:22:18 kueche volumio[986]: info: ------------------------------------------- Mar 08 10:22:20 kueche volumio[986]: info: MYVOLUMIO Environment detected Mar 08 10:22:20 kueche volumio[986]: info: Plugin folders cleanup Mar 08 10:22:20 kueche volumio[986]: info: Scanning into folder /volumio/app/plugins/ Mar 08 10:22:20 kueche volumio[986]: info: Scanning category audio_interface Mar 08 10:22:20 kueche volumio[986]: info: Scanning category miscellanea Mar 08 10:22:20 kueche volumio[986]: info: Scanning category music_service Mar 08 10:22:20 kueche volumio[986]: info: Scanning category plugins.json Mar 08 10:22:20 kueche volumio[986]: info: Scanning category system_controller Mar 08 10:22:20 kueche volumio[986]: info: Scanning category user_interface Mar 08 10:22:20 kueche volumio[986]: info: Scanning into folder /data/plugins/ Mar 08 10:22:20 kueche volumio[986]: info: Scanning category music_service Mar 08 10:22:20 kueche volumio[986]: info: Plugin folders cleanup completed Mar 08 10:22:20 kueche volumio[986]: info: ------------------------------------------- Mar 08 10:22:20 kueche volumio[986]: info: ----- Core plugins startup ---- Mar 08 10:22:20 kueche volumio[986]: info: ------------------------------------------- Mar 08 10:22:20 kueche volumio[986]: info: Loading plugins from folder /volumio/app/plugins/ Mar 08 10:22:20 kueche volumio[986]: info: Adding plugin upnp to MyMusic Plugins Mar 08 10:22:20 kueche volumio[986]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 08 10:22:20 kueche volumio[986]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 08 10:22:20 kueche volumio[986]: info: Loading plugins from folder /data/plugins/ Mar 08 10:22:20 kueche volumio[986]: info: Loading plugin "system"... Mar 08 10:22:20 kueche volumio[986]: info: Loading plugin "appearance"... Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "network"... Mar 08 10:22:22 kueche volumio[986]: info: Refreshing Cached IP Addresses Mar 08 10:22:22 kueche sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 10:22:22 kueche sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "services"... Mar 08 10:22:22 kueche sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 10:22:22 kueche sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:22 kueche sudo[1033]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "alsa_controller"... Mar 08 10:22:22 kueche sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 08 10:22:22 kueche sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:22 kueche sudo[1031]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:22 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "wizard"... Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "networkfs"... Mar 08 10:22:22 kueche volumio[986]: info: Starting Udev Watcher for removable devices Mar 08 10:22:22 kueche sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Josef,password=h1t3l2170,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.36/AnJo\\Musik /mnt/NAS/NAS Mar 08 10:22:22 kueche sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 10:22:22 kueche volumio[986]: info: Ignoring mount for partition: boot Mar 08 10:22:22 kueche volumio[986]: info: Ignoring mount for partition: volumio Mar 08 10:22:22 kueche volumio[986]: info: Ignoring mount for partition: volumio_data Mar 08 10:22:22 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "volumio_command_line_client"... Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "upnp"... Mar 08 10:22:22 kueche volumio[986]: info: [1709889742713] Starting Upmpd Daemon Mar 08 10:22:22 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "my_music"... Mar 08 10:22:22 kueche volumio[986]: info: Loading plugin "mpd"... Mar 08 10:22:22 kueche kernel: Key type cifs.spnego registered Mar 08 10:22:22 kueche kernel: Key type cifs.idmap registered Mar 08 10:22:22 kueche 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. Mar 08 10:22:22 kueche kernel: CIFS: Attempting to mount \\192.168.0.36\AnJo Mar 08 10:22:23 kueche sudo[1036]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:23 kueche volumio[986]: info: Loading plugin "upnp_browser"... Mar 08 10:22:23 kueche volumio[986]: info: Loading plugin "alarm-clock"... Mar 08 10:22:24 kueche volumio[986]: info: Loading plugin "airplay_emulation"... Mar 08 10:22:24 kueche volumio[986]: info: Starting Shairport Sync Mar 08 10:22:24 kueche volumio[986]: info: Loading plugin "last_100"... Mar 08 10:22:24 kueche volumio[986]: info: Loading plugin "webradio"... Mar 08 10:22:24 kueche volumio[986]: info: Loading plugin "i2s_dacs"... Mar 08 10:22:24 kueche volumio[986]: info: Loading plugin "volumiodiscovery"... Mar 08 10:22:24 kueche volumio[986]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 08 10:22:24 kueche volumio[986]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 10:22:24 kueche volumio[986]: *** WARNING *** For more information see Mar 08 10:22:24 kueche volumio[986]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 08 10:22:24 kueche node[986]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 08 10:22:24 kueche volumio[986]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 10:22:24 kueche volumio[986]: *** WARNING *** For more information see Mar 08 10:22:24 kueche node[986]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 10:22:24 kueche node[986]: *** WARNING *** For more information see Mar 08 10:22:24 kueche node[986]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 08 10:22:24 kueche node[986]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 10:22:24 kueche node[986]: *** WARNING *** For more information see Mar 08 10:22:24 kueche volumio[986]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 08 10:22:24 kueche volumio[986]: info: Discovery: Started advertising with name: kueche Mar 08 10:22:24 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 10:22:24 kueche volumio[986]: info: Loading plugin "spop"... Mar 08 10:22:24 kueche sudo[1046]: pam_unix(sudo:session): session closed for user root Mar 08 10:22:25 kueche volumio-remote-updater[469]: [2024-03-08 10:22:25] [connect] Successful connection Mar 08 10:22:26 kueche volumio[986]: info: Loading plugin "outputs"... Mar 08 10:22:26 kueche volumio[986]: info: Loading plugin "albumart"... Mar 08 10:22:26 kueche volumio[986]: info: Plugin example_plugin is not enabled Mar 08 10:22:26 kueche volumio[986]: info: Loading plugin "inputs"... Mar 08 10:22:26 kueche volumio[986]: info: Loading plugin "updater_comm"... Mar 08 10:22:26 kueche volumio[986]: info: Plugin mpdemulation is not enabled Mar 08 10:22:26 kueche volumio[986]: info: Loading plugin "rest_api"... Mar 08 10:22:26 kueche volumio[986]: info: Loading plugin "websocket"... Mar 08 10:22:26 kueche volumio[986]: info: Loading i18n strings for locale de Mar 08 10:22:26 kueche volumio[986]: Updating browse sources language Mar 08 10:22:26 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 10:22:27 kueche volumio[986]: Forking 3 albumart workers Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::initPlayerControls Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 10:22:27 kueche volumio[986]: Express server listening on port 3000 Mar 08 10:22:27 kueche volumio[986]: [Metrics] WebUI: 9s 719.92ms Mar 08 10:22:27 kueche volumio[986]: info: CoreStateMachine::resetVolumioState Mar 08 10:22:27 kueche volumio[986]: info: CoreStateMachine::getcurrentVolume Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 10:22:27 kueche volumio[986]: verbose: New Socket.io Connection to 192.168.0.133:3000 from 192.168.0.164 UA: node-XMLHttpRequest Total Clients: 1 Mar 08 10:22:27 kueche volumio-remote-updater[469]: [2024-03-08 10:22:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1709889745 101 Mar 08 10:22:27 kueche volumio[986]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::volumioGetState Mar 08 10:22:27 kueche volumio[986]: info: CorePlayQueue::getTrack 0 Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 10:22:27 kueche volumio[986]: info: Reloading queue from file Mar 08 10:22:27 kueche volumio[986]: info: Setting Device type: Raspberry PI Mar 08 10:22:27 kueche volumio[986]: info: CoreStateMachine::setRepeat null single undefined Mar 08 10:22:27 kueche volumio[986]: info: CoreStateMachine::pushState Mar 08 10:22:27 kueche volumio[986]: info: CorePlayQueue::getTrack 0 Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::volumioPushState Mar 08 10:22:27 kueche volumio[986]: info: CoreStateMachine::setRandom null Mar 08 10:22:27 kueche volumio[986]: info: CoreStateMachine::pushState Mar 08 10:22:27 kueche volumio[986]: info: CorePlayQueue::getTrack 0 Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::volumioPushState Mar 08 10:22:27 kueche volumio[986]: info: Listing playlists Mar 08 10:22:27 kueche volumio[986]: info: Listing playlists Mar 08 10:22:27 kueche volumio[986]: info: VolumeController:: Volume=15 Mute =false Mar 08 10:22:27 kueche volumio[986]: info: CoreStateMachine::pushState Mar 08 10:22:27 kueche volumio[986]: info: CorePlayQueue::getTrack 0 Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::volumioPushState Mar 08 10:22:27 kueche volumio[986]: info: CoreStateMachine::updateTrackBlock Mar 08 10:22:27 kueche volumio[986]: info: CorePlayQueue::getTrackBlock Mar 08 10:22:27 kueche volumio[986]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 10:22:28 kueche volumio[986]: info: CoreCommandRouter::volumioGetState Mar 08 10:22:28 kueche volumio[986]: info: CorePlayQueue::getTrack 0 Mar 08 10:22:28 kueche volumio[986]: info: CoreCommandRouter::volumioGetState Mar 08 10:22:28 kueche volumio[986]: info: CorePlayQueue::getTrack 0 Mar 08 10:22:28 kueche volumio[986]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 10:22:28 kueche volumio[986]: info: CoreStateMachine::ClearQueue Mar 08 10:22:28 kueche volumio[986]: info: CoreStateMachine::stop Mar 08 10:22:28 kueche volumio[986]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 10:22:28 kueche volumio[986]: info: CorePlayQueue::clearPlayQueue Mar 08 10:22:28 kueche volumio[986]: info: CorePlayQueue::saveQueue Mar 08 10:22:28 kueche volumio[986]: info: CoreCommandRouter::volumioPushQueue Mar 08 10:22:28 kueche volumio[986]: info: CoreStateMachine::addQueueItems Mar 08 10:22:28 kueche volumio[986]: info: CorePlayQueue::addQueueItems Mar 08 10:22:28 kueche volumio[986]: info: Preload queue cleared Mar 08 10:22:28 kueche volumio[986]: info: Adding Item to queue: mnt/NAS/NAS/doorbell.mp3 Mar 08 10:22:28 kueche volumio[986]: info: Exploding uri mnt/NAS/NAS/doorbell.mp3 in service mpd Mar 08 10:22:28 kueche volumio[986]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 10:22:28 kueche volumio[986]: Starting albumart workers Mar 08 10:22:28 kueche volumio[986]: TypeError: Cannot read property 'then' of undefined Mar 08 10:22:28 kueche volumio[986]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Mar 08 10:22:28 kueche volumio[986]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Mar 08 10:22:28 kueche volumio[986]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Mar 08 10:22:28 kueche volumio[986]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Mar 08 10:22:28 kueche volumio[986]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Mar 08 10:22:28 kueche volumio[986]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Mar 08 10:22:28 kueche volumio[986]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Mar 08 10:22:28 kueche volumio[986]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:25) Mar 08 10:22:28 kueche volumio[986]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:84:33) Mar 08 10:22:28 kueche volumio[986]: at Socket.emit (events.js:315:20) Mar 08 10:22:28 kueche volumio[986]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 08 10:22:28 kueche volumio[986]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 08 10:22:28 kueche volumio[986]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 10:22:28 kueche volumio[986]: Starting albumart workers Mar 08 10:22:28 kueche volumio[986]: Starting albumart workers Mar 08 10:22:29 kueche sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-08 10:21 Mar 08 10:22:29 kueche sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"