-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-03-03 20:30:26 CET. -- Mar 03 20:29:17 volumiolego fake-hwclock[330]: Mon Mar 3 19:29:17 UTC 2025 Mar 03 20:29:17 volumiolego systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 03 20:29:17 volumiolego systemd[1]: Started Restore / save the current clock. Mar 03 20:29:17 volumiolego systemd[1]: Started Apply Kernel Variables. Mar 03 20:29:17 volumiolego systemd[1]: Started Load/Save Random Seed. Mar 03 20:29:17 volumiolego systemd[1]: Started Create System Users. Mar 03 20:29:17 volumiolego systemd[1]: Starting Create Static Device Nodes in /dev... Mar 03 20:29:17 volumiolego systemd[1]: dynamicswap.service: Succeeded. Mar 03 20:29:17 volumiolego systemd[1]: Started udev Coldplug all Devices. Mar 03 20:29:17 volumiolego systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 03 20:29:17 volumiolego systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 03 20:29:17 volumiolego systemd[1]: Started Create Static Device Nodes in /dev. Mar 03 20:29:17 volumiolego systemd[1]: Starting udev Kernel Device Manager... Mar 03 20:29:17 volumiolego systemd[1]: Reached target Local File Systems (Pre). Mar 03 20:29:17 volumiolego systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 03 20:29:17 volumiolego systemd[1]: Mounting /tmp... Mar 03 20:29:17 volumiolego systemd[1]: Mounting /var/spool/cups... Mar 03 20:29:17 volumiolego systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 03 20:29:17 volumiolego systemd[1]: Mounting /var/log... Mar 03 20:29:17 volumiolego systemd[1]: Mounted /tmp. Mar 03 20:29:17 volumiolego systemd[1]: Mounted /var/spool/cups. Mar 03 20:29:17 volumiolego systemd[1]: Mounted /var/log. Mar 03 20:29:17 volumiolego systemd[1]: Starting Flush Journal to Persistent Storage... Mar 03 20:29:17 volumiolego systemd[1]: Mounting /var/spool/cups/tmp... Mar 03 20:29:17 volumiolego systemd-udevd[352]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 03 20:29:17 volumiolego systemd[1]: Mounted /var/spool/cups/tmp. Mar 03 20:29:17 volumiolego systemd-journald[323]: Runtime journal (/run/log/journal/a89b7be63bc86b985c184d84675fe488) is 7.5M, max 30.0M, 22.5M free. Mar 03 20:29:17 volumiolego systemd[1]: Started Flush Journal to Persistent Storage. Mar 03 20:29:17 volumiolego systemd[1]: Started udev Kernel Device Manager. Mar 03 20:29:17 volumiolego systemd[1]: Starting Show Plymouth Boot Screen... Mar 03 20:29:17 volumiolego systemd[1]: plymouth-start.service: Succeeded. Mar 03 20:29:17 volumiolego systemd[1]: Started Show Plymouth Boot Screen. Mar 03 20:29:17 volumiolego systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 03 20:29:17 volumiolego systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 03 20:29:17 volumiolego systemd[1]: Reached target Paths. Mar 03 20:29:17 volumiolego systemd[1]: Reached target Local Encrypted Volumes. Mar 03 20:29:17 volumiolego kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Mar 03 20:29:17 volumiolego kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Mar 03 20:29:17 volumiolego kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 03 20:29:17 volumiolego kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 03 20:29:17 volumiolego kernel: [vc_sm_connected_init]: start Mar 03 20:29:17 volumiolego kernel: [vc_sm_connected_init]: installed successfully Mar 03 20:29:17 volumiolego kernel: mc: Linux media interface: v0.10 Mar 03 20:29:17 volumiolego kernel: videodev: Linux video capture interface: v2.00 Mar 03 20:29:17 volumiolego kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 03 20:29:17 volumiolego kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Mar 03 20:29:17 volumiolego kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 03 20:29:17 volumiolego kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Mar 03 20:29:17 volumiolego kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 03 20:29:17 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 03 20:29:17 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 03 20:29:17 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 03 20:29:17 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 03 20:29:17 volumiolego kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 03 20:29:17 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 03 20:29:17 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 03 20:29:17 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 03 20:29:18 volumiolego systemd[1]: Found device /dev/disk/by-uuid/51B1-040D. Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 03 20:29:18 volumiolego kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 03 20:29:18 volumiolego kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 03 20:29:18 volumiolego kernel: Bluetooth: Core ver 2.22 Mar 03 20:29:18 volumiolego kernel: NET: Registered PF_BLUETOOTH protocol family Mar 03 20:29:18 volumiolego kernel: Bluetooth: HCI device and connection manager initialized Mar 03 20:29:18 volumiolego kernel: Bluetooth: HCI socket layer initialized Mar 03 20:29:18 volumiolego kernel: Bluetooth: L2CAP socket layer initialized Mar 03 20:29:18 volumiolego kernel: Bluetooth: SCO socket layer initialized Mar 03 20:29:18 volumiolego kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 03 20:29:18 volumiolego kernel: edt_ft5x06 10-0038: supply iovcc not found, using dummy regulator Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Mar 03 20:29:18 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Mar 03 20:29:18 volumiolego kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 03 20:29:18 volumiolego kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Mar 03 20:29:18 volumiolego kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Mar 03 20:29:18 volumiolego kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 03 20:29:18 volumiolego kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Mar 03 20:29:18 volumiolego kernel: input: 10-0038 generic ft5x06 (00) as /devices/platform/soc/3f205000.i2c/i2c-11/i2c-10/10-0038/input/input0 Mar 03 20:29:18 volumiolego systemd[1]: Starting File System Check on /dev/disk/by-uuid/51B1-040D... Mar 03 20:29:18 volumiolego systemd[1]: Started File System Check Daemon to report status. Mar 03 20:29:18 volumiolego systemd[1]: Created slice system-systemd\x2dbacklight.slice. Mar 03 20:29:18 volumiolego systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:10-0045... Mar 03 20:29:18 volumiolego systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:10-0045. Mar 03 20:29:18 volumiolego systemd-udevd[366]: Using default interface naming scheme 'v240'. Mar 03 20:29:18 volumiolego systemd-udevd[366]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 03 20:29:18 volumiolego kernel: Bluetooth: HCI UART driver ver 2.3 Mar 03 20:29:18 volumiolego kernel: Bluetooth: HCI UART protocol H4 registered Mar 03 20:29:18 volumiolego kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Mar 03 20:29:18 volumiolego kernel: Bluetooth: HCI UART protocol Broadcom registered Mar 03 20:29:18 volumiolego kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Mar 03 20:29:18 volumiolego kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Mar 03 20:29:18 volumiolego kernel: uart-pl011 3f201000.serial: no DMA platform data Mar 03 20:29:18 volumiolego kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Mar 03 20:29:18 volumiolego kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Mar 03 20:29:18 volumiolego kernel: usbcore: registered new interface driver brcmfmac Mar 03 20:29:18 volumiolego systemd-fsck[434]: fsck.fat 4.1 (2017-01-24) Mar 03 20:29:18 volumiolego systemd-fsck[434]: /dev/mmcblk0p1: 398 files, 166313/184582 clusters Mar 03 20:29:18 volumiolego systemd[1]: Started File System Check on /dev/disk/by-uuid/51B1-040D. Mar 03 20:29:18 volumiolego systemd[1]: Mounting /boot... Mar 03 20:29:18 volumiolego kernel: Bluetooth: hci0: BCM: chip id 94 Mar 03 20:29:18 volumiolego kernel: Bluetooth: hci0: BCM: features 0x2e Mar 03 20:29:18 volumiolego kernel: Bluetooth: hci0: BCM43430A1 Mar 03 20:29:18 volumiolego kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Mar 03 20:29:19 volumiolego kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Mar 03 20:29:19 volumiolego kernel: vc4_dsi 3f700000.dsi: Fixed dependency cycle(s) with /soc/dsi@7e700000/bridge@0 Mar 03 20:29:19 volumiolego kernel: platform 1.panel_disp: Fixed dependency cycle(s) with /soc/dsi@7e700000/bridge@0 Mar 03 20:29:19 volumiolego kernel: mipi-dsi 3f700000.dsi.0: Fixed dependency cycle(s) with /soc/dsi@7e700000 Mar 03 20:29:19 volumiolego kernel: mipi-dsi 3f700000.dsi.0: Fixed dependency cycle(s) with /panel_disp@1 Mar 03 20:29:19 volumiolego systemd[1]: Mounted /boot. Mar 03 20:29:19 volumiolego kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Mar 03 20:29:19 volumiolego kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 Mar 03 20:29:19 volumiolego systemd-udevd[364]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 03 20:29:19 volumiolego kernel: Console: switching to colour dummy device 80x30 Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4]) Mar 03 20:29:19 volumiolego kernel: Registered IR keymap rc-cec Mar 03 20:29:19 volumiolego kernel: rc rc0: vc4-hdmi as /devices/platform/soc/3f902000.hdmi/rc/rc0 Mar 03 20:29:19 volumiolego kernel: input: vc4-hdmi as /devices/platform/soc/3f902000.hdmi/rc/rc0/input1 Mar 03 20:29:19 volumiolego systemd-udevd[361]: Using default interface naming scheme 'v240'. Mar 03 20:29:19 volumiolego systemd-udevd[361]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 03 20:29:19 volumiolego kernel: input: vc4-hdmi HDMI Jack as /devices/platform/soc/3f902000.hdmi/sound/card2/input2 Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4]) Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: bound 3f700000.dsi (ops vc4_dsi_ops [vc4]) Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: bound 3f004000.txp (ops vc4_txp_ops [vc4]) Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4]) Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4]) Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4]) Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4]) Mar 03 20:29:19 volumiolego kernel: [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0 Mar 03 20:29:19 volumiolego systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Mar 03 20:29:19 volumiolego kernel: Console: switching to colour frame buffer device 100x30 Mar 03 20:29:19 volumiolego kernel: vc4-drm soc:gpu: [drm] fb0: vc4drmfb frame buffer device Mar 03 20:29:19 volumiolego systemd-udevd[368]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 03 20:29:19 volumiolego kernel: Bluetooth: hci0: BCM: features 0x2e Mar 03 20:29:19 volumiolego kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Mar 03 20:29:19 volumiolego kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Mar 03 20:29:19 volumiolego systemd-udevd[362]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 03 20:29:19 volumiolego kernel: alsactl[558]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Mar 03 20:29:19 volumiolego systemd-udevd[364]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 03 20:29:19 volumiolego systemd-udevd[359]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 03 20:29:19 volumiolego systemd[1]: Created slice system-bthelper.slice. Mar 03 20:29:19 volumiolego systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 03 20:29:19 volumiolego systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 03 20:29:19 volumiolego systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Starting Show Plymouth Boot Screen... Mar 03 20:29:20 volumiolego systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 03 20:29:20 volumiolego systemd[1]: Reached target Local File Systems. Mar 03 20:29:20 volumiolego systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 03 20:29:20 volumiolego systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Starting Create Volatile Files and Directories... Mar 03 20:29:20 volumiolego systemd[1]: Starting Raise network interfaces... Mar 03 20:29:20 volumiolego systemd[1]: Started ifup for eth0. Mar 03 20:29:20 volumiolego systemd[1]: Starting Preprocess NFS configuration... Mar 03 20:29:20 volumiolego systemd[1]: plymouth-read-write.service: Succeeded. Mar 03 20:29:20 volumiolego systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 03 20:29:20 volumiolego systemd[1]: Received SIGRTMIN+20 from PID 215 (plymouthd). Mar 03 20:29:20 volumiolego systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Mar 03 20:29:20 volumiolego systemd[1]: nfs-config.service: Succeeded. Mar 03 20:29:20 volumiolego systemd[1]: Started Preprocess NFS configuration. Mar 03 20:29:20 volumiolego systemd[1]: plymouth-start.service: Succeeded. Mar 03 20:29:20 volumiolego systemd[1]: Started Show Plymouth Boot Screen. Mar 03 20:29:20 volumiolego systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 03 20:29:20 volumiolego systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Reached target NFS client services. Mar 03 20:29:20 volumiolego systemd[1]: Started ifup for wlan0. Mar 03 20:29:20 volumiolego systemd[1]: Started Create Volatile Files and Directories. Mar 03 20:29:20 volumiolego systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Starting RPC bind portmap service... Mar 03 20:29:20 volumiolego systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 03 20:29:20 volumiolego systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 03 20:29:20 volumiolego systemd[1]: Started Load/Save RF Kill Switch Status. Mar 03 20:29:20 volumiolego kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Mar 03 20:29:20 volumiolego systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 03 20:29:20 volumiolego systemd[1]: Reached target System Initialization. Mar 03 20:29:20 volumiolego systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 03 20:29:20 volumiolego systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 03 20:29:20 volumiolego systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 03 20:29:20 volumiolego systemd[1]: Started Daily apt download activities. Mar 03 20:29:20 volumiolego systemd[1]: Started Daily apt upgrade and clean activities. Mar 03 20:29:20 volumiolego systemd[1]: Listening on triggerhappy.socket. Mar 03 20:29:20 volumiolego systemd[1]: Reached target Sockets. Mar 03 20:29:20 volumiolego systemd[1]: Reached target Basic System. Mar 03 20:29:20 volumiolego systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 03 20:29:20 volumiolego systemd[1]: Started Volumio Iptables Module. Mar 03 20:29:20 volumiolego systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Started MPD Monitor Service. Mar 03 20:29:20 volumiolego systemd[1]: Started volumio-remote-updater.service. Mar 03 20:29:20 volumiolego systemd[1]: Started Volumio Log Rotation Service. Mar 03 20:29:20 volumiolego kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 03 20:29:20 volumiolego systemd[1]: Starting dhcpcd on all interfaces... Mar 03 20:29:20 volumiolego systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 03 20:29:20 volumiolego systemd[1]: Started Manage Sound Card State (restore and store). Mar 03 20:29:20 volumiolego systemd[1]: Starting Volumio Time Update Utility... Mar 03 20:29:20 volumiolego alsactl[685]: alsactl 1.1.8 daemon started Mar 03 20:29:20 volumiolego systemd[1]: Starting Login Service... Mar 03 20:29:20 volumiolego systemd[1]: Started UPnP Renderer front-end to MPD. Mar 03 20:29:20 volumiolego kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 03 20:29:20 volumiolego systemd[1]: Starting triggerhappy global hotkey daemon... Mar 03 20:29:20 volumiolego systemd[1]: Started D-Bus System Message Bus. Mar 03 20:29:20 volumiolego systemd[1]: Starting Save/Restore Sound Card State... Mar 03 20:29:20 volumiolego systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 03 20:29:20 volumiolego systemd[1]: Reached target Login Prompts. Mar 03 20:29:20 volumiolego volumio-time-update[686]: volumio-time-update-util: Fetching time from Volumio... Mar 03 20:29:20 volumiolego dhcpcd[683]: Not running dhcpcd because /etc/network/interfaces Mar 03 20:29:20 volumiolego dhcpcd[683]: defines some interfaces that will use a Mar 03 20:29:20 volumiolego dhcpcd[683]: DHCP client or static address Mar 03 20:29:20 volumiolego systemd[1]: Started Daily man-db regeneration. Mar 03 20:29:20 volumiolego systemd[1]: Reached target Timers. Mar 03 20:29:20 volumiolego systemd[1]: Starting Wireless Services... Mar 03 20:29:20 volumiolego systemd[1]: Starting WPA supplicant... Mar 03 20:29:20 volumiolego systemd[1]: Started RPC bind portmap service. Mar 03 20:29:20 volumiolego systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 03 20:29:20 volumiolego systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 03 20:29:20 volumiolego systemd[1]: Failed to start dhcpcd on all interfaces. Mar 03 20:29:20 volumiolego systemd[1]: Reached target Remote File Systems (Pre). Mar 03 20:29:20 volumiolego systemd[1]: Reached target Remote File Systems. Mar 03 20:29:20 volumiolego systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 03 20:29:20 volumiolego systemd[1]: Reached target RPC Port Mapper. Mar 03 20:29:20 volumiolego thd[693]: Unable to parse trigger line: Mar 03 20:29:20 volumiolego thd[693]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 03 20:29:20 volumiolego thd[693]: Unable to parse trigger line: Mar 03 20:29:20 volumiolego thd[693]: Found socket passed from systemd Mar 03 20:29:20 volumiolego systemd-logind[687]: New seat seat0. Mar 03 20:29:20 volumiolego systemd-logind[687]: Watching system buttons on /dev/input/event1 (vc4-hdmi) Mar 03 20:29:20 volumiolego avahi-daemon[676]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 03 20:29:20 volumiolego avahi-daemon[676]: Successfully dropped root privileges. Mar 03 20:29:20 volumiolego avahi-daemon[676]: avahi-daemon 0.7 starting up. Mar 03 20:29:20 volumiolego mpd_monitor.sh[679]: MPD error: Connection refused Mar 03 20:29:21 volumiolego mpd_monitor.sh[679]: mpd: no process found Mar 03 20:29:21 volumiolego dbus-daemon[694]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 03 20:29:21 volumiolego wpa_supplicant[705]: Successfully initialized wpa_supplicant Mar 03 20:29:21 volumiolego avahi-daemon[676]: Successfully called chroot(). Mar 03 20:29:21 volumiolego systemd[1]: Started Login Service. Mar 03 20:29:21 volumiolego avahi-daemon[676]: Successfully dropped remaining capabilities. Mar 03 20:29:21 volumiolego systemd[1]: Started triggerhappy global hotkey daemon. Mar 03 20:29:21 volumiolego systemd[1]: hciuart.service: Succeeded. Mar 03 20:29:21 volumiolego systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 03 20:29:21 volumiolego systemd[1]: Started Save/Restore Sound Card State. Mar 03 20:29:21 volumiolego avahi-daemon[676]: Loading service file /services/volumio.service. Mar 03 20:29:21 volumiolego avahi-daemon[676]: Network interface enumeration completed. Mar 03 20:29:21 volumiolego avahi-daemon[676]: Server startup complete. Host name is volumiolego.local. Local service cookie is 1373865068. Mar 03 20:29:21 volumiolego avahi-daemon[676]: Service "VolumioLEGO" (/services/volumio.service) successfully established. Mar 03 20:29:21 volumiolego sh[606]: eth0: waiting for carrier Mar 03 20:29:21 volumiolego dhcpcd[649]: eth0: waiting for carrier Mar 03 20:29:21 volumiolego kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Mar 03 20:29:21 volumiolego kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Mar 03 20:29:21 volumiolego sh[630]: wlan0=wlan0 Mar 03 20:29:21 volumiolego systemd[1]: Started WPA supplicant. Mar 03 20:29:21 volumiolego systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 03 20:29:21 volumiolego volumio-remote-updater[680]: Error: No active session Mar 03 20:29:21 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:21] [info] asio async_connect error: system:111 (Connection refused) Mar 03 20:29:21 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 03 20:29:21 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:21] [error] handle_connect error: Underlying Transport Error Mar 03 20:29:21 volumiolego haveged[648]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 03 20:29:21 volumiolego haveged[648]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 03 20:29:21 volumiolego haveged[648]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0015 Mar 03 20:29:21 volumiolego haveged[648]: haveged: fills: 0, generated: 0 Mar 03 20:29:21 volumiolego volumio[692]: Could not open config: /tmp/upmpdcli.conf Mar 03 20:29:21 volumiolego systemd[1]: Listening on mpd.socket. Mar 03 20:29:21 volumiolego systemd[1]: Reached target Sound Card. Mar 03 20:29:21 volumiolego systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 03 20:29:21 volumiolego systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 03 20:29:21 volumiolego systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 03 20:29:21 volumiolego systemd[1]: iptables.service: Succeeded. Mar 03 20:29:21 volumiolego volumio-time-update[686]: volumio-time-update-util: Date not found in response Mar 03 20:29:21 volumiolego volumio-time-update[686]: volumio-time-update-util: Retrying in 5 seconds... Mar 03 20:29:21 volumiolego systemd[1]: Started Raise network interfaces. Mar 03 20:29:21 volumiolego systemd[1]: Reached target Network. Mar 03 20:29:21 volumiolego systemd[1]: Reached target Network is Online. Mar 03 20:29:21 volumiolego systemd[1]: Starting LSB: Brings up/down network automatically... Mar 03 20:29:21 volumiolego bthelper[811]: Raspberry Pi BDADDR already set Mar 03 20:29:21 volumiolego systemd[1]: Starting Network Time Service... Mar 03 20:29:21 volumiolego systemd[1]: Starting OpenBSD Secure Shell server... Mar 03 20:29:21 volumiolego systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 03 20:29:21 volumiolego systemd[1]: Starting Samba NMB Daemon... Mar 03 20:29:21 volumiolego systemd[1]: Starting /etc/rc.local Compatibility... Mar 03 20:29:21 volumiolego loadcpufreq[708]: Loading cpufreq kernel modules...done (none). Mar 03 20:29:21 volumiolego systemd[1]: Starting Permit User Sessions... Mar 03 20:29:21 volumiolego systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 20:29:21 volumiolego systemd[1]: Starting Music Player Daemon... Mar 03 20:29:22 volumiolego systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 03 20:29:22 volumiolego systemd[1]: Started Raspberry Pi bluetooth helper. Mar 03 20:29:22 volumiolego systemd[1]: Started /etc/rc.local Compatibility. Mar 03 20:29:22 volumiolego systemd[1]: Starting Bluetooth service... Mar 03 20:29:22 volumiolego systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 03 20:29:22 volumiolego systemd[1]: Started Permit User Sessions. Mar 03 20:29:22 volumiolego ntpd[832]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 03 20:29:22 volumiolego ntpd[832]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 03 20:29:22 volumiolego ntpd[867]: proto: precision = 0.677 usec (-20) Mar 03 20:29:22 volumiolego systemd[1]: Starting Hold until boot process finishes up... Mar 03 20:29:22 volumiolego ntpd[867]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 03 20:29:22 volumiolego ntpd[867]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 03 20:29:22 volumiolego ntpd[867]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 66 days ago Mar 03 20:29:22 volumiolego systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 03 20:29:22 volumiolego systemd[1]: Started Network Time Service. Mar 03 20:29:22 volumiolego systemd[1]: Received SIGRTMIN+21 from PID 215 (plymouthd). Mar 03 20:29:22 volumiolego ntpd[867]: Listen and drop on 0 v6wildcard [::]:123 Mar 03 20:29:22 volumiolego ntpd[867]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 03 20:29:22 volumiolego ntpd[867]: Listen normally on 2 lo 127.0.0.1:123 Mar 03 20:29:22 volumiolego ntpd[867]: Listening on routing socket on fd #19 for interface updates Mar 03 20:29:22 volumiolego ntpd[867]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 03 20:29:22 volumiolego ntpd[867]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 03 20:29:22 volumiolego systemd[1]: Received SIGRTMIN+21 from PID 215 (plymouthd). Mar 03 20:29:22 volumiolego systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 03 20:29:22 volumiolego systemd[1]: Started Hold until boot process finishes up. Mar 03 20:29:22 volumiolego systemd[1]: plymouth-quit.service: Succeeded. Mar 03 20:29:22 volumiolego systemd[1]: Started Terminate Plymouth Boot Screen. Mar 03 20:29:22 volumiolego ifplugd(eth0)[881]: ifplugd 0.28 initializing. Mar 03 20:29:22 volumiolego ifplugd(eth0)[881]: Using interface eth0/B8:27:EB:32:7F:69 with driver (version: 6.6.62-v7+) Mar 03 20:29:22 volumiolego ifplugd(eth0)[881]: Using detection mode: SIOCETHTOOL Mar 03 20:29:22 volumiolego ifplugd(eth0)[881]: Initialization complete, link beat not detected. Mar 03 20:29:22 volumiolego cpufrequtils[865]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 03 20:29:22 volumiolego systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 03 20:29:22 volumiolego ifplugd[827]: Network Interface Plugging Daemon...start eth0...done. Mar 03 20:29:22 volumiolego systemd[1]: Started LSB: Brings up/down network automatically. Mar 03 20:29:22 volumiolego bluetoothd[863]: Bluetooth daemon 5.50 Mar 03 20:29:22 volumiolego bluetoothd[863]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 03 20:29:22 volumiolego bluetoothd[863]: Starting SDP server Mar 03 20:29:22 volumiolego bluetoothd[863]: Excluding (cli) sap Mar 03 20:29:22 volumiolego systemd[1]: Started Bluetooth service. Mar 03 20:29:22 volumiolego systemd[1]: Reached target Bluetooth. Mar 03 20:29:22 volumiolego sshd[897]: Server listening on 0.0.0.0 port 22. Mar 03 20:29:22 volumiolego sshd[897]: Server listening on :: port 22. Mar 03 20:29:22 volumiolego systemd[1]: Started OpenBSD Secure Shell server. Mar 03 20:29:22 volumiolego kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 03 20:29:22 volumiolego kernel: Bluetooth: BNEP filters: protocol multicast Mar 03 20:29:22 volumiolego kernel: Bluetooth: BNEP socket layer initialized Mar 03 20:29:22 volumiolego dbus-daemon[694]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=863 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 03 20:29:22 volumiolego bluetoothd[863]: Bluetooth management interface 1.22 initialized Mar 03 20:29:22 volumiolego kernel: Bluetooth: MGMT ver 1.22 Mar 03 20:29:22 volumiolego sudo[861]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 20:29:22 volumiolego sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:22 volumiolego sudo[861]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 03 20:29:22 volumiolego sudo[861]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:22 volumiolego bluetoothd[863]: Failed to set privacy: Rejected (0x0b) Mar 03 20:29:22 volumiolego systemd[1]: Starting Hostname Service... Mar 03 20:29:23 volumiolego dbus-daemon[694]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 03 20:29:23 volumiolego systemd[1]: Started Hostname Service. Mar 03 20:29:23 volumiolego ntpd[867]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 03 20:29:23 volumiolego nmbd[862]: [2025/03/03 20:29:23.422172, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 03 20:29:23 volumiolego nmbd[862]: started asyncdns process 903 Mar 03 20:29:23 volumiolego nmbd[862]: [2025/03/03 20:29:23.424552, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 03 20:29:23 volumiolego nmbd[862]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 03 20:29:23 volumiolego nmbd[862]: [2025/03/03 20:29:23.424728, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 03 20:29:23 volumiolego nmbd[862]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 03 20:29:23 volumiolego wireless.js[703]: Cleaning previous... Mar 03 20:29:24 volumiolego ntpd[867]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 03 20:29:24 volumiolego sudo[911]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 03 20:29:24 volumiolego sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:24 volumiolego sudo[911]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:24 volumiolego sudo[913]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 03 20:29:24 volumiolego sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:24 volumiolego sudo[913]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:24 volumiolego wireless.js[703]: Stopped aP Mar 03 20:29:24 volumiolego sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 03 20:29:24 volumiolego sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:24 volumiolego kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 03 20:29:24 volumiolego sudo[920]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:24 volumiolego sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 03 20:29:24 volumiolego sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:24 volumiolego sudo[922]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:24 volumiolego sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 03 20:29:24 volumiolego sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:24 volumiolego sudo[929]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:24 volumiolego sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 03 20:29:24 volumiolego sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:25 volumiolego ntpd[867]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 03 20:29:25 volumiolego systemd[1]: systemd-rfkill.service: Succeeded. Mar 03 20:29:25 volumiolego sudo[931]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:25 volumiolego wireless.js[703]: SETTING APPROPRIATE REG DOMAIN: DE Mar 03 20:29:25 volumiolego sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 03 20:29:25 volumiolego sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:25 volumiolego sudo[936]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:25 volumiolego sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Mar 03 20:29:25 volumiolego sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:25 volumiolego sudo[938]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:25 volumiolego wireless.js[703]: SUCCESSFULLY SET NEW REGDOMAIN: DE Mar 03 20:29:25 volumiolego wireless.js[703]: Start wireless flow Mar 03 20:29:25 volumiolego wireless.js[703]: Stopped hotspot (if there).. Mar 03 20:29:25 volumiolego sudo[943]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 03 20:29:25 volumiolego sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:25 volumiolego sudo[943]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:25 volumiolego sudo[945]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 03 20:29:25 volumiolego sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:26 volumiolego sudo[945]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:26 volumiolego ntpd[867]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 03 20:29:26 volumiolego wireless.js[703]: DHCP IP Mar 03 20:29:26 volumiolego wpa_supplicant[947]: Successfully initialized wpa_supplicant Mar 03 20:29:26 volumiolego wireless.js[703]: Start ap Mar 03 20:29:26 volumiolego kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 03 20:29:26 volumiolego sudo[948]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 03 20:29:26 volumiolego sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:26 volumiolego dhcpcd[949]: dev: loaded udev Mar 03 20:29:26 volumiolego mpd[900]: Mar 03 20:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 20:29:26 volumiolego dhcpcd[949]: wlan0: connected to Access Point `' Mar 03 20:29:26 volumiolego dhcpcd[949]: no interfaces have a carrier Mar 03 20:29:26 volumiolego dhcpcd[949]: forked to background, child pid 979 Mar 03 20:29:26 volumiolego dhcpcd[979]: eth0: waiting for carrier Mar 03 20:29:26 volumiolego dhcpcd[979]: wlan0: waiting for carrier Mar 03 20:29:26 volumiolego sudo[948]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:26 volumiolego systemd[1]: Started Music Player Daemon. Mar 03 20:29:26 volumiolego mpd_monitor.sh[679]: MPD restarted due to no mpc output. Mar 03 20:29:26 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:26] [info] asio async_connect error: system:111 (Connection refused) Mar 03 20:29:26 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 03 20:29:26 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:26] [error] handle_connect error: Underlying Transport Error Mar 03 20:29:26 volumiolego volumio-time-update[686]: volumio-time-update-util: Fetching time from Volumio... Mar 03 20:29:26 volumiolego volumio-time-update[686]: volumio-time-update-util: Date not found in response Mar 03 20:29:26 volumiolego volumio-time-update[686]: volumio-time-update-util: Retrying in 5 seconds... Mar 03 20:29:26 volumiolego wpa_supplicant[969]: wlan0: Trying to associate with SSID 'Compagel' Mar 03 20:29:26 volumiolego bthelper[811]: Changing power off succeeded Mar 03 20:29:27 volumiolego wpa_supplicant[969]: wlan0: Associated with dc:39:6f:83:2b:e2 Mar 03 20:29:27 volumiolego wpa_supplicant[969]: wlan0: CTRL-EVENT-CONNECTED - Connection to dc:39:6f:83:2b:e2 completed [id=0 id_str=] Mar 03 20:29:27 volumiolego dhcpcd[979]: wlan0: carrier acquired Mar 03 20:29:27 volumiolego wpa_supplicant[969]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 03 20:29:27 volumiolego wpa_supplicant[969]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Mar 03 20:29:27 volumiolego dhcpcd[979]: wlan0: connected to Access Point `Compagel' Mar 03 20:29:27 volumiolego dhcpcd[979]: DUID 00:01:00:01:2e:f2:a3:33:b8:27:eb:67:2a:3c Mar 03 20:29:27 volumiolego dhcpcd[979]: wlan0: IAID eb:67:2a:3c Mar 03 20:29:27 volumiolego dhcpcd[979]: wlan0: adding address fe80::2265:34a4:f274:1a5d Mar 03 20:29:27 volumiolego dhcpcd[979]: ipv6_addaddr1: Permission denied Mar 03 20:29:27 volumiolego bthelper[811]: [CHG] Controller B8:27:EB:98:D5:C3 Class: 0x0000041c Mar 03 20:29:27 volumiolego bthelper[811]: Changing power on succeeded Mar 03 20:29:27 volumiolego wireless.js[703]: trying... Mar 03 20:29:27 volumiolego sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 03 20:29:27 volumiolego sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:27 volumiolego sudo[997]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:27 volumiolego dhcpcd[979]: wlan0: rebinding lease of 192.168.0.28 Mar 03 20:29:27 volumiolego dhcpcd[979]: wlan0: probing address 192.168.0.28/24 Mar 03 20:29:27 volumiolego dhcpcd[979]: wlan0: soliciting an IPv6 router Mar 03 20:29:28 volumiolego wireless.js[703]: trying... Mar 03 20:29:28 volumiolego sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 03 20:29:28 volumiolego sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:28 volumiolego sudo[1000]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:29 volumiolego wireless.js[703]: trying... Mar 03 20:29:29 volumiolego sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 03 20:29:29 volumiolego sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:29 volumiolego sudo[1003]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:30 volumiolego wireless.js[703]: trying... Mar 03 20:29:30 volumiolego sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 03 20:29:30 volumiolego sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:30 volumiolego sudo[1006]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:31 volumiolego wireless.js[703]: trying... Mar 03 20:29:31 volumiolego sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 03 20:29:31 volumiolego sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:31 volumiolego sudo[1009]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:31 volumiolego volumio-time-update[686]: volumio-time-update-util: Fetching time from Volumio... Mar 03 20:29:31 volumiolego volumio-time-update[686]: volumio-time-update-util: Date not found in response Mar 03 20:29:31 volumiolego volumio-time-update[686]: volumio-time-update-util: Retrying in 5 seconds... Mar 03 20:29:32 volumiolego wireless.js[703]: trying... Mar 03 20:29:32 volumiolego sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 03 20:29:32 volumiolego sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:32 volumiolego sudo[1017]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:32 volumiolego dhcpcd[979]: wlan0: leased 192.168.0.28 for 864000 seconds Mar 03 20:29:32 volumiolego avahi-daemon[676]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.28. Mar 03 20:29:32 volumiolego dhcpcd[979]: wlan0: adding route to 192.168.0.0/24 Mar 03 20:29:32 volumiolego dhcpcd[979]: wlan0: adding default route via 192.168.0.1 Mar 03 20:29:32 volumiolego avahi-daemon[676]: New relevant interface wlan0.IPv4 for mDNS. Mar 03 20:29:32 volumiolego avahi-daemon[676]: Registering new address record for 192.168.0.28 on wlan0.IPv4. Mar 03 20:29:33 volumiolego wireless.js[703]: trying... Mar 03 20:29:33 volumiolego sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 03 20:29:33 volumiolego sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:33 volumiolego sudo[1041]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:33 volumiolego wireless.js[703]: Connected to: ----Compagel Mar 03 20:29:33 volumiolego wireless.js[703]: ---- Mar 03 20:29:33 volumiolego sudo[1044]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 20:29:33 volumiolego sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:33 volumiolego sudo[1044]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:33 volumiolego wireless.js[703]: ... joined AP, wlan0 IPv4 is 192.168.0.28, ipV6 is undefined Mar 03 20:29:33 volumiolego wireless.js[703]: It's done! AP Mar 03 20:29:33 volumiolego systemd[1]: Started Wireless Services. Mar 03 20:29:33 volumiolego kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 03 20:29:33 volumiolego systemd[1]: Started Volumio Backend Module. Mar 03 20:29:33 volumiolego systemd[1]: Started Volumio Cpu Tweaker. Mar 03 20:29:33 volumiolego volumio-cpu-tweak[1050]: Setting RT Priority for mpd Mar 03 20:29:33 volumiolego volumio-cpu-tweak[1050]: Setting MPD Affinity Mar 03 20:29:33 volumiolego volumio-cpu-tweak[1050]: pid 900's current affinity mask: f Mar 03 20:29:33 volumiolego volumio-cpu-tweak[1050]: pid 900's new affinity mask: 3 Mar 03 20:29:33 volumiolego volumio-cpu-tweak[1050]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 03 20:29:33 volumiolego volumio-cpu-tweak[1050]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 03 20:29:33 volumiolego nmbd[862]: [2025/03/03 20:29:33.431661, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 03 20:29:33 volumiolego nmbd[862]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 03 20:29:33 volumiolego systemd[1]: Started Samba NMB Daemon. Mar 03 20:29:33 volumiolego systemd[1]: Starting Samba Winbind Daemon... Mar 03 20:29:33 volumiolego systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 03 20:29:33 volumiolego winbindd[1066]: [2025/03/03 20:29:33.815391, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 03 20:29:33 volumiolego winbindd[1066]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 03 20:29:33 volumiolego winbindd[1066]: [2025/03/03 20:29:33.843350, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 03 20:29:33 volumiolego systemd[1]: Started Samba Winbind Daemon. Mar 03 20:29:33 volumiolego winbindd[1066]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 03 20:29:33 volumiolego systemd[1]: Starting Samba SMB Daemon... Mar 03 20:29:34 volumiolego ntpd[867]: Listen normally on 3 wlan0 192.168.0.28:123 Mar 03 20:29:34 volumiolego ntpd[867]: new interface(s) found: waking up resolver Mar 03 20:29:34 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:34] [info] asio async_connect error: system:111 (Connection refused) Mar 03 20:29:34 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 03 20:29:34 volumiolego volumio-remote-updater[680]: [2025-03-03 20:29:34] [error] handle_connect error: Underlying Transport Error Mar 03 20:29:34 volumiolego smbd[1071]: [2025/03/03 20:29:34.458989, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 03 20:29:34 volumiolego systemd[1]: Started Samba SMB Daemon. Mar 03 20:29:34 volumiolego smbd[1071]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 03 20:29:36 volumiolego volumio[1049]: info: ------------------------------------------- Mar 03 20:29:36 volumiolego volumio[1049]: info: ----- Volumio3 ---- Mar 03 20:29:36 volumiolego volumio[1049]: info: ------------------------------------------- Mar 03 20:29:36 volumiolego volumio[1049]: info: ----- System startup ---- Mar 03 20:29:36 volumiolego volumio[1049]: info: ------------------------------------------- Mar 03 20:29:36 volumiolego volumio-time-update[686]: volumio-time-update-util: Fetching time from Volumio... Mar 03 20:29:37 volumiolego volumio-time-update[686]: volumio-time-update-util: Setting system time to: 2025-03-03 20:29:59 Mar 03 20:29:37 volumiolego sudo[1095]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-03-03 20:29:59 Mar 03 20:29:37 volumiolego sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:29:37 volumiolego dbus-daemon[694]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1096 comm="timedatectl set-time 2025-03-03 20:29:59 ") Mar 03 20:29:37 volumiolego systemd[1]: Starting Time & Date Service... Mar 03 20:29:37 volumiolego dbus-daemon[694]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 03 20:29:37 volumiolego systemd[1]: Started Time & Date Service. Mar 03 20:29:59 volumiolego systemd-timedated[1097]: Changed local time to Mon Mar 3 20:29:59 2025 Mar 03 20:29:59 volumiolego sudo[1095]: pam_unix(sudo:session): session closed for user root Mar 03 20:29:59 volumiolego volumio-time-update[686]: volumio-time-update-util: System time updated successfully. Mar 03 20:29:59 volumiolego systemd[1]: Started Volumio Time Update Utility. Mar 03 20:29:59 volumiolego systemd[1]: Reached target Multi-User System. Mar 03 20:29:59 volumiolego systemd[1]: Reached target Graphical Interface. Mar 03 20:29:59 volumiolego systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 03 20:29:59 volumiolego systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 03 20:29:59 volumiolego systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 03 20:29:59 volumiolego systemd[1]: Startup finished in 13.706s (kernel) + 21.762s (userspace) = 35.468s. Mar 03 20:29:59 volumiolego volumio[1049]: info: MYVOLUMIO Environment detected Mar 03 20:29:59 volumiolego volumio[1049]: info: Plugin folders cleanup Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning into folder /volumio/app/plugins/ Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category audio_interface Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category miscellanea Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category music_service Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category plugins.json Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category system_controller Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category user_interface Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning into folder /data/plugins/ Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category music_service Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category system_hardware Mar 03 20:29:59 volumiolego volumio[1049]: info: Scanning category user_interface Mar 03 20:29:59 volumiolego volumio[1049]: info: Plugin folders cleanup completed Mar 03 20:29:59 volumiolego volumio[1049]: info: ------------------------------------------- Mar 03 20:29:59 volumiolego volumio[1049]: info: ----- Core plugins startup ---- Mar 03 20:29:59 volumiolego volumio[1049]: info: ------------------------------------------- Mar 03 20:29:59 volumiolego volumio[1049]: info: Loading plugins from folder /volumio/app/plugins/ Mar 03 20:29:59 volumiolego volumio[1049]: info: Adding plugin upnp to MyMusic Plugins Mar 03 20:29:59 volumiolego volumio[1049]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 03 20:29:59 volumiolego volumio[1049]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 03 20:29:59 volumiolego volumio[1049]: info: Loading plugins from folder /data/plugins/ Mar 03 20:29:59 volumiolego volumio[1049]: info: Loading plugin "system"... Mar 03 20:29:59 volumiolego volumio[1049]: info: Loading plugin "appearance"... Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "network"... Mar 03 20:30:01 volumiolego volumio[1049]: info: Refreshing Cached IP Addresses Mar 03 20:30:01 volumiolego sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 20:30:01 volumiolego sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:01 volumiolego sudo[1105]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:01 volumiolego sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 20:30:01 volumiolego sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:01 volumiolego sudo[1107]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "services"... Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "alsa_controller"... Mar 03 20:30:01 volumiolego sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 03 20:30:01 volumiolego sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:01 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "wizard"... Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "networkfs"... Mar 03 20:30:01 volumiolego volumio[1049]: info: Starting Udev Watcher for removable devices Mar 03 20:30:01 volumiolego sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tom,password=Tkl60318!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.69/Multimedia/Music/Kinder /mnt/NAS/Kinder Mar 03 20:30:01 volumiolego sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:01 volumiolego volumio[1049]: info: Ignoring mount for partition: boot Mar 03 20:30:01 volumiolego volumio[1049]: info: Ignoring mount for partition: volumio Mar 03 20:30:01 volumiolego volumio[1049]: info: Ignoring mount for partition: volumio_data Mar 03 20:30:01 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "volumio_command_line_client"... Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "upnp"... Mar 03 20:30:01 volumiolego volumio[1049]: info: [1741030201575] Starting Upmpd Daemon Mar 03 20:30:01 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "my_music"... Mar 03 20:30:01 volumiolego volumio[1049]: info: Loading plugin "mpd"... Mar 03 20:30:01 volumiolego kernel: Key type cifs.spnego registered Mar 03 20:30:01 volumiolego kernel: Key type cifs.idmap registered Mar 03 20:30:01 volumiolego kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 03 20:30:01 volumiolego kernel: CIFS: Attempting to mount //192.168.0.69/Multimedia/Music/Kinder Mar 03 20:30:01 volumiolego kernel: cryptd: max_cpu_qlen set to 1000 Mar 03 20:30:02 volumiolego sudo[1116]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:02 volumiolego volumio[1049]: info: Loading plugin "upnp_browser"... Mar 03 20:30:02 volumiolego sudo[1133]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:02 volumiolego systemd[1]: Starting Daily apt download activities... Mar 03 20:30:02 volumiolego volumio[1049]: info: Loading plugin "alarm-clock"... Mar 03 20:30:03 volumiolego volumio[1049]: info: Loading plugin "airplay_emulation"... Mar 03 20:30:03 volumiolego volumio[1049]: info: Starting Shairport Sync Mar 03 20:30:03 volumiolego volumio[1049]: info: Loading plugin "last_100"... Mar 03 20:30:03 volumiolego volumio[1049]: info: Loading plugin "webradio"... Mar 03 20:30:03 volumiolego volumio[1049]: info: Loading plugin "i2s_dacs"... Mar 03 20:30:03 volumiolego volumio[1049]: info: Loading plugin "volumiodiscovery"... Mar 03 20:30:03 volumiolego volumio[1049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 03 20:30:03 volumiolego volumio[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 20:30:03 volumiolego volumio[1049]: *** WARNING *** For more information see Mar 03 20:30:03 volumiolego volumio[1049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 03 20:30:03 volumiolego node[1049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 03 20:30:03 volumiolego volumio[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 20:30:03 volumiolego volumio[1049]: *** WARNING *** For more information see Mar 03 20:30:03 volumiolego node[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 20:30:03 volumiolego node[1049]: *** WARNING *** For more information see Mar 03 20:30:03 volumiolego node[1049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 03 20:30:03 volumiolego node[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 20:30:03 volumiolego node[1049]: *** WARNING *** For more information see Mar 03 20:30:03 volumiolego volumio[1049]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 03 20:30:03 volumiolego volumio[1049]: info: Discovery: Started advertising with name: VolumioLEGO Mar 03 20:30:03 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 20:30:03 volumiolego volumio[1049]: info: Loading plugin "spop"... Mar 03 20:30:05 volumiolego volumio-remote-updater[680]: [2025-03-03 20:30:05] [connect] Successful connection Mar 03 20:30:05 volumiolego systemd[1]: apt-daily.service: Succeeded. Mar 03 20:30:05 volumiolego systemd[1]: Started Daily apt download activities. Mar 03 20:30:06 volumiolego volumio[1049]: info: Loading plugin "outputs"... Mar 03 20:30:06 volumiolego volumio[1049]: info: Loading plugin "albumart"... Mar 03 20:30:06 volumiolego volumio[1049]: info: Plugin example_plugin is not enabled Mar 03 20:30:06 volumiolego volumio[1049]: info: Loading plugin "inputs"... Mar 03 20:30:06 volumiolego volumio[1049]: info: Loading plugin "updater_comm"... Mar 03 20:30:06 volumiolego volumio[1049]: info: Plugin mpdemulation is not enabled Mar 03 20:30:06 volumiolego volumio[1049]: info: Loading plugin "rest_api"... Mar 03 20:30:06 volumiolego volumio[1049]: info: Loading plugin "websocket"... Mar 03 20:30:06 volumiolego volumio[1049]: info: Starting Socket.io Server version 2.3.0 Mar 03 20:30:06 volumiolego volumio[1049]: info: Loading plugin "rotaryencoder2"... Mar 03 20:30:06 volumiolego volumio[1049]: Forking 3 albumart workers Mar 03 20:30:07 volumiolego volumio[1049]: info: Loading plugin "touch_display"... Mar 03 20:30:07 volumiolego volumio[1049]: Starting albumart workers Mar 03 20:30:07 volumiolego volumio[1049]: Starting albumart workers Mar 03 20:30:07 volumiolego volumio[1049]: Starting albumart workers Mar 03 20:30:07 volumiolego volumio[1049]: info: Applying required configuration parameters for plugin touch_display Mar 03 20:30:08 volumiolego volumio[1049]: info: Loading i18n strings for locale de Mar 03 20:30:08 volumiolego volumio[1049]: Updating browse sources language Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::initPlayerControls Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:30:08 volumiolego volumio[1049]: Express server listening on port 3000 Mar 03 20:30:08 volumiolego volumio[1049]: [Metrics] WebUI: 11s 504.44ms Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreStateMachine::resetVolumioState Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreStateMachine::getcurrentVolume Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::volumioRetrievevolume Mar 03 20:30:08 volumiolego volumio[1049]: info: Volumio Network Manager: Network status updated: 2 Mar 03 20:30:08 volumiolego volumio[1049]: verbose: New Socket.io Connection to 192.168.0.28:3000 from 192.168.0.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 03 20:30:08 volumiolego volumio-remote-updater[680]: [2025-03-03 20:30:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741030205 101 Mar 03 20:30:08 volumiolego volumio[1049]: 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 Mar 03 20:30:08 volumiolego volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:08 volumiolego volumio[1049]: info: Reloading queue from file Mar 03 20:30:08 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 03 20:30:09 volumiolego volumio[1049]: info: Setting Device type: Raspberry PI Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreStateMachine::setRepeat null single undefined Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreStateMachine::pushState Mar 03 20:30:09 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioPushState Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreStateMachine::setRandom false Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreStateMachine::pushState Mar 03 20:30:09 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioPushState Mar 03 20:30:09 volumiolego volumio[1049]: info: Completed loading Core Plugins Mar 03 20:30:09 volumiolego volumio[1049]: info: Preparing to generate the ALSA configuration file Mar 03 20:30:09 volumiolego volumio[1049]: info: VolumeController:: Volume=4 Mute =false Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreStateMachine::pushState Mar 03 20:30:09 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioPushState Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreStateMachine::updateTrackBlock Mar 03 20:30:09 volumiolego volumio[1049]: info: CorePlayQueue::getTrackBlock Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioRetrievevolume Mar 03 20:30:09 volumiolego volumio[1049]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 03 20:30:09 volumiolego volumio[1049]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Mar 03 20:30:09 volumiolego volumio[1049]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioGetState Mar 03 20:30:09 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:09 volumiolego volumio[1049]: info: Discovery: adding 0a832379-d93e-4b9b-9939-a922071c46b3 Mar 03 20:30:09 volumiolego volumio[1049]: info: Discovery: Found device VolumioLEGO Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioGetState Mar 03 20:30:09 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:09 volumiolego volumio[1049]: info: Asound.conf file unchanged, so no further update is needed Mar 03 20:30:09 volumiolego volumio[1049]: info: Output device has changed, restarting MPD Mar 03 20:30:09 volumiolego volumio[1049]: info: Output device has changed, restarting Shairport Sync Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:09 volumiolego sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 20:30:09 volumiolego sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:09 volumiolego sudo[1290]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:09 volumiolego sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 20:30:09 volumiolego sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:09 volumiolego systemd[1]: Stopping Music Player Daemon... Mar 03 20:30:09 volumiolego volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 20:30:09 volumiolego volumio[1049]: info: ___________ START PLUGINS ___________ Mar 03 20:30:09 volumiolego volumio[1049]: info: ControllerMpd::onStart: Initializing MPD Mar 03 20:30:09 volumiolego volumio[1049]: info: Creating MPD Configuration file Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 20:30:09 volumiolego volumio[1049]: info: [1741030209369] CoreMusicLibrary::Adding element Medienserver Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 20:30:09 volumiolego sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 20:30:09 volumiolego sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:09 volumiolego sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 20:30:09 volumiolego sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:09 volumiolego sudo[1299]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:09 volumiolego volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:09 volumiolego systemd[1]: mpd.service: Succeeded. Mar 03 20:30:09 volumiolego systemd[1]: Stopped Music Player Daemon. Mar 03 20:30:09 volumiolego volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 20:30:09 volumiolego volumio[1049]: info: [1741030209518] CoreMusicLibrary::Adding element Last_100 Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 20:30:09 volumiolego volumio[1049]: info: [1741030209521] CoreMusicLibrary::Adding element Webradio Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 20:30:09 volumiolego volumio[1049]: info: Initializing BBC Radios Mar 03 20:30:09 volumiolego systemd[1]: Starting Music Player Daemon... Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:09 volumiolego volumio[1049]: info: Creating Spotify config file Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:09 volumiolego sudo[1310]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 20:30:09 volumiolego sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:09 volumiolego sudo[1310]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:09 volumiolego volumio[1049]: info: Loading i18n strings for locale de Mar 03 20:30:09 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 20:30:09 volumiolego volumio[1049]: info: Volumio Calling Home Mar 03 20:30:09 volumiolego sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=27 relative_axis=true steps-per-period=1 Mar 03 20:30:09 volumiolego sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:09 volumiolego sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 03 20:30:09 volumiolego sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 03 20:30:09 volumiolego sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:09 volumiolego sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:09 volumiolego sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 03 20:30:09 volumiolego sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:10 volumiolego sudo[1327]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:10 volumiolego systemd[1]: Reloading. Mar 03 20:30:10 volumiolego volumio[1049]: info: VolumeController:: Volume=4 Mute =false Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreStateMachine::pushState Mar 03 20:30:10 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:10 volumiolego kernel: rotary-encoder rotary@16: gray Mar 03 20:30:10 volumiolego kernel: input: rotary@16 as /devices/platform/rotary@16/input/input3 Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::volumioPushState Mar 03 20:30:10 volumiolego volumio[1049]: info: Discovery: adding 90de26a0-0915-46ea-9c61-7ad18a7ab556 Mar 03 20:30:10 volumiolego volumio[1049]: info: Discovery: Found device VolumioDach Mar 03 20:30:10 volumiolego volumio[1049]: info: Discovery: Connecting to remote: 192.168.0.58 Mar 03 20:30:10 volumiolego volumio[1049]: info: touch_display: Backlight interface detected. Mar 03 20:30:10 volumiolego volumio[1049]: info: MPD Permissions set Mar 03 20:30:10 volumiolego volumio[1049]: info: MPD Permissions set Mar 03 20:30:10 volumiolego volumio[1049]: info: Spotify config file written Mar 03 20:30:10 volumiolego volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 03 20:30:10 volumiolego volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 03 20:30:10 volumiolego volumio[1049]: info: Volumio called home Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 03 20:30:10 volumiolego sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego systemd-udevd[1343]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:30:10 volumiolego volumio[1049]: info: No need to fix Spotify hosts Mar 03 20:30:10 volumiolego volumio[1049]: info: Discovery: Connected to remote: 192.168.0.58 Mar 03 20:30:10 volumiolego systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 03 20:30:10 volumiolego sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Mar 03 20:30:10 volumiolego sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:10 volumiolego volumio[1049]: info: touch_display: Raspberry Pi Foundation touch screen detected. Mar 03 20:30:10 volumiolego sudo[1376]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:10 volumiolego sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 03 20:30:10 volumiolego sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:10 volumiolego sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Mar 03 20:30:10 volumiolego sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:10 volumiolego sudo[1378]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:10 volumiolego volumio[1049]: info: Starting Shairport Sync Mar 03 20:30:10 volumiolego sudo[1381]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:10 volumiolego volumio[1049]: info: Starting Shairport Sync Mar 03 20:30:10 volumiolego sudo[1385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 20:30:10 volumiolego volumio[1049]: info: Starting Shairport Sync Mar 03 20:30:10 volumiolego sudo[1385]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:10 volumiolego sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 20:30:10 volumiolego sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:10 volumiolego volumio[1049]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 03 20:30:10 volumiolego volumio[1049]: SPOTIFY: BQA3udGhbQ0NH8udGDvNtZGxuVEdqzCAIYlpRZTgvP1Qnv4dlXt-Mqe2wv0rHLrWvvMT4ssHLWDHqZnc299xrpOGHA25_C4mJm4wHbSZcg_DjDpdomhT9iL7KR35ijN-Y52tOdBNy_GGXL733-2Gii6sLPMBWlBoFxmMo0nEtWoDcxOqFQO2duP5bNkJPLa3Z2O20oY13ITUYMqV8zMmeHNnclHvhpuTihjpGgvmHbx6f2mLZb8VCQ2S1O1tyWYb4GO2LYiBtnvvDpvMKlGgN0wTq49-GqEyVPuVYzLBqwI Mar 03 20:30:10 volumiolego volumio[1049]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 03 20:30:10 volumiolego sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 20:30:10 volumiolego volumio[1049]: info: New Spotify access token = BQA3udGhbQ0NH8udGDvNtZGxuVEdqzCAIYlpRZTgvP1Qnv4dlXt-Mqe2wv0rHLrWvvMT4ssHLWDHqZnc299xrpOGHA25_C4mJm4wHbSZcg_DjDpdomhT9iL7KR35ijN-Y52tOdBNy_GGXL733-2Gii6sLPMBWlBoFxmMo0nEtWoDcxOqFQO2duP5bNkJPLa3Z2O20oY13ITUYMqV8zMmeHNnclHvhpuTihjpGgvmHbx6f2mLZb8VCQ2S1O1tyWYb4GO2LYiBtnvvDpvMKlGgN0wTq49-GqEyVPuVYzLBqwI Mar 03 20:30:10 volumiolego volumio[1049]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 03 20:30:10 volumiolego sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:10 volumiolego systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::volumioGetState Mar 03 20:30:10 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:10 volumiolego volumio[1049]: info: CoreCommandRouter::volumioGetState Mar 03 20:30:10 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:10 volumiolego volumio[1049]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 03 20:30:10 volumiolego volumio[1049]: info: touch_display: File permissions for backlight brightness control set. Mar 03 20:30:11 volumiolego volumio[1049]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Mar 03 20:30:11 volumiolego sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=5 active_low=1 gpio_pull=up keycode=20 Mar 03 20:30:11 volumiolego sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:11 volumiolego systemd[1]: systemd-fsckd.service: Succeeded. Mar 03 20:30:11 volumiolego systemd[1]: Reloading. Mar 03 20:30:11 volumiolego sudo[1336]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:11 volumiolego volumio[1049]: info: touch_display: systemctl daemon-reload succeeded. Mar 03 20:30:11 volumiolego kernel: input: button@5 as /devices/platform/button@5/input/input4 Mar 03 20:30:11 volumiolego sudo[1396]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:11 volumiolego sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 03 20:30:11 volumiolego sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:11 volumiolego volumio[1049]: SPOTIFY: User informations: {"country":"DE","display_name":"Thomas Kugler","email":"mail@kuglertom.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31zxbbvzkrp7nw3jq6mlbhwvrhkm"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/31zxbbvzkrp7nw3jq6mlbhwvrhkm","id":"31zxbbvzkrp7nw3jq6mlbhwvrhkm","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=689750997738967&height=300&width=300&ext=1743622100&hash=AbZHpl8ZdTZ2yKK6-bTNZ-2k","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=689750997738967&height=50&width=50&ext=1743622100&hash=AbYxIhpb2AiFDqCs-_W6SJOK","width":64}],"product":"premium","type":"user","uri":"spotify:user:31zxbbvzkrp7nw3jq6mlbhwvrhkm"} Mar 03 20:30:11 volumiolego volumio[1049]: info: Spotify Successfully logged in Mar 03 20:30:11 volumiolego volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 20:30:11 volumiolego volumio[1049]: info: [1741030211294] CoreMusicLibrary::Adding element Spotify Mar 03 20:30:11 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 20:30:11 volumiolego volumio[1049]: Cannot find translation for source Spotify Mar 03 20:30:11 volumiolego systemd-udevd[1343]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 03 20:30:11 volumiolego sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 20:30:11 volumiolego sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:11 volumiolego sudo[1438]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:11 volumiolego systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 03 20:30:11 volumiolego sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 03 20:30:11 volumiolego sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:11 volumiolego sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 20:30:11 volumiolego sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:11 volumiolego sudo[1440]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:11 volumiolego mpd[1322]: Mar 03 20:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 20:30:12 volumiolego systemd[1]: Started Music Player Daemon. Mar 03 20:30:12 volumiolego sudo[1292]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego sudo[1332]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego sudo[1301]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 03 20:30:12 volumiolego volumio[1049]: error: MPD error: The expression evaluated to a falsy value: Mar 03 20:30:12 volumiolego volumio[1049]: assert.ok(self.idling) Mar 03 20:30:12 volumiolego volumio[1049]: error: The expression evaluated to a falsy value: Mar 03 20:30:12 volumiolego volumio[1049]: assert.ok(self.idling) Mar 03 20:30:12 volumiolego volumio[1049]: info: MPD running with PID1322 Mar 03 20:30:12 volumiolego volumio[1049]: ,establishing connection Mar 03 20:30:12 volumiolego volumio[1049]: error: updateQueue error: null Mar 03 20:30:12 volumiolego volumio[1049]: error: updateQueue error: null Mar 03 20:30:12 volumiolego sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 03 20:30:12 volumiolego sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:12 volumiolego sudo[1452]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego systemd[1]: Started UPnP Renderer front-end to MPD. Mar 03 20:30:12 volumiolego sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 03 20:30:12 volumiolego sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:12 volumiolego systemd[1]: Started Volumio Kiosk. Mar 03 20:30:12 volumiolego systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 20:30:12 volumiolego sudo[1402]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego systemd[1]: Started go-librespot Daemon. Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 03 20:30:12 volumiolego sudo[1456]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: Volumio Kiosk started. Mar 03 20:30:12 volumiolego sudo[1351]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego go-librespot[1459]: go-librespot daemon starting... Mar 03 20:30:12 volumiolego volumio[1049]: info: CoreCommandRouter::volumioGetState Mar 03 20:30:12 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:12 volumiolego systemd[1]: shairport-sync.service: Succeeded. Mar 03 20:30:12 volumiolego systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 20:30:12 volumiolego volumio[1049]: info: CoreCommandRouter::volumioGetState Mar 03 20:30:12 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:12 volumiolego sudo[1443]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego volumio[1049]: info: Completed starting Core Plugins Mar 03 20:30:12 volumiolego volumio[1049]: info: ------------------------------------------- Mar 03 20:30:12 volumiolego volumio[1049]: info: ----- MyVolumio plugins startup ---- Mar 03 20:30:12 volumiolego volumio[1049]: info: ------------------------------------------- Mar 03 20:30:12 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 03 20:30:12 volumiolego sudo[1330]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: X display number found: Mar 03 20:30:12 volumiolego volumio[1049]: info: Upmpdcli Daemon Started Mar 03 20:30:12 volumiolego systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 20:30:12 volumiolego sudo[1390]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego sudo[1387]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego volumio[1049]: info: Shairport-Sync Started Mar 03 20:30:12 volumiolego sudo[1385]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:12 volumiolego volumio[1049]: Error adding Membership: Error: addMembership EINVAL Mar 03 20:30:12 volumiolego volumio[1049]: info: Shairport-Sync Started Mar 03 20:30:12 volumiolego volumio[1049]: info: Shairport-Sync Started Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: X display number found: 0 Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: X display number found: 0 Mar 03 20:30:12 volumiolego volumio[1450]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 03 20:30:12 volumiolego startx[1457]: X.Org X Server 1.20.4 Mar 03 20:30:12 volumiolego startx[1457]: X Protocol Version 11, Revision 0 Mar 03 20:30:12 volumiolego startx[1457]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 03 20:30:12 volumiolego startx[1457]: Current Operating System: Linux volumiolego 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Mar 03 20:30:12 volumiolego startx[1457]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 03 20:30:12 volumiolego startx[1457]: Build Date: 04 April 2023 07:50:56AM Mar 03 20:30:12 volumiolego startx[1457]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 03 20:30:12 volumiolego startx[1457]: Current version of pixman: 0.36.0 Mar 03 20:30:12 volumiolego startx[1457]: Before reporting problems, check http://wiki.x.org Mar 03 20:30:12 volumiolego startx[1457]: to make sure that you have the latest version. Mar 03 20:30:12 volumiolego startx[1457]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 03 20:30:12 volumiolego startx[1457]: (++) from command line, (!!) notice, (II) informational, Mar 03 20:30:12 volumiolego startx[1457]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 03 20:30:12 volumiolego startx[1457]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Mar 3 20:30:12 2025 Mar 03 20:30:12 volumiolego startx[1457]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 03 20:30:12 volumiolego startx[1457]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 03 20:30:12 volumiolego volumio[1049]: info: touch_display: X display number found: 0 Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=debug msg="app state loaded" Mar 03 20:30:13 volumiolego sh[606]: timed out Mar 03 20:30:13 volumiolego dhcpcd[649]: timed out Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 20:30:13 volumiolego sh[606]: dhcpcd exited Mar 03 20:30:13 volumiolego dhcpcd[649]: dhcpcd exited Mar 03 20:30:13 volumiolego sh[606]: ifup: failed to bring up eth0 Mar 03 20:30:13 volumiolego systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 03 20:30:13 volumiolego systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01: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-gew1.spotify.com:80]" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=info msg="zeroconf server listening on port 35985" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=debug msg="obtained new client token: AADB47EWZFYTI9WRld3JXik49nV71FQHpN2dY4qEn2ZAMX/MHL+FFJ5SIuLWyb8RXlKDFpGxeuXAdYC/jt77ta4e0ZgpjbTRRj4yX+X1nqexzKfEcErcqiTYVVsCR9EPdEK9lH2Ns0IL7R+oKrHKWVLzAw+qk+PlvNgrGgeYdJqoalTIvu5aa2M+7N606RvXrWzEH2JI5adiIT07MyHCGaklkzytoXjkD8PFVFyQUASsDuO7xVy6HGq8lg==" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=debug msg="completed keyexchange" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=debug msg="completed challenge" Mar 03 20:30:13 volumiolego go-librespot[1459]: time="2025-03-03T20:30:13+01:00" level=info msg="authenticated AP as 31zxbbvzkrp7nw3jq6mlbhwvrhkm" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=info msg="authenticated Login5 as 31zxbbvzkrp7nw3jq6mlbhwvrhkm" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="initializing zeroconf session, username: 31zxbbvzkrp7nw3jq6mlbhwvrhkm" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="dealer connection opened" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=trace msg="starting accesspoint recv loop" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=trace msg="starting dealer recv loop" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=trace msg="received accesspoint ping" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="received connection id: YTIzODhiYjYtNzc0OS00NDY0LWFjYmYtMjM3Yjk3ZTk4Y2ZjK2RlYWxlcit0Y3A6Ly8wYWNhNWExOC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArOEUxM0Y1REQ1QURENTYwMjg5QkYzMTAxNjQ0RTQwNjc3RTE5OUI2OTA0MDcxMDlCRTc2OUZCRjlCQzRFRDA2MA==" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=trace msg="received accesspoint pong ack" Mar 03 20:30:14 volumiolego go-librespot[1459]: time="2025-03-03T20:30:14+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 03 20:30:14 volumiolego kernel: broken atomic modeset userspace detected, disabling atomic Mar 03 20:30:14 volumiolego systemd[1]: systemd-hostnamed.service: Succeeded. Mar 03 20:30:14 volumiolego startx[1457]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Mar 03 20:30:15 volumiolego volumio[1049]: info: go-librespot daemon successfully initialized Mar 03 20:30:15 volumiolego volumio[1049]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 03 20:30:17 volumiolego volumio[1049]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 03 20:30:18 volumiolego volumio[1049]: info: Initializing connection to go-librespot Websocket Mar 03 20:30:18 volumiolego go-librespot[1459]: time="2025-03-03T20:30:18+01:00" level=debug msg="new websocket client" Mar 03 20:30:18 volumiolego volumio[1049]: info: Connection to go-librespot Websocket established Mar 03 20:30:19 volumiolego volumio[1450]: ............................................++++ Mar 03 20:30:20 volumiolego volumio[1450]: ........++++ Mar 03 20:30:20 volumiolego volumio[1450]: e is 65537 (0x010001) Mar 03 20:30:20 volumiolego volumio[1450]: writing RSA key Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 03 20:30:21 volumiolego volumio[1049]: info: Adding plugin bluetooth to MyMusic Plugins Mar 03 20:30:21 volumiolego volumio[1049]: info: Adding plugin multiroom to MyMusic Plugins Mar 03 20:30:21 volumiolego volumio[1049]: info: Adding plugin metavolumio to MyMusic Plugins Mar 03 20:30:21 volumiolego volumio[1049]: info: Adding plugin cd_controller to MyMusic Plugins Mar 03 20:30:21 volumiolego volumio[1049]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 03 20:30:21 volumiolego volumio[1049]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 03 20:30:21 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 03 20:30:21 volumiolego go-librespot[1459]: time="2025-03-03T20:30:21+01:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:5EFDWgDxlSwktKXnw1aqRN/resume-point-revision/a5e33461-0327-4993-a158-1b1a09ad3cf5" Mar 03 20:30:21 volumiolego go-librespot[1459]: time="2025-03-03T20:30:21+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 285" Mar 03 20:30:22 volumiolego volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 03 20:30:22 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 03 20:30:22 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:22 volumiolego volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:30:22 volumiolego volumio[1049]: info: Starting MyVolumio Remote Streaming Endpoints Mar 03 20:30:22 volumiolego volumio[1049]: info: MyVolumio login type: Token Mar 03 20:30:22 volumiolego volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 03 20:30:22 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=debug msg="handling transfer player command from 4936bf2880db2cb735952aec809e9d338859b682" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=debug msg="resolved context of episode" uri="spotify:show:6LuAZxNcc4sjMoJRhfZI4L" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=trace msg="fetched new page 0 with 33 items (list: 33)" uri="spotify:show:6LuAZxNcc4sjMoJRhfZI4L" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=debug msg="loading episode (paused: false, position: 31915ms)" uri="spotify:episode:5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=trace msg="emitting websocket event: will_play" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=debug msg="selected format OGG_VORBIS_96 (13427965cf298764a1b94dd72f1b69495cd52335)" uri="spotify:episode:5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=debug msg="requested aes key for file 13427965cf298764a1b94dd72f1b69495cd52335, gid: 5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:23 volumiolego go-librespot[1459]: time="2025-03-03T20:30:23+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2030" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1461" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="fetched first chunk of 33, total size is 16906848 bytes" uri="spotify:episode:5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=trace msg="seek to 31915ms (diff: 291ms, samples: 1407451, bytes: 365673)" uri="spotify:episode:5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="created new output device" Mar 03 20:30:24 volumiolego kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=info msg="loaded episode \"\\\"Was ist Mut?\\\" MIRA SHOW\" (paused: false, position: 31915ms, duration: 1564000ms, prefetched: false)" uri="spotify:episode:5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="fetched chunk 3/32, size: 524288" uri="spotify:episode:5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=trace msg="scheduling prefetch in 1502s" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=trace msg="emitting websocket event: metadata" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=trace msg="emitting websocket event: active" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="sending successful reply for dealer request" Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 03 20:30:24 volumiolego go-librespot[1459]: time="2025-03-03T20:30:24+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 03 20:30:25 volumiolego go-librespot[1459]: time="2025-03-03T20:30:25+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 03 20:30:25 volumiolego go-librespot[1459]: time="2025-03-03T20:30:25+01:00" level=trace msg="emitting websocket event: playing" Mar 03 20:30:25 volumiolego volumio[1049]: info: Starting Streaming Service Transparent Proxy Mar 03 20:30:25 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 03 20:30:25 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 03 20:30:25 volumiolego volumio[1049]: info: Streaming services startup Mar 03 20:30:25 volumiolego volumio[1049]: info: Starting Streaming Daemon Mar 03 20:30:25 volumiolego volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 03 20:30:25 volumiolego go-librespot[1459]: time="2025-03-03T20:30:25+01:00" level=debug msg="fetched chunk 2/32, size: 524288" uri="spotify:episode:5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:25 volumiolego sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 03 20:30:25 volumiolego sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:30:25 volumiolego volumio[1049]: info: Getting Spotify volume Mar 03 20:30:25 volumiolego sudo[1698]: pam_unix(sudo:session): session closed for user root Mar 03 20:30:25 volumiolego go-librespot[1459]: time="2025-03-03T20:30:25+01:00" level=debug msg="fetched chunk 1/32, size: 524288" uri="spotify:episode:5EFDWgDxlSwktKXnw1aqRN" Mar 03 20:30:25 volumiolego volumio[1049]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:episode:5EFDWgDxlSwktKXnw1aqRN","play_origin":"com.spotify.service.podcastepisode"}} Mar 03 20:30:25 volumiolego volumio[1049]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:episode:5EFDWgDxlSwktKXnw1aqRN","name":"\"Was ist Mut?\" MIRA SHOW","artist_names":["MiRA SHOW - der Kinderpodcast"],"album_name":"MiRA SHOW - der Kinderpodcast","album_cover_url":"https://i.scdn.co/image/ab6765630000f68d442e3e0e57900d9f7605a279","position":31915,"duration":1564000,"release_date":"","track_number":0,"disc_number":0}} Mar 03 20:30:25 volumiolego volumio[1049]: SPOTIFY: received: {"type":"active","data":null} Mar 03 20:30:25 volumiolego volumio[1049]: info: Aligning Spotify Volume to Volumio Volume Mar 03 20:30:25 volumiolego volumio[1049]: info: CoreCommandRouter::volumioGetState Mar 03 20:30:25 volumiolego volumio[1049]: info: CorePlayQueue::getTrack 0 Mar 03 20:30:25 volumiolego volumio[1049]: info: Setting Spotify Volume from Volumio: 4 Mar 03 20:30:25 volumiolego volumio[1049]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:episode:5EFDWgDxlSwktKXnw1aqRN","play_origin":"com.spotify.service.podcastepisode"}} Mar 03 20:30:25 volumiolego volumio[1049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 20:30:25 volumiolego volumio[1049]: TypeError: Cannot read property 'service' of undefined Mar 03 20:30:25 volumiolego volumio[1049]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Mar 03 20:30:25 volumiolego volumio[1049]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Mar 03 20:30:25 volumiolego volumio[1049]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Mar 03 20:30:25 volumiolego volumio[1049]: at WebSocket.emit (events.js:315:20) Mar 03 20:30:25 volumiolego volumio[1049]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Mar 03 20:30:25 volumiolego volumio[1049]: at Receiver.emit (events.js:315:20) Mar 03 20:30:25 volumiolego volumio[1049]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Mar 03 20:30:25 volumiolego volumio[1049]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Mar 03 20:30:25 volumiolego volumio[1049]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Mar 03 20:30:25 volumiolego volumio[1049]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Mar 03 20:30:25 volumiolego volumio[1049]: at writeOrBuffer (internal/streams/writable.js:358:12) Mar 03 20:30:25 volumiolego volumio[1049]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Mar 03 20:30:25 volumiolego volumio[1049]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Mar 03 20:30:25 volumiolego volumio[1049]: at Socket.emit (events.js:315:20) Mar 03 20:30:25 volumiolego volumio[1049]: at addChunk (internal/streams/readable.js:309:12) Mar 03 20:30:25 volumiolego volumio[1049]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 03 20:30:25 volumiolego volumio[1049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 20:30:26 volumiolego sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 20:29 Mar 03 20:30:26 volumiolego sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"