-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-06-04 20:47:26 CEST. -- Jun 04 20:46:45 volumio-niclas fake-hwclock[325]: Tue Jun 4 18:46:45 UTC 2024 Jun 04 20:46:45 volumio-niclas kernel: i2c_dev: i2c /dev entries driver Jun 04 20:46:45 volumio-niclas systemd-modules-load[330]: Inserted module 'i2c_dev' Jun 04 20:46:45 volumio-niclas systemd[1]: Started Restore / save the current clock. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Create list of required static device nodes for the current kernel. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted RPC Pipe File System. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Load Kernel Modules. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Remount Root and Kernel File Systems. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted Kernel Debug File System. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted POSIX Message Queue File System. Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Create System Users... Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Load/Save Random Seed... Jun 04 20:46:45 volumio-niclas systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting Kernel Configuration File System... Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting FUSE Control File System... Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Apply Kernel Variables... Jun 04 20:46:45 volumio-niclas systemd[1]: dynamicswap.service: Succeeded. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Create System Users. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Load/Save Random Seed. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted Kernel Configuration File System. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted FUSE Control File System. Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Create Static Device Nodes in /dev... Jun 04 20:46:45 volumio-niclas systemd[1]: Started Apply Kernel Variables. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Create Static Device Nodes in /dev. Jun 04 20:46:45 volumio-niclas systemd[1]: Starting udev Kernel Device Manager... Jun 04 20:46:45 volumio-niclas systemd[1]: Reached target Local File Systems (Pre). Jun 04 20:46:45 volumio-niclas systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting /var/log... Jun 04 20:46:45 volumio-niclas systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting /tmp... Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting /var/spool/cups... Jun 04 20:46:45 volumio-niclas systemd[1]: Started udev Coldplug all Devices. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted /var/log. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted /tmp. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted /var/spool/cups. Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting /var/spool/cups/tmp... Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Flush Journal to Persistent Storage... Jun 04 20:46:45 volumio-niclas systemd-udevd[354]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted /var/spool/cups/tmp. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Helper to synchronize boot up for ifupdown. Jun 04 20:46:45 volumio-niclas systemd-journald[324]: Runtime journal (/run/log/journal/c28a768762e55f60d289ca8f665f5f47) is 7.5M, max 30.0M, 22.5M free. Jun 04 20:46:45 volumio-niclas systemd[1]: Started udev Kernel Device Manager. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Flush Journal to Persistent Storage. Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Show Plymouth Boot Screen... Jun 04 20:46:45 volumio-niclas systemd[1]: plymouth-start.service: Succeeded. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Show Plymouth Boot Screen. Jun 04 20:46:45 volumio-niclas systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 04 20:46:45 volumio-niclas systemd[1]: Reached target Local Encrypted Volumes. Jun 04 20:46:45 volumio-niclas systemd[1]: Reached target Paths. Jun 04 20:46:45 volumio-niclas systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jun 04 20:46:46 volumio-niclas systemd-udevd[371]: Using default interface naming scheme 'v240'. Jun 04 20:46:46 volumio-niclas kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 20:46:46 volumio-niclas kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jun 04 20:46:46 volumio-niclas kernel: [vc_sm_connected_init]: start Jun 04 20:46:46 volumio-niclas kernel: [vc_sm_connected_init]: installed successfully Jun 04 20:46:46 volumio-niclas kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jun 04 20:46:46 volumio-niclas kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jun 04 20:46:46 volumio-niclas systemd-udevd[371]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 04 20:46:46 volumio-niclas kernel: mc: Linux media interface: v0.10 Jun 04 20:46:46 volumio-niclas systemd-udevd[377]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 04 20:46:46 volumio-niclas kernel: videodev: Linux video capture interface: v2.00 Jun 04 20:46:46 volumio-niclas kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 20:46:46 volumio-niclas kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jun 04 20:46:46 volumio-niclas kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jun 04 20:46:46 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jun 04 20:46:46 volumio-niclas kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 04 20:46:46 volumio-niclas kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jun 04 20:46:46 volumio-niclas kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jun 04 20:46:46 volumio-niclas kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jun 04 20:46:46 volumio-niclas kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 04 20:46:46 volumio-niclas kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 04 20:46:46 volumio-niclas kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 20:46:47 volumio-niclas kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: Core ver 2.22 Jun 04 20:46:47 volumio-niclas kernel: NET: Registered PF_BLUETOOTH protocol family Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI device and connection manager initialized Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI socket layer initialized Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: L2CAP socket layer initialized Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: SCO socket layer initialized Jun 04 20:46:47 volumio-niclas kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jun 04 20:46:47 volumio-niclas kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jun 04 20:46:47 volumio-niclas kernel: usbcore: registered new interface driver brcmfmac Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI UART driver ver 2.3 Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI UART protocol H4 registered Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI UART protocol Broadcom registered Jun 04 20:46:47 volumio-niclas kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jun 04 20:46:47 volumio-niclas kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jun 04 20:46:47 volumio-niclas kernel: uart-pl011 fe201000.serial: no DMA platform data Jun 04 20:46:47 volumio-niclas systemd[1]: Found device /dev/disk/by-uuid/FD90-ED9E. Jun 04 20:46:47 volumio-niclas systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 04 20:46:47 volumio-niclas kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 20:46:47 volumio-niclas kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jun 04 20:46:47 volumio-niclas 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 Jun 04 20:46:47 volumio-niclas kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 04 20:46:47 volumio-niclas systemd[1]: Starting File System Check on /dev/disk/by-uuid/FD90-ED9E... Jun 04 20:46:47 volumio-niclas kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 04 20:46:47 volumio-niclas systemd-udevd[373]: Using default interface naming scheme 'v240'. Jun 04 20:46:47 volumio-niclas systemd-udevd[373]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM: chip id 107 Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM: features 0x2f Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM4345C0 Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jun 04 20:46:47 volumio-niclas systemd-fsck[492]: fsck.fat 4.1 (2017-01-24) Jun 04 20:46:47 volumio-niclas systemd-fsck[492]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jun 04 20:46:47 volumio-niclas systemd-fsck[492]: Automatically removing dirty bit. Jun 04 20:46:47 volumio-niclas systemd-fsck[492]: Performing changes. Jun 04 20:46:47 volumio-niclas systemd-fsck[492]: /dev/mmcblk0p1: 369 files, 134026/184582 clusters Jun 04 20:46:47 volumio-niclas systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jun 04 20:46:47 volumio-niclas systemd[1]: Started File System Check on /dev/disk/by-uuid/FD90-ED9E. Jun 04 20:46:48 volumio-niclas systemd[1]: Started File System Check Daemon to report status. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Load/Save RF Kill Switch Status... Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Mounting /boot... Jun 04 20:46:48 volumio-niclas systemd[1]: Created slice system-bthelper.slice. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Show Plymouth Boot Screen... Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Load/Save RF Kill Switch Status. Jun 04 20:46:48 volumio-niclas systemd[1]: Mounted /boot. Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target Local File Systems. Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Create Volatile Files and Directories... Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Raise network interfaces... Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Preprocess NFS configuration... Jun 04 20:46:48 volumio-niclas systemd[1]: Started ifup for eth0. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jun 04 20:46:48 volumio-niclas systemd[1]: Started ifup for wlan0. Jun 04 20:46:48 volumio-niclas systemd[1]: plymouth-start.service: Succeeded. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Show Plymouth Boot Screen. Jun 04 20:46:48 volumio-niclas systemd[1]: Received SIGRTMIN+20 from PID 221 (plymouthd). Jun 04 20:46:48 volumio-niclas systemd[1]: plymouth-read-write.service: Succeeded. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jun 04 20:46:48 volumio-niclas systemd[1]: nfs-config.service: Succeeded. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Preprocess NFS configuration. Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target NFS client services. Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 04 20:46:48 volumio-niclas kernel: Bluetooth: hci0: BCM: features 0x2f Jun 04 20:46:48 volumio-niclas kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jun 04 20:46:48 volumio-niclas kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jun 04 20:46:48 volumio-niclas systemd[1]: Started Create Volatile Files and Directories. Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting RPC bind portmap service... Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jun 04 20:46:48 volumio-niclas kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 20:46:48 volumio-niclas systemd[1]: Started Update UTMP about System Boot/Shutdown. Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target System Initialization. Jun 04 20:46:48 volumio-niclas systemd[1]: apt-daily.timer: Not using persistent file timestamp Thu 2024-10-03 09:36:54 CEST as it is in the future. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Daily apt download activities. Jun 04 20:46:48 volumio-niclas systemd[1]: Listening on D-Bus System Message Bus Socket. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Daily Cleanup of Temporary Directories. Jun 04 20:46:48 volumio-niclas kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 04 20:46:48 volumio-niclas systemd[1]: Listening on triggerhappy.socket. Jun 04 20:46:48 volumio-niclas systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Thu 2024-10-03 09:36:54 CEST as it is in the future. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Daily apt upgrade and clean activities. Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target Timers. Jun 04 20:46:48 volumio-niclas systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target Sockets. Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target Basic System. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Volumio Iptables Module. Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Login Service... Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Wireless Services... Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting dhcpcd on all interfaces... Jun 04 20:46:48 volumio-niclas systemd[1]: Started Manage Sound Card State (restore and store). Jun 04 20:46:48 volumio-niclas alsactl[632]: alsactl 1.1.8 daemon started Jun 04 20:46:48 volumio-niclas dhcpcd[628]: Not running dhcpcd because /etc/network/interfaces Jun 04 20:46:48 volumio-niclas dhcpcd[628]: defines some interfaces that will use a Jun 04 20:46:48 volumio-niclas dhcpcd[628]: DHCP client or static address Jun 04 20:46:48 volumio-niclas systemd[1]: Started volumio-remote-updater.service. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Volumio Log Rotation Service. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting triggerhappy global hotkey daemon... Jun 04 20:46:48 volumio-niclas systemd[1]: Started UPnP Renderer front-end to MPD. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Save/Restore Sound Card State... Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jun 04 20:46:48 volumio-niclas systemd[1]: Started D-Bus System Message Bus. Jun 04 20:46:48 volumio-niclas systemd[1]: Starting WPA supplicant... Jun 04 20:46:48 volumio-niclas systemd[1]: Started RPC bind portmap service. Jun 04 20:46:48 volumio-niclas systemd[1]: hciuart.service: Succeeded. Jun 04 20:46:48 volumio-niclas systemd[1]: Started Configure Bluetooth Modems connected by UART. Jun 04 20:46:48 volumio-niclas systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jun 04 20:46:48 volumio-niclas systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jun 04 20:46:48 volumio-niclas systemd[1]: Failed to start dhcpcd on all interfaces. Jun 04 20:46:48 volumio-niclas sh[526]: eth0: waiting for carrier Jun 04 20:46:48 volumio-niclas dhcpcd[573]: eth0: waiting for carrier Jun 04 20:46:48 volumio-niclas systemd-logind[625]: New seat seat0. Jun 04 20:46:48 volumio-niclas thd[644]: Unable to parse trigger line: Jun 04 20:46:49 volumio-niclas kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jun 04 20:46:49 volumio-niclas kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jun 04 20:46:49 volumio-niclas thd[644]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 04 20:46:49 volumio-niclas thd[644]: Unable to parse trigger line: Jun 04 20:46:49 volumio-niclas thd[644]: Found socket passed from systemd Jun 04 20:46:49 volumio-niclas systemd[1]: Started triggerhappy global hotkey daemon. Jun 04 20:46:49 volumio-niclas systemd[1]: Started Save/Restore Sound Card State. Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target Sound Card. Jun 04 20:46:49 volumio-niclas systemd[1]: Starting Raspberry Pi bluetooth helper... Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target Remote File Systems (Pre). Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target Remote File Systems. Jun 04 20:46:49 volumio-niclas systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target RPC Port Mapper. Jun 04 20:46:49 volumio-niclas sh[529]: wlan0=wlan0 Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: Successfully dropped root privileges. Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: avahi-daemon 0.7 starting up. Jun 04 20:46:49 volumio-niclas bthelper[656]: Raspberry Pi BDADDR already set Jun 04 20:46:49 volumio-niclas systemd[1]: Started Raspberry Pi bluetooth helper. Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: Successfully called chroot(). Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: Successfully dropped remaining capabilities. Jun 04 20:46:49 volumio-niclas wpa_supplicant[655]: Successfully initialized wpa_supplicant Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: Loading service file /services/volumio.service. Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: Network interface enumeration completed. Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: Server startup complete. Host name is volumio-niclas.local. Local service cookie is 3819330633. Jun 04 20:46:49 volumio-niclas avahi-daemon[649]: Service "Volumio Niclas" (/services/volumio.service) successfully established. Jun 04 20:46:49 volumio-niclas systemd[1]: Started Raise network interfaces. Jun 04 20:46:49 volumio-niclas systemd[1]: Started Login Service. Jun 04 20:46:49 volumio-niclas systemd[1]: Started WPA supplicant. Jun 04 20:46:49 volumio-niclas systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target Network. Jun 04 20:46:50 volumio-niclas systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Permit User Sessions... Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Network Time Service... Jun 04 20:46:50 volumio-niclas systemd[1]: Reached target Network is Online. Jun 04 20:46:50 volumio-niclas volumio-remote-updater[636]: Error: No active session Jun 04 20:46:50 volumio-niclas systemd[1]: Starting LSB: Brings up/down network automatically... Jun 04 20:46:50 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:46:50] [info] asio async_connect error: system:111 (Connection refused) Jun 04 20:46:50 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:46:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 20:46:50 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:46:50] [error] handle_connect error: Underlying Transport Error Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Samba NMB Daemon... Jun 04 20:46:50 volumio-niclas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 20:46:50 volumio-niclas systemd[1]: Starting /etc/rc.local Compatibility... Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Bluetooth service... Jun 04 20:46:50 volumio-niclas systemd[1]: Started /etc/rc.local Compatibility. Jun 04 20:46:50 volumio-niclas systemd[1]: Started Permit User Sessions. Jun 04 20:46:50 volumio-niclas systemd[1]: iptables.service: Succeeded. Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Hold until boot process finishes up... Jun 04 20:46:50 volumio-niclas volumio[647]: Could not open config: /tmp/upmpdcli.conf Jun 04 20:46:50 volumio-niclas systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 04 20:46:50 volumio-niclas systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 04 20:46:50 volumio-niclas systemd[1]: Received SIGRTMIN+21 from PID 221 (plymouthd). Jun 04 20:46:50 volumio-niclas systemd[1]: Received SIGRTMIN+21 from PID 221 (plymouthd). Jun 04 20:46:50 volumio-niclas systemd[1]: plymouth-quit.service: Succeeded. Jun 04 20:46:50 volumio-niclas systemd[1]: Started Terminate Plymouth Boot Screen. Jun 04 20:46:50 volumio-niclas systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 04 20:46:50 volumio-niclas systemd[1]: Started Hold until boot process finishes up. Jun 04 20:46:50 volumio-niclas systemd[1]: Started Getty on tty1. Jun 04 20:46:50 volumio-niclas ifplugd(eth0)[768]: ifplugd 0.28 initializing. Jun 04 20:46:50 volumio-niclas systemd[1]: Reached target Login Prompts. Jun 04 20:46:50 volumio-niclas ifplugd(eth0)[768]: Using interface eth0/DC:A6:32:17:AA:8A with driver (version: 6.1.77-v7l+) Jun 04 20:46:50 volumio-niclas ifplugd(eth0)[768]: Using detection mode: SIOCETHTOOL Jun 04 20:46:50 volumio-niclas ifplugd(eth0)[768]: Initialization complete, link beat not detected. Jun 04 20:46:50 volumio-niclas ifplugd[724]: Network Interface Plugging Daemon...start eth0...done. Jun 04 20:46:50 volumio-niclas systemd[1]: Started LSB: Brings up/down network automatically. Jun 04 20:46:50 volumio-niclas loadcpufreq[659]: Loading cpufreq kernel modules...done (none). Jun 04 20:46:50 volumio-niclas ntpd[721]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 04 20:46:50 volumio-niclas ntpd[721]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 04 20:46:50 volumio-niclas systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 04 20:46:50 volumio-niclas systemd[1]: Started Network Time Service. Jun 04 20:46:50 volumio-niclas bluetoothd[733]: Bluetooth daemon 5.50 Jun 04 20:46:50 volumio-niclas ntpd[774]: proto: precision = 2.111 usec (-19) Jun 04 20:46:50 volumio-niclas bluetoothd[733]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 04 20:46:50 volumio-niclas systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 04 20:46:50 volumio-niclas systemd[1]: Started Bluetooth service. Jun 04 20:46:50 volumio-niclas ntpd[774]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 04 20:46:50 volumio-niclas ntpd[774]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 04 20:46:50 volumio-niclas ntpd[774]: Listen and drop on 0 v6wildcard [::]:123 Jun 04 20:46:50 volumio-niclas ntpd[774]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 04 20:46:50 volumio-niclas ntpd[774]: Listen normally on 2 lo 127.0.0.1:123 Jun 04 20:46:50 volumio-niclas ntpd[774]: Listening on routing socket on fd #19 for interface updates Jun 04 20:46:50 volumio-niclas bluetoothd[733]: Starting SDP server Jun 04 20:46:50 volumio-niclas bluetoothd[733]: Excluding (cli) sap Jun 04 20:46:50 volumio-niclas ntpd[774]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 04 20:46:50 volumio-niclas ntpd[774]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 04 20:46:50 volumio-niclas systemd[1]: Reached target Bluetooth. Jun 04 20:46:50 volumio-niclas kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 04 20:46:50 volumio-niclas kernel: Bluetooth: BNEP filters: protocol multicast Jun 04 20:46:50 volumio-niclas kernel: Bluetooth: BNEP socket layer initialized Jun 04 20:46:50 volumio-niclas bluetoothd[733]: Bluetooth management interface 1.22 initialized Jun 04 20:46:50 volumio-niclas dbus-daemon[650]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=733 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 04 20:46:50 volumio-niclas kernel: Bluetooth: MGMT ver 1.22 Jun 04 20:46:50 volumio-niclas bluetoothd[733]: Failed to set privacy: Rejected (0x0b) Jun 04 20:46:50 volumio-niclas cpufrequtils[775]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 04 20:46:50 volumio-niclas systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 04 20:46:51 volumio-niclas systemd[1]: Starting Hostname Service... Jun 04 20:46:51 volumio-niclas dbus-daemon[650]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 04 20:46:51 volumio-niclas systemd[1]: Started Hostname Service. Jun 04 20:46:51 volumio-niclas nmbd[736]: [2024/06/04 20:46:51.298918, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 04 20:46:51 volumio-niclas nmbd[736]: started asyncdns process 798 Jun 04 20:46:51 volumio-niclas nmbd[736]: [2024/06/04 20:46:51.302639, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 04 20:46:51 volumio-niclas nmbd[736]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 04 20:46:51 volumio-niclas nmbd[736]: [2024/06/04 20:46:51.302770, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 04 20:46:51 volumio-niclas nmbd[736]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 04 20:46:51 volumio-niclas haveged[561]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 04 20:46:51 volumio-niclas haveged[561]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jun 04 20:46:51 volumio-niclas haveged[561]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00235 Jun 04 20:46:51 volumio-niclas haveged[561]: haveged: fills: 0, generated: 0 Jun 04 20:46:51 volumio-niclas wireless.js[626]: Cleaning previous... Jun 04 20:46:51 volumio-niclas ntpd[774]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 04 20:46:51 volumio-niclas sudo[806]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 04 20:46:51 volumio-niclas sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:51 volumio-niclas sudo[806]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:51 volumio-niclas sudo[808]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 04 20:46:51 volumio-niclas sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:52 volumio-niclas sudo[808]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:52 volumio-niclas wireless.js[626]: Stopped aP Jun 04 20:46:52 volumio-niclas sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 04 20:46:52 volumio-niclas sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:52 volumio-niclas sudo[815]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:52 volumio-niclas kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 20:46:52 volumio-niclas sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 04 20:46:52 volumio-niclas sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:52 volumio-niclas sudo[817]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:52 volumio-niclas sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 04 20:46:52 volumio-niclas sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:52 volumio-niclas sudo[824]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:52 volumio-niclas sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 04 20:46:52 volumio-niclas sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:52 volumio-niclas ntpd[774]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 04 20:46:53 volumio-niclas systemd[1]: systemd-rfkill.service: Succeeded. Jun 04 20:46:53 volumio-niclas ntpd[774]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 04 20:46:54 volumio-niclas bthelper[656]: Changing power off succeeded Jun 04 20:46:54 volumio-niclas ntpd[774]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 04 20:46:54 volumio-niclas bthelper[656]: [CHG] Controller DC:A6:32:17:AA:8E Class: 0x0000041c Jun 04 20:46:54 volumio-niclas bthelper[656]: Changing power on succeeded Jun 04 20:46:55 volumio-niclas sudo[826]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:55 volumio-niclas wireless.js[626]: SETTING APPROPRIATE REG DOMAIN: DE Jun 04 20:46:55 volumio-niclas sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 04 20:46:55 volumio-niclas sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:55 volumio-niclas sudo[837]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:55 volumio-niclas sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jun 04 20:46:55 volumio-niclas sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:55 volumio-niclas sudo[839]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:55 volumio-niclas wireless.js[626]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jun 04 20:46:55 volumio-niclas wireless.js[626]: Start wireless flow Jun 04 20:46:55 volumio-niclas wireless.js[626]: Stopped hotspot (if there).. Jun 04 20:46:55 volumio-niclas sudo[844]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 04 20:46:55 volumio-niclas sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:55 volumio-niclas sudo[844]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:55 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:46:55] [info] asio async_connect error: system:111 (Connection refused) Jun 04 20:46:55 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:46:55] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 20:46:55 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:46:55] [error] handle_connect error: Underlying Transport Error Jun 04 20:46:55 volumio-niclas sudo[846]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 04 20:46:55 volumio-niclas sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:55 volumio-niclas sudo[846]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:55 volumio-niclas wireless.js[626]: DHCP IP Jun 04 20:46:55 volumio-niclas wireless.js[626]: Start ap Jun 04 20:46:55 volumio-niclas wpa_supplicant[848]: Successfully initialized wpa_supplicant Jun 04 20:46:55 volumio-niclas sudo[849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 04 20:46:55 volumio-niclas sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:55 volumio-niclas dhcpcd[850]: dev: loaded udev Jun 04 20:46:55 volumio-niclas kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 20:46:55 volumio-niclas dhcpcd-run-hooks[868]: wlan0: starting wpa_supplicant Jun 04 20:46:56 volumio-niclas dhcpcd-run-hooks[873]: wlan0: failed to start wpa_supplicant Jun 04 20:46:56 volumio-niclas 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 Jun 04 20:46:56 volumio-niclas dhcpcd[850]: wlan0: connected to Access Point `' Jun 04 20:46:56 volumio-niclas dhcpcd[850]: no interfaces have a carrier Jun 04 20:46:56 volumio-niclas dhcpcd[850]: forked to background, child pid 884 Jun 04 20:46:56 volumio-niclas dhcpcd[884]: eth0: waiting for carrier Jun 04 20:46:56 volumio-niclas dhcpcd[884]: wlan0: waiting for carrier Jun 04 20:46:56 volumio-niclas sudo[849]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:56 volumio-niclas wireless.js[626]: trying... Jun 04 20:46:56 volumio-niclas sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:46:56 volumio-niclas sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:56 volumio-niclas sudo[886]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:57 volumio-niclas wireless.js[626]: trying... Jun 04 20:46:57 volumio-niclas sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:46:57 volumio-niclas sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:57 volumio-niclas sudo[889]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:58 volumio-niclas wpa_supplicant[872]: wlan0: Trying to associate with SSID 'Elbenwald' Jun 04 20:46:58 volumio-niclas wireless.js[626]: trying... Jun 04 20:46:58 volumio-niclas sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:46:58 volumio-niclas sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:58 volumio-niclas sudo[892]: pam_unix(sudo:session): session closed for user root Jun 04 20:46:59 volumio-niclas wireless.js[626]: trying... Jun 04 20:46:59 volumio-niclas sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:46:59 volumio-niclas sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:46:59 volumio-niclas sudo[895]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:00 volumio-niclas wireless.js[626]: trying... Jun 04 20:47:00 volumio-niclas sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:47:00 volumio-niclas sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:00 volumio-niclas sudo[898]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:00 volumio-niclas dhcpcd[884]: wlan0: carrier acquired Jun 04 20:47:00 volumio-niclas dhcpcd[884]: wlan0: connected to Access Point `Elbenwald' Jun 04 20:47:00 volumio-niclas wpa_supplicant[872]: wlan0: Associated with d4:24:dd:97:f4:29 Jun 04 20:47:00 volumio-niclas wpa_supplicant[872]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:24:dd:97:f4:29 completed [id=0 id_str=] Jun 04 20:47:00 volumio-niclas wpa_supplicant[872]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 04 20:47:00 volumio-niclas wpa_supplicant[872]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 04 20:47:00 volumio-niclas dhcpcd[884]: DUID 00:01:00:01:2d:f2:1e:49:dc:a6:32:17:aa:8a Jun 04 20:47:00 volumio-niclas dhcpcd[884]: wlan0: IAID 32:17:aa:8c Jun 04 20:47:00 volumio-niclas dhcpcd[884]: wlan0: adding address fe80::5305:fd61:e725:d861 Jun 04 20:47:00 volumio-niclas dhcpcd[884]: ipv6_addaddr1: Permission denied Jun 04 20:47:00 volumio-niclas dhcpcd[884]: wlan0: carrier lost Jun 04 20:47:00 volumio-niclas dhcpcd[884]: wlan0: carrier acquired Jun 04 20:47:00 volumio-niclas dhcpcd[884]: wlan0: IAID 32:17:aa:8c Jun 04 20:47:01 volumio-niclas dhcpcd[884]: wlan0: soliciting an IPv6 router Jun 04 20:47:01 volumio-niclas dhcpcd[884]: wlan0: rebinding lease of 192.168.178.56 Jun 04 20:47:01 volumio-niclas dhcpcd[884]: wlan0: probing address 192.168.178.56/24 Jun 04 20:47:01 volumio-niclas wireless.js[626]: trying... Jun 04 20:47:01 volumio-niclas sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:47:01 volumio-niclas sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:01 volumio-niclas sudo[913]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:02 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:47:02] [info] asio async_connect error: system:111 (Connection refused) Jun 04 20:47:02 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:47:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 20:47:02 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:47:02] [error] handle_connect error: Underlying Transport Error Jun 04 20:47:02 volumio-niclas wireless.js[626]: trying... Jun 04 20:47:02 volumio-niclas sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:47:02 volumio-niclas sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:02 volumio-niclas sudo[916]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:03 volumio-niclas wireless.js[626]: trying... Jun 04 20:47:03 volumio-niclas sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:47:03 volumio-niclas sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:03 volumio-niclas sudo[919]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:04 volumio-niclas wireless.js[626]: trying... Jun 04 20:47:04 volumio-niclas sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:47:04 volumio-niclas sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:04 volumio-niclas sudo[922]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:05 volumio-niclas wireless.js[626]: trying... Jun 04 20:47:05 volumio-niclas sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:47:05 volumio-niclas sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:05 volumio-niclas sudo[925]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:06 volumio-niclas dhcpcd[884]: wlan0: leased 192.168.178.56 for 864000 seconds Jun 04 20:47:06 volumio-niclas avahi-daemon[649]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.56. Jun 04 20:47:06 volumio-niclas avahi-daemon[649]: New relevant interface wlan0.IPv4 for mDNS. Jun 04 20:47:06 volumio-niclas dhcpcd[884]: wlan0: adding route to 192.168.178.0/24 Jun 04 20:47:06 volumio-niclas avahi-daemon[649]: Registering new address record for 192.168.178.56 on wlan0.IPv4. Jun 04 20:47:06 volumio-niclas dhcpcd[884]: wlan0: adding default route via 192.168.178.1 Jun 04 20:47:06 volumio-niclas wireless.js[626]: trying... Jun 04 20:47:06 volumio-niclas sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 20:47:06 volumio-niclas sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:06 volumio-niclas sudo[949]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:06 volumio-niclas wireless.js[626]: Connected to: ----Elbenwald Jun 04 20:47:06 volumio-niclas wireless.js[626]: ---- Jun 04 20:47:06 volumio-niclas sudo[952]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 20:47:06 volumio-niclas sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:06 volumio-niclas sudo[952]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:06 volumio-niclas wireless.js[626]: ... joined AP, wlan0 IPv4 is 192.168.178.56, ipV6 is undefined Jun 04 20:47:06 volumio-niclas wireless.js[626]: It's done! AP Jun 04 20:47:06 volumio-niclas kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 20:47:06 volumio-niclas systemd[1]: Started Wireless Services. Jun 04 20:47:06 volumio-niclas systemd[1]: Started Volumio Backend Module. Jun 04 20:47:06 volumio-niclas systemd[1]: Started Volumio Cpu Tweaker. Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[958]: Setting RT Priority for mpd Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[958]: pid 35's current scheduling policy: SCHED_OTHER Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[958]: pid 35's current scheduling priority: 0 Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[958]: Setting MPD Affinity Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[958]: pid 3's current affinity mask: f Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[958]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[958]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 04 20:47:06 volumio-niclas systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 04 20:47:07 volumio-niclas ntpd[774]: Listen normally on 3 wlan0 192.168.178.56:123 Jun 04 20:47:07 volumio-niclas ntpd[774]: new interface(s) found: waking up resolver Jun 04 20:47:08 volumio-niclas volumio[957]: info: ------------------------------------------- Jun 04 20:47:08 volumio-niclas volumio[957]: info: ----- Volumio3 ---- Jun 04 20:47:08 volumio-niclas volumio[957]: info: ------------------------------------------- Jun 04 20:47:08 volumio-niclas volumio[957]: info: ----- System startup ---- Jun 04 20:47:08 volumio-niclas volumio[957]: info: ------------------------------------------- Jun 04 20:47:09 volumio-niclas volumio[957]: info: MYVOLUMIO Environment detected Jun 04 20:47:09 volumio-niclas volumio[957]: info: Plugin folders cleanup Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning into folder /volumio/app/plugins/ Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning category audio_interface Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning category miscellanea Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning category music_service Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning category plugins.json Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning category system_controller Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning category user_interface Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning into folder /data/plugins/ Jun 04 20:47:09 volumio-niclas volumio[957]: info: Scanning category music_service Jun 04 20:47:09 volumio-niclas volumio[957]: info: Plugin folders cleanup completed Jun 04 20:47:09 volumio-niclas volumio[957]: info: ------------------------------------------- Jun 04 20:47:09 volumio-niclas volumio[957]: info: ----- Core plugins startup ---- Jun 04 20:47:09 volumio-niclas volumio[957]: info: ------------------------------------------- Jun 04 20:47:09 volumio-niclas volumio[957]: info: Loading plugins from folder /volumio/app/plugins/ Jun 04 20:47:09 volumio-niclas volumio[957]: info: Adding plugin upnp to MyMusic Plugins Jun 04 20:47:09 volumio-niclas volumio[957]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 04 20:47:09 volumio-niclas volumio[957]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 04 20:47:09 volumio-niclas volumio[957]: info: Loading plugins from folder /data/plugins/ Jun 04 20:47:09 volumio-niclas volumio[957]: info: Loading plugin "system"... Jun 04 20:47:09 volumio-niclas volumio[957]: info: Loading plugin "appearance"... Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "network"... Jun 04 20:47:10 volumio-niclas volumio[957]: info: Refreshing Cached IP Addresses Jun 04 20:47:10 volumio-niclas sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 20:47:10 volumio-niclas sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:10 volumio-niclas sudo[988]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "services"... Jun 04 20:47:10 volumio-niclas sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 20:47:10 volumio-niclas sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "alsa_controller"... Jun 04 20:47:10 volumio-niclas sudo[990]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:10 volumio-niclas sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 04 20:47:10 volumio-niclas sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:10 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "wizard"... Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "networkfs"... Jun 04 20:47:10 volumio-niclas volumio[957]: info: Starting Udev Watcher for removable devices Jun 04 20:47:10 volumio-niclas sudo[1001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumio,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.12/Multimedia/Musik/Alben /mnt/NAS/Datenzwerg Jun 04 20:47:10 volumio-niclas sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:10 volumio-niclas volumio[957]: info: Ignoring mount for partition: boot Jun 04 20:47:10 volumio-niclas volumio[957]: info: Ignoring mount for partition: volumio Jun 04 20:47:10 volumio-niclas volumio[957]: info: Ignoring mount for partition: volumio_data Jun 04 20:47:10 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "volumio_command_line_client"... Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "upnp"... Jun 04 20:47:10 volumio-niclas volumio[957]: info: [1717526830411] Starting Upmpd Daemon Jun 04 20:47:10 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "my_music"... Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "mpd"... Jun 04 20:47:10 volumio-niclas kernel: Key type cifs.spnego registered Jun 04 20:47:10 volumio-niclas kernel: Key type cifs.idmap registered Jun 04 20:47:10 volumio-niclas 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. Jun 04 20:47:10 volumio-niclas kernel: CIFS: Attempting to mount \\192.168.178.12\Multimedia Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "upnp_browser"... Jun 04 20:47:10 volumio-niclas volumio[957]: info: Loading plugin "alarm-clock"... Jun 04 20:47:11 volumio-niclas volumio[957]: info: Loading plugin "airplay_emulation"... Jun 04 20:47:11 volumio-niclas volumio[957]: info: Starting Shairport Sync Jun 04 20:47:11 volumio-niclas volumio[957]: info: Loading plugin "last_100"... Jun 04 20:47:11 volumio-niclas volumio[957]: info: Loading plugin "webradio"... Jun 04 20:47:11 volumio-niclas volumio[957]: info: Loading plugin "i2s_dacs"... Jun 04 20:47:11 volumio-niclas volumio[957]: info: I2S DAC not set, start Auto-detection Jun 04 20:47:11 volumio-niclas volumio[957]: info: Loading plugin "volumiodiscovery"... Jun 04 20:47:11 volumio-niclas volumio[957]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 20:47:11 volumio-niclas volumio[957]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 20:47:11 volumio-niclas node[957]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 20:47:11 volumio-niclas volumio[957]: *** WARNING *** For more information see Jun 04 20:47:11 volumio-niclas volumio[957]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 20:47:11 volumio-niclas volumio[957]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 20:47:11 volumio-niclas volumio[957]: *** WARNING *** For more information see Jun 04 20:47:11 volumio-niclas node[957]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 20:47:11 volumio-niclas node[957]: *** WARNING *** For more information see Jun 04 20:47:11 volumio-niclas node[957]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 20:47:11 volumio-niclas node[957]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 20:47:11 volumio-niclas node[957]: *** WARNING *** For more information see Jun 04 20:47:11 volumio-niclas volumio[957]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 04 20:47:11 volumio-niclas volumio[957]: info: Discovery: Started advertising with name: Volumio Niclas Jun 04 20:47:11 volumio-niclas nmbd[736]: [2024/06/04 20:47:11.306391, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 20:47:11 volumio-niclas nmbd[736]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 04 20:47:11 volumio-niclas systemd[1]: Started Samba NMB Daemon. Jun 04 20:47:11 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 20:47:11 volumio-niclas volumio[957]: info: Loading plugin "spop"... Jun 04 20:47:11 volumio-niclas nmbd[736]: [2024/06/04 20:47:11.330525, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 04 20:47:11 volumio-niclas nmbd[736]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.56 for name WORKGROUP<1d>. Jun 04 20:47:11 volumio-niclas nmbd[736]: This response was from IP 192.168.178.35, reporting an IP address of 192.168.178.35. Jun 04 20:47:11 volumio-niclas systemd[1]: Starting Samba Winbind Daemon... Jun 04 20:47:11 volumio-niclas winbindd[1016]: [2024/06/04 20:47:11.564270, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 04 20:47:11 volumio-niclas winbindd[1016]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 04 20:47:11 volumio-niclas systemd[1]: Started Samba Winbind Daemon. Jun 04 20:47:11 volumio-niclas winbindd[1016]: [2024/06/04 20:47:11.582072, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 20:47:11 volumio-niclas winbindd[1016]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 04 20:47:11 volumio-niclas systemd[1]: Starting Samba SMB Daemon... Jun 04 20:47:11 volumio-niclas winbindd[1016]: [2024/06/04 20:47:11.955670, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jun 04 20:47:11 volumio-niclas winbindd[1016]: res_names->count = 3, expected 5 Jun 04 20:47:11 volumio-niclas smbd[1022]: [2024/06/04 20:47:11.972788, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 20:47:11 volumio-niclas smbd[1022]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 04 20:47:11 volumio-niclas systemd[1]: Started Samba SMB Daemon. Jun 04 20:47:11 volumio-niclas systemd[1]: Reached target Multi-User System. Jun 04 20:47:11 volumio-niclas systemd[1]: Reached target Graphical Interface. Jun 04 20:47:12 volumio-niclas systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 04 20:47:12 volumio-niclas systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 04 20:47:12 volumio-niclas systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 04 20:47:12 volumio-niclas systemd[1]: Startup finished in 14.221s (kernel) + 29.119s (userspace) = 43.341s. Jun 04 20:47:12 volumio-niclas wpa_supplicant[872]: RRM: Ignoring radio measurement request: Not RRM network Jun 04 20:47:12 volumio-niclas volumio[957]: info: Loading plugin "outputs"... Jun 04 20:47:12 volumio-niclas volumio[957]: info: Loading plugin "albumart"... Jun 04 20:47:12 volumio-niclas volumio[957]: info: Plugin example_plugin is not enabled Jun 04 20:47:12 volumio-niclas volumio[957]: info: Loading plugin "inputs"... Jun 04 20:47:12 volumio-niclas volumio[957]: info: Loading plugin "updater_comm"... Jun 04 20:47:12 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:47:12] [connect] Successful connection Jun 04 20:47:12 volumio-niclas volumio[957]: info: Plugin mpdemulation is not enabled Jun 04 20:47:12 volumio-niclas volumio[957]: info: Loading plugin "rest_api"... Jun 04 20:47:12 volumio-niclas volumio[957]: info: Loading plugin "websocket"... Jun 04 20:47:12 volumio-niclas volumio[957]: info: Starting Socket.io Server version 2.3.0 Jun 04 20:47:12 volumio-niclas volumio[957]: info: Loading i18n strings for locale de Jun 04 20:47:12 volumio-niclas volumio[957]: Updating browse sources language Jun 04 20:47:12 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 20:47:12 volumio-niclas volumio[957]: Forking 3 albumart workers Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::initPlayerControls Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: Express server listening on port 3000 Jun 04 20:47:13 volumio-niclas volumio[957]: [Metrics] WebUI: 5s 114.10ms Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::resetVolumioState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::getcurrentVolume Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 20:47:13 volumio-niclas volumio[957]: verbose: New Socket.io Connection to 192.168.178.56:3000 from 192.168.178.62 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 04 20:47:13 volumio-niclas volumio-remote-updater[636]: [2024-06-04 20:47:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1717526832 101 Jun 04 20:47:13 volumio-niclas volumio[957]: 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: 2 Jun 04 20:47:13 volumio-niclas volumio[957]: info: Reloading queue from file Jun 04 20:47:13 volumio-niclas volumio[957]: Starting albumart workers Jun 04 20:47:13 volumio-niclas volumio[957]: Starting albumart workers Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::setRepeat false single undefined Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::pushState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CorePlayQueue::getTrack 0 Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioPushState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::setRandom false Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::pushState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CorePlayQueue::getTrack 0 Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioPushState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 04 20:47:13 volumio-niclas volumio[957]: info: VolumeController:: Volume=100 Mute =false Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::pushState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CorePlayQueue::getTrack 0 Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioPushState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::updateTrackBlock Jun 04 20:47:13 volumio-niclas volumio[957]: info: CorePlayQueue::getTrackBlock Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 20:47:13 volumio-niclas volumio[957]: Starting albumart workers Jun 04 20:47:13 volumio-niclas volumio[957]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 04 20:47:13 volumio-niclas volumio[957]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jun 04 20:47:13 volumio-niclas volumio[957]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 04 20:47:13 volumio-niclas volumio[957]: info: Setting Device type: Raspberry PI Jun 04 20:47:13 volumio-niclas volumio[957]: info: Discovery: adding 5a0c4c77-43bb-4b6b-adc2-1ba3ded445b8 Jun 04 20:47:13 volumio-niclas volumio[957]: info: Discovery: Found device Volumio Niclas Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioGetState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CorePlayQueue::getTrack 0 Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioGetState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CorePlayQueue::getTrack 0 Jun 04 20:47:13 volumio-niclas volumio[957]: info: VolumeController:: Volume=100 Mute =false Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreStateMachine::pushState Jun 04 20:47:13 volumio-niclas volumio[957]: info: CorePlayQueue::getTrack 0 Jun 04 20:47:13 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioPushState Jun 04 20:47:13 volumio-niclas sudo[993]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:14 volumio-niclas wpa_supplicant[872]: RRM: Ignoring radio measurement request: Not RRM network Jun 04 20:47:16 volumio-niclas sudo[1001]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:16 volumio-niclas volumio[957]: info: Cannot mount NAS Datenzwerg at system boot, trial number 1 ,retrying in 5 seconds Jun 04 20:47:16 volumio-niclas volumio[957]: info: Completed loading Core Plugins Jun 04 20:47:16 volumio-niclas volumio[957]: info: Preparing to generate the ALSA configuration file Jun 04 20:47:16 volumio-niclas kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 04 20:47:16 volumio-niclas kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jun 04 20:47:16 volumio-niclas volumio[957]: info: Asound.conf file unchanged, so no further update is needed Jun 04 20:47:16 volumio-niclas volumio[957]: info: Output device has changed, restarting MPD Jun 04 20:47:16 volumio-niclas sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 20:47:16 volumio-niclas sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:16 volumio-niclas volumio[957]: info: Output device has changed, restarting Shairport Sync Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 20:47:16 volumio-niclas sudo[1097]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:16 volumio-niclas sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 20:47:16 volumio-niclas sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:16 volumio-niclas volumio[957]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 20:47:16 volumio-niclas volumio[957]: info: ___________ START PLUGINS ___________ Jun 04 20:47:16 volumio-niclas volumio[957]: info: ControllerMpd::onStart: Initializing MPD Jun 04 20:47:16 volumio-niclas volumio[957]: info: Creating MPD Configuration file Jun 04 20:47:16 volumio-niclas sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 20:47:16 volumio-niclas sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 20:47:16 volumio-niclas volumio[957]: info: [1717526836892] CoreMusicLibrary::Adding element Medienserver Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 20:47:16 volumio-niclas sudo[1107]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:16 volumio-niclas sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 20:47:16 volumio-niclas sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:16 volumio-niclas systemd[1]: Listening on mpd.socket. Jun 04 20:47:16 volumio-niclas systemd[1]: Starting Music Player Daemon... Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 20:47:16 volumio-niclas systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 04 20:47:16 volumio-niclas systemd[1]: mpd.service: Succeeded. Jun 04 20:47:16 volumio-niclas systemd[1]: Stopped Music Player Daemon. Jun 04 20:47:16 volumio-niclas systemd[1]: Starting Music Player Daemon... Jun 04 20:47:16 volumio-niclas volumio[957]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 20:47:16 volumio-niclas sudo[1116]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 04 20:47:16 volumio-niclas volumio[957]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 20:47:16 volumio-niclas sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 20:47:16 volumio-niclas volumio[957]: info: [1717526836994] CoreMusicLibrary::Adding element Last_100 Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 20:47:16 volumio-niclas volumio[957]: info: [1717526836997] CoreMusicLibrary::Adding element Webradio Jun 04 20:47:16 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 20:47:16 volumio-niclas sudo[1116]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 04 20:47:16 volumio-niclas sudo[1116]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 20:47:17 volumio-niclas volumio[957]: info: Initializing BBC Radios Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: Creating Spotify config file Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: Volumio Calling Home Jun 04 20:47:17 volumio-niclas volumio[957]: info: MPD Permissions set Jun 04 20:47:17 volumio-niclas volumio[957]: info: MPD Permissions set Jun 04 20:47:17 volumio-niclas volumio[957]: info: Spotify config file written Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 04 20:47:17 volumio-niclas sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 20:47:17 volumio-niclas volumio[957]: info: Volumio called home Jun 04 20:47:17 volumio-niclas volumio[957]: info: Starting Shairport Sync Jun 04 20:47:17 volumio-niclas systemd[1]: Started go-librespot Daemon. Jun 04 20:47:17 volumio-niclas go-librespot[1146]: Librespot-go daemon starting... Jun 04 20:47:17 volumio-niclas sudo[1136]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:17 volumio-niclas volumio[957]: info: Starting Shairport Sync Jun 04 20:47:17 volumio-niclas volumio[957]: info: Starting Shairport Sync Jun 04 20:47:17 volumio-niclas sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 20:47:17 volumio-niclas sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:17 volumio-niclas sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 20:47:17 volumio-niclas sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:17 volumio-niclas systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 20:47:17 volumio-niclas sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 20:47:17 volumio-niclas sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:17 volumio-niclas systemd[1]: shairport-sync.service: Succeeded. Jun 04 20:47:17 volumio-niclas systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 20:47:17 volumio-niclas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 20:47:17 volumio-niclas volumio[957]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 04 20:47:17 volumio-niclas sudo[1149]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:17 volumio-niclas volumio[957]: info: Shairport-Sync Started Jun 04 20:47:17 volumio-niclas volumio[957]: Error adding Membership: Error: addMembership EINVAL Jun 04 20:47:17 volumio-niclas systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 20:47:17 volumio-niclas systemd[1]: shairport-sync.service: Succeeded. Jun 04 20:47:17 volumio-niclas systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 20:47:17 volumio-niclas volumio[957]: info: An error occurred while refreshing Spotify Token Error: certificate is not yet valid Jun 04 20:47:17 volumio-niclas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 20:47:17 volumio-niclas sudo[1154]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:17 volumio-niclas sudo[1151]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:17 volumio-niclas volumio[957]: info: Shairport-Sync Started Jun 04 20:47:17 volumio-niclas volumio[957]: info: Shairport-Sync Started Jun 04 20:47:17 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioGetState Jun 04 20:47:17 volumio-niclas volumio[957]: info: CorePlayQueue::getTrack 0 Jun 04 20:47:17 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:17+02:00" level=info msg="generated new device id: 2cd5851efce60759f109656de5a41820ada1597d" Jun 04 20:47:17 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:17+02:00" level=debug msg="stored credentials found for l5d20ev0p495e4wwtl6jt0xan" Jun 04 20:47:18 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:18+02:00" level=debug msg="obtained new client token: AAB7T28c3ZbZ0p8XVuqW7fCTCSZj9pqmOX4yCy/MthbIt1dlKfy08+G244k/izseZ/lEi7OrIPRp2mpvsLWC616YATtRJSEH4RBql5OyUuP2EsS8eOoXePeK4GIqTiXFX9vlT0j1c/L5+HzzS4FLLQmeLLrqvp5QgLEQCKJDTb1nbtZ+4tG54H67k7Nbib9lPfad551p5zVVX86xZDMY+AsmyxYxOWNvKJxvBWcn7YpnYjgbkt+XzO/2rWU/c7c=" Jun 04 20:47:18 volumio-niclas systemd[1]: systemd-fsckd.service: Succeeded. Jun 04 20:47:18 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:18+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]" Jun 04 20:47:18 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:18+02:00" level=debug msg="completed keyexchange" Jun 04 20:47:18 volumio-niclas mpd[1121]: Jun 04 20:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 04 20:47:18 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:18+02:00" level=debug msg="completed challenge" Jun 04 20:47:18 volumio-niclas systemd[1]: Started Music Player Daemon. Jun 04 20:47:18 volumio-niclas sudo[1100]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:18 volumio-niclas sudo[1109]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:18 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:18+02:00" level=debug msg="authenticated as l5d20ev0p495e4wwtl6jt0xan" Jun 04 20:47:18 volumio-niclas volumio[957]: info: Completed starting Core Plugins Jun 04 20:47:18 volumio-niclas volumio[957]: info: ------------------------------------------- Jun 04 20:47:18 volumio-niclas volumio[957]: info: ----- MyVolumio plugins startup ---- Jun 04 20:47:18 volumio-niclas volumio[957]: info: ------------------------------------------- Jun 04 20:47:18 volumio-niclas volumio[957]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 04 20:47:18 volumio-niclas sh[526]: timed out Jun 04 20:47:18 volumio-niclas dhcpcd[573]: timed out Jun 04 20:47:18 volumio-niclas sh[526]: dhcpcd exited Jun 04 20:47:18 volumio-niclas dhcpcd[573]: dhcpcd exited Jun 04 20:47:18 volumio-niclas sh[526]: ifup: failed to bring up eth0 Jun 04 20:47:18 volumio-niclas systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 04 20:47:18 volumio-niclas systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 04 20:47:19 volumio-niclas volumio[957]: error: MPD error: The expression evaluated to a falsy value: Jun 04 20:47:19 volumio-niclas volumio[957]: assert.ok(self.idling) Jun 04 20:47:19 volumio-niclas volumio[957]: error: The expression evaluated to a falsy value: Jun 04 20:47:19 volumio-niclas volumio[957]: assert.ok(self.idling) Jun 04 20:47:19 volumio-niclas volumio[957]: error: updateQueue error: null Jun 04 20:47:19 volumio-niclas volumio[957]: info: MPD running with PID1121 Jun 04 20:47:19 volumio-niclas volumio[957]: ,establishing connection Jun 04 20:47:19 volumio-niclas volumio[957]: error: updateQueue error: null Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="authenticated as l5d20ev0p495e4wwtl6jt0xan" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="dealer connection opened" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="received connection id: ZGQ4ZTA3MDAtNTRkYy00ZjI5LWIxZTMtZGI2NzljNDg2NGIyK2RlYWxlcit0Y3A6Ly8wYWNhNTk3Yi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjVCMTg2NTZFRDQ5Q0QzQ0FEOEJEQjk2NzEwRjVDMTAxQjMyREIxM0IyMEZGRUY1NDJDM0I4RUY1NkFCMzA3Qg==" Jun 04 20:47:19 volumio-niclas volumio[957]: info: Discovery: adding 3267fee1-416a-48a6-89be-407692d91282 Jun 04 20:47:19 volumio-niclas volumio[957]: info: Discovery: Found device Volumio Wohnzimmer Jun 04 20:47:19 volumio-niclas volumio[957]: info: Discovery: Connecting to remote: 192.168.178.62 Jun 04 20:47:19 volumio-niclas volumio[957]: info: Discovery: Connected to remote: 192.168.178.62 Jun 04 20:47:19 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:19+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 04 20:47:20 volumio-niclas sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 20:47:20 volumio-niclas sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:20 volumio-niclas volumio[957]: info: go-librespot daemon successfully initialized Jun 04 20:47:20 volumio-niclas sudo[1174]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:20 volumio-niclas sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 20:47:20 volumio-niclas sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:20 volumio-niclas sudo[1176]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:20 volumio-niclas sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 04 20:47:20 volumio-niclas sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:20 volumio-niclas systemd[1]: Started UPnP Renderer front-end to MPD. Jun 04 20:47:20 volumio-niclas sudo[1180]: pam_unix(sudo:session): session closed for user root Jun 04 20:47:20 volumio-niclas volumio[957]: info: Upmpdcli Daemon Started Jun 04 20:47:20 volumio-niclas volumio[1182]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 04 20:47:21 volumio-niclas systemd[1]: systemd-hostnamed.service: Succeeded. Jun 04 20:47:21 volumio-niclas sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumio,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.12/Multimedia/Musik/Alben /mnt/NAS/Datenzwerg Jun 04 20:47:21 volumio-niclas sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:21 volumio-niclas kernel: CIFS: Attempting to mount \\192.168.178.12\Multimedia Jun 04 20:47:23 volumio-niclas volumio[957]: info: Initializing connection to go-librespot Websocket Jun 04 20:47:23 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:23+02:00" level=debug msg="new websocket client" Jun 04 20:47:23 volumio-niclas volumio[957]: info: Connection to go-librespot Websocket established Jun 04 20:47:24 volumio-niclas volumio[957]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 04 20:47:24 volumio-niclas volumio[1182]: .............................................++++ Jun 04 20:47:24 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:24+02:00" level=debug msg="handling transfer player command from add795d49c59dbd00c37d5a9b0ffc67172603c3e" Jun 04 20:47:24 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:24+02:00" level=trace msg="fetched new page 0 with 666 items (list: 666)" Jun 04 20:47:24 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:24+02:00" level=debug msg="loading track spotify:track:1eMUGMEWrvTXYWrPobq2dH (paused: false, position: -10416901162ms)" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=trace msg="emitting websocket event: will_play" Jun 04 20:47:25 volumio-niclas volumio[957]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1eMUGMEWrvTXYWrPobq2dH","play_origin":"collection-songs"}} Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:1eMUGMEWrvTXYWrPobq2dH" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="requested aes key for file 9806e59c6cc5ee2057632324b7aa915048fbe38a, gid: 1eMUGMEWrvTXYWrPobq2dH" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="fetched first chunk of 20, total size is 10205992 bytes" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=trace msg="seek to -10416901162ms (diff: -10416901162ms, samples: -459385341244, bytes: 0)" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=info msg="loaded track \"Heute Nacht\" (uri: spotify:track:1eMUGMEWrvTXYWrPobq2dH, paused: false, position: -10416901162ms, duration: 204444ms)" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="fetched chunk 1/19, size: 524288" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="fetched chunk 3/19, size: 524288" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=trace msg="emitting websocket event: metadata" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=trace msg="emitting websocket event: active" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="sending successful reply for delaer request" Jun 04 20:47:25 volumio-niclas volumio[957]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1eMUGMEWrvTXYWrPobq2dH","name":"Heute Nacht","artist_names":["Maddix"],"album_name":"Heute Nacht","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02a13e6d8af4a37e8a2547c8f4","position":-10416901162,"duration":204444,"release_date":"year:2022 month:10 day:14","track_number":1,"disc_number":1}} Jun 04 20:47:25 volumio-niclas volumio[957]: SPOTIFY: received: {"type":"active","data":null} Jun 04 20:47:25 volumio-niclas volumio[957]: info: Aligning Spotify Volume to Volumio Volume Jun 04 20:47:25 volumio-niclas volumio[957]: info: CoreCommandRouter::volumioGetState Jun 04 20:47:25 volumio-niclas volumio[957]: info: CorePlayQueue::getTrack 0 Jun 04 20:47:25 volumio-niclas volumio[957]: info: Setting Spotify Volume from Volumio: 100 Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="fetched chunk 2/19, size: 524288" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=trace msg="emitting websocket event: playing" Jun 04 20:47:25 volumio-niclas volumio[957]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1eMUGMEWrvTXYWrPobq2dH","play_origin":"collection-songs"}} Jun 04 20:47:25 volumio-niclas volumio[957]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 20:47:25 volumio-niclas volumio[957]: TypeError: Cannot read property 'service' of undefined Jun 04 20:47:25 volumio-niclas volumio[957]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jun 04 20:47:25 volumio-niclas volumio[957]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:262:18) Jun 04 20:47:25 volumio-niclas volumio[957]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jun 04 20:47:25 volumio-niclas volumio[957]: at WebSocket.emit (events.js:315:20) Jun 04 20:47:25 volumio-niclas volumio[957]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1209:20) Jun 04 20:47:25 volumio-niclas volumio[957]: at Receiver.emit (events.js:315:20) Jun 04 20:47:25 volumio-niclas volumio[957]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:594:14) Jun 04 20:47:25 volumio-niclas volumio[957]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jun 04 20:47:25 volumio-niclas volumio[957]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jun 04 20:47:25 volumio-niclas volumio[957]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jun 04 20:47:25 volumio-niclas volumio[957]: at writeOrBuffer (internal/streams/writable.js:358:12) Jun 04 20:47:25 volumio-niclas volumio[957]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jun 04 20:47:25 volumio-niclas volumio[957]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1303:35) Jun 04 20:47:25 volumio-niclas volumio[957]: at Socket.emit (events.js:315:20) Jun 04 20:47:25 volumio-niclas volumio[957]: at addChunk (internal/streams/readable.js:309:12) Jun 04 20:47:25 volumio-niclas volumio[957]: at readableAddChunk (internal/streams/readable.js:284:9) Jun 04 20:47:25 volumio-niclas volumio[957]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1748" Jun 04 20:47:25 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:25+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 04 20:47:26 volumio-niclas sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-04 20:46 Jun 04 20:47:26 volumio-niclas sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 20:47:26 volumio-niclas go-librespot[1146]: time="2024-06-04T20:47:26+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"