-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-06-26 22:25:08 CEST. -- Jun 26 22:24:05 jukebox fake-hwclock[306]: Thu Jun 26 20:24:05 UTC 2025 Jun 26 22:24:05 jukebox systemd[1]: Starting Load/Save Random Seed... Jun 26 22:24:05 jukebox systemd[1]: Starting Create System Users... Jun 26 22:24:05 jukebox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 26 22:24:05 jukebox systemd[1]: Started Restore / save the current clock. Jun 26 22:24:05 jukebox systemd[1]: Started Load/Save Random Seed. Jun 26 22:24:05 jukebox systemd[1]: Started Create System Users. Jun 26 22:24:05 jukebox systemd[1]: Starting Create Static Device Nodes in /dev... Jun 26 22:24:05 jukebox systemd[1]: dynamicswap.service: Succeeded. Jun 26 22:24:05 jukebox systemd[1]: Started udev Coldplug all Devices. Jun 26 22:24:05 jukebox systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jun 26 22:24:05 jukebox systemd[1]: Started Helper to synchronize boot up for ifupdown. Jun 26 22:24:05 jukebox systemd[1]: Started Create Static Device Nodes in /dev. Jun 26 22:24:05 jukebox systemd[1]: Reached target Local File Systems (Pre). Jun 26 22:24:05 jukebox systemd[1]: Mounting /var/spool/cups... Jun 26 22:24:05 jukebox systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 26 22:24:05 jukebox systemd[1]: Mounting /tmp... Jun 26 22:24:05 jukebox systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 26 22:24:05 jukebox systemd[1]: Mounting /var/log... Jun 26 22:24:05 jukebox systemd[1]: Starting udev Kernel Device Manager... Jun 26 22:24:05 jukebox systemd[1]: Mounted /var/spool/cups. Jun 26 22:24:05 jukebox systemd[1]: Mounted /tmp. Jun 26 22:24:05 jukebox systemd[1]: Mounted /var/log. Jun 26 22:24:05 jukebox systemd[1]: Starting Flush Journal to Persistent Storage... Jun 26 22:24:05 jukebox systemd[1]: Mounting /var/spool/cups/tmp... Jun 26 22:24:05 jukebox systemd[1]: Mounted /var/spool/cups/tmp. Jun 26 22:24:05 jukebox systemd-udevd[340]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jun 26 22:24:05 jukebox systemd-journald[315]: Runtime journal (/run/log/journal/a4d620ccb4a6a12010c39c5d649876fe) is 7.5M, max 30.0M, 22.5M free. Jun 26 22:24:05 jukebox systemd[1]: Started udev Kernel Device Manager. Jun 26 22:24:05 jukebox systemd[1]: Starting Show Plymouth Boot Screen... Jun 26 22:24:05 jukebox systemd[1]: Started Flush Journal to Persistent Storage. Jun 26 22:24:05 jukebox systemd[1]: plymouth-start.service: Succeeded. Jun 26 22:24:05 jukebox systemd[1]: Started Show Plymouth Boot Screen. Jun 26 22:24:05 jukebox systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jun 26 22:24:05 jukebox systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 26 22:24:05 jukebox systemd[1]: Reached target Paths. Jun 26 22:24:05 jukebox systemd[1]: Reached target Local Encrypted Volumes. Jun 26 22:24:05 jukebox kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Jun 26 22:24:05 jukebox kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Jun 26 22:24:05 jukebox kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jun 26 22:24:05 jukebox kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jun 26 22:24:05 jukebox kernel: [vc_sm_connected_init]: start Jun 26 22:24:05 jukebox kernel: [vc_sm_connected_init]: installed successfully Jun 26 22:24:05 jukebox kernel: mc: Linux media interface: v0.10 Jun 26 22:24:05 jukebox kernel: videodev: Linux video capture interface: v2.00 Jun 26 22:24:05 jukebox kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jun 26 22:24:05 jukebox kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jun 26 22:24:05 jukebox kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jun 26 22:24:05 jukebox kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 26 22:24:05 jukebox systemd[1]: Found device /dev/disk/by-uuid/7883-9333. Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 26 22:24:05 jukebox kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jun 26 22:24:05 jukebox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jun 26 22:24:06 jukebox kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 26 22:24:06 jukebox systemd[1]: Starting File System Check on /dev/disk/by-uuid/7883-9333... Jun 26 22:24:06 jukebox kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jun 26 22:24:06 jukebox kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jun 26 22:24:06 jukebox kernel: Bluetooth: Core ver 2.22 Jun 26 22:24:06 jukebox kernel: NET: Registered PF_BLUETOOTH protocol family Jun 26 22:24:06 jukebox kernel: Bluetooth: HCI device and connection manager initialized Jun 26 22:24:06 jukebox kernel: Bluetooth: HCI socket layer initialized Jun 26 22:24:06 jukebox kernel: Bluetooth: L2CAP socket layer initialized Jun 26 22:24:06 jukebox kernel: Bluetooth: SCO socket layer initialized Jun 26 22:24:06 jukebox systemd-udevd[343]: Using default interface naming scheme 'v240'. Jun 26 22:24:06 jukebox kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 26 22:24:06 jukebox kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jun 26 22:24:06 jukebox kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jun 26 22:24:06 jukebox kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 26 22:24:06 jukebox kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 26 22:24:06 jukebox kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 26 22:24:06 jukebox systemd-udevd[343]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 26 22:24:06 jukebox kernel: Bluetooth: HCI UART driver ver 2.3 Jun 26 22:24:06 jukebox kernel: Bluetooth: HCI UART protocol H4 registered Jun 26 22:24:06 jukebox kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jun 26 22:24:06 jukebox kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jun 26 22:24:06 jukebox kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jun 26 22:24:06 jukebox kernel: Bluetooth: HCI UART protocol Broadcom registered Jun 26 22:24:06 jukebox kernel: uart-pl011 3f201000.serial: no DMA platform data Jun 26 22:24:06 jukebox systemd-fsck[398]: fsck.fat 4.1 (2017-01-24) Jun 26 22:24:06 jukebox systemd-fsck[398]: /dev/mmcblk0p1: 411 files, 41753/46774 clusters Jun 26 22:24:06 jukebox systemd[1]: Started File System Check Daemon to report status. Jun 26 22:24:06 jukebox systemd[1]: Started File System Check on /dev/disk/by-uuid/7883-9333. Jun 26 22:24:06 jukebox systemd[1]: Mounting /boot... Jun 26 22:24:06 jukebox kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jun 26 22:24:06 jukebox kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jun 26 22:24:06 jukebox kernel: usbcore: registered new interface driver brcmfmac Jun 26 22:24:06 jukebox systemd[1]: Mounted /boot. Jun 26 22:24:06 jukebox systemd[1]: Reached target Local File Systems. Jun 26 22:24:06 jukebox systemd[1]: Starting Create Volatile Files and Directories... Jun 26 22:24:06 jukebox systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jun 26 22:24:06 jukebox systemd[1]: Starting Raise network interfaces... Jun 26 22:24:06 jukebox systemd[1]: Starting Preprocess NFS configuration... Jun 26 22:24:06 jukebox systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jun 26 22:24:06 jukebox kernel: Bluetooth: hci0: BCM: chip id 94 Jun 26 22:24:06 jukebox kernel: Bluetooth: hci0: BCM: features 0x2e Jun 26 22:24:06 jukebox kernel: Bluetooth: hci0: BCM43430A1 Jun 26 22:24:06 jukebox kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Jun 26 22:24:06 jukebox kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Jun 26 22:24:06 jukebox systemd[1]: Started Create Volatile Files and Directories. Jun 26 22:24:06 jukebox systemd[1]: plymouth-read-write.service: Succeeded. Jun 26 22:24:06 jukebox systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jun 26 22:24:06 jukebox systemd[1]: nfs-config.service: Succeeded. Jun 26 22:24:06 jukebox systemd[1]: Started Preprocess NFS configuration. Jun 26 22:24:06 jukebox systemd[1]: Received SIGRTMIN+20 from PID 199 (plymouthd). Jun 26 22:24:06 jukebox ifup[475]: command failed: No such device (-19) Jun 26 22:24:06 jukebox systemd-udevd[349]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 26 22:24:06 jukebox kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jun 26 22:24:06 jukebox 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 Jun 26 22:24:06 jukebox ifup[475]: command failed: No such device (-19) Jun 26 22:24:07 jukebox systemd-udevd[350]: Using default interface naming scheme 'v240'. Jun 26 22:24:07 jukebox systemd-udevd[350]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Jun 26 22:24:07 jukebox kernel: alsactl[536]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jun 26 22:24:07 jukebox kernel: Bluetooth: hci0: BCM: features 0x2e Jun 26 22:24:07 jukebox kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Jun 26 22:24:07 jukebox kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Jun 26 22:24:07 jukebox systemd[1]: Created slice system-bthelper.slice. Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jun 26 22:24:07 jukebox systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jun 26 22:24:07 jukebox systemd[1]: Starting Show Plymouth Boot Screen... Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jun 26 22:24:07 jukebox systemd[1]: Started ifup for wlan0. Jun 26 22:24:07 jukebox systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jun 26 22:24:07 jukebox systemd[1]: Reached target NFS client services. Jun 26 22:24:07 jukebox systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jun 26 22:24:07 jukebox systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jun 26 22:24:07 jukebox systemd[1]: Starting RPC bind portmap service... Jun 26 22:24:07 jukebox kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jun 26 22:24:07 jukebox systemd[1]: Started ifup for eth0. Jun 26 22:24:07 jukebox systemd[1]: Started Raise network interfaces. Jun 26 22:24:07 jukebox systemd[1]: plymouth-read-write.service: Succeeded. Jun 26 22:24:07 jukebox systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jun 26 22:24:07 jukebox systemd[1]: Received SIGRTMIN+20 from PID 199 (plymouthd). Jun 26 22:24:07 jukebox systemd[1]: plymouth-start.service: Succeeded. Jun 26 22:24:07 jukebox systemd[1]: Started Show Plymouth Boot Screen. Jun 26 22:24:07 jukebox systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 26 22:24:07 jukebox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 26 22:24:07 jukebox systemd[1]: Starting Load/Save RF Kill Switch Status... Jun 26 22:24:07 jukebox systemd[1]: Started Update UTMP about System Boot/Shutdown. Jun 26 22:24:07 jukebox systemd[1]: Reached target System Initialization. Jun 26 22:24:07 jukebox systemd[1]: Started Daily Cleanup of Temporary Directories. Jun 26 22:24:07 jukebox systemd[1]: Listening on triggerhappy.socket. Jun 26 22:24:07 jukebox systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jun 26 22:24:07 jukebox systemd[1]: Started Daily apt download activities. Jun 26 22:24:07 jukebox systemd[1]: Started Daily apt upgrade and clean activities. Jun 26 22:24:07 jukebox systemd[1]: Reached target Timers. Jun 26 22:24:07 jukebox systemd[1]: Listening on D-Bus System Message Bus Socket. Jun 26 22:24:07 jukebox systemd[1]: Reached target Sockets. Jun 26 22:24:07 jukebox systemd[1]: Reached target Basic System. Jun 26 22:24:08 jukebox systemd[1]: Started UPnP Renderer front-end to MPD. Jun 26 22:24:08 jukebox systemd[1]: Starting triggerhappy global hotkey daemon... Jun 26 22:24:08 jukebox systemd[1]: Started volumio-remote-updater.service. Jun 26 22:24:08 jukebox systemd[1]: Started D-Bus System Message Bus. Jun 26 22:24:08 jukebox systemd[1]: Starting Login Service... Jun 26 22:24:08 jukebox systemd[1]: Starting Wireless Services... Jun 26 22:24:08 jukebox thd[643]: Unable to parse trigger line: Jun 26 22:24:08 jukebox thd[643]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 26 22:24:08 jukebox thd[643]: Unable to parse trigger line: Jun 26 22:24:08 jukebox thd[643]: Found socket passed from systemd Jun 26 22:24:08 jukebox systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jun 26 22:24:08 jukebox systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jun 26 22:24:08 jukebox systemd[1]: Started Volumio Log Rotation Service. Jun 26 22:24:08 jukebox systemd[1]: Started Manage Sound Card State (restore and store). Jun 26 22:24:08 jukebox systemd[1]: Starting WPA supplicant... Jun 26 22:24:08 jukebox alsactl[650]: alsactl 1.1.8 daemon started Jun 26 22:24:08 jukebox systemd[1]: Started Volumio Iptables Module. Jun 26 22:24:08 jukebox systemd[1]: Started MPD Monitor Service. Jun 26 22:24:08 jukebox systemd[1]: Starting Save/Restore Sound Card State... Jun 26 22:24:08 jukebox systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jun 26 22:24:08 jukebox systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jun 26 22:24:08 jukebox systemd[1]: Starting Volumio Time Update Utility... Jun 26 22:24:08 jukebox systemd[1]: Started Load/Save RF Kill Switch Status. Jun 26 22:24:08 jukebox systemd[1]: Started triggerhappy global hotkey daemon. Jun 26 22:24:08 jukebox systemd[1]: Started RPC bind portmap service. Jun 26 22:24:08 jukebox systemd[1]: Reached target Remote File Systems (Pre). Jun 26 22:24:08 jukebox systemd[1]: Reached target Remote File Systems. Jun 26 22:24:08 jukebox systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 26 22:24:08 jukebox systemd[1]: Reached target RPC Port Mapper. Jun 26 22:24:08 jukebox systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jun 26 22:24:08 jukebox systemd[1]: Starting dhcpcd on all interfaces... Jun 26 22:24:08 jukebox mpd_monitor.sh[653]: MPD Monitor Service: Starting MPD Monitor Service Jun 26 22:24:08 jukebox dhcpcd[658]: Not running dhcpcd because /etc/network/interfaces Jun 26 22:24:08 jukebox dhcpcd[658]: defines some interfaces that will use a Jun 26 22:24:08 jukebox dhcpcd[658]: DHCP client or static address Jun 26 22:24:08 jukebox volumio-time-update[656]: volumio-time-update-util: Fetching time from Volumio... Jun 26 22:24:08 jukebox kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 26 22:24:08 jukebox sh[582]: wlan0=wlan0 Jun 26 22:24:08 jukebox systemd-logind[646]: New seat seat0. Jun 26 22:24:08 jukebox mpd_monitor.sh[653]: MPD error: Connection refused Jun 26 22:24:08 jukebox mpd_monitor.sh[653]: MPD Monitor Service: MPD Appears to be inactive, restarting Jun 26 22:24:08 jukebox mpd_monitor.sh[653]: mpd: no process found Jun 26 22:24:08 jukebox avahi-daemon[655]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 26 22:24:08 jukebox avahi-daemon[655]: Successfully dropped root privileges. Jun 26 22:24:08 jukebox avahi-daemon[655]: avahi-daemon 0.7 starting up. Jun 26 22:24:09 jukebox systemd[1]: Started Login Service. Jun 26 22:24:09 jukebox systemd[1]: hciuart.service: Succeeded. Jun 26 22:24:09 jukebox wpa_supplicant[651]: Successfully initialized wpa_supplicant Jun 26 22:24:09 jukebox systemd[1]: Started Configure Bluetooth Modems connected by UART. Jun 26 22:24:09 jukebox systemd[1]: Started Save/Restore Sound Card State. Jun 26 22:24:09 jukebox systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jun 26 22:24:09 jukebox avahi-daemon[655]: Successfully called chroot(). Jun 26 22:24:09 jukebox avahi-daemon[655]: Successfully dropped remaining capabilities. Jun 26 22:24:09 jukebox systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jun 26 22:24:09 jukebox systemd[1]: Failed to start dhcpcd on all interfaces. Jun 26 22:24:09 jukebox haveged[586]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 26 22:24:09 jukebox haveged[586]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jun 26 22:24:09 jukebox haveged[586]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99858 Jun 26 22:24:09 jukebox haveged[586]: haveged: fills: 0, generated: 0 Jun 26 22:24:09 jukebox avahi-daemon[655]: Loading service file /services/volumio.service. Jun 26 22:24:09 jukebox avahi-daemon[655]: Network interface enumeration completed. Jun 26 22:24:09 jukebox avahi-daemon[655]: Server startup complete. Host name is jukebox.local. Local service cookie is 2085264406. Jun 26 22:24:09 jukebox avahi-daemon[655]: Service "JukeBox" (/services/volumio.service) successfully established. Jun 26 22:24:09 jukebox systemd[1]: Started WPA supplicant. Jun 26 22:24:09 jukebox sh[597]: eth0: waiting for carrier Jun 26 22:24:09 jukebox kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jun 26 22:24:09 jukebox kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jun 26 22:24:09 jukebox dhcpcd[634]: eth0: waiting for carrier Jun 26 22:24:09 jukebox systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 26 22:24:09 jukebox systemd[1]: Listening on mpd.socket. Jun 26 22:24:09 jukebox systemd[1]: Reached target Network. Jun 26 22:24:09 jukebox systemd[1]: Starting Permit User Sessions... Jun 26 22:24:09 jukebox systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 26 22:24:09 jukebox systemd[1]: Starting Network Time Service... Jun 26 22:24:09 jukebox systemd[1]: Reached target Network is Online. Jun 26 22:24:09 jukebox systemd[1]: Starting LSB: Brings up/down network automatically... Jun 26 22:24:09 jukebox systemd[1]: Starting /etc/rc.local Compatibility... Jun 26 22:24:09 jukebox volumio-remote-updater[644]: Error: No active session Jun 26 22:24:09 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:09] [info] asio async_connect error: system:111 (Connection refused) Jun 26 22:24:09 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 26 22:24:09 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:09] [error] handle_connect error: Underlying Transport Error Jun 26 22:24:09 jukebox systemd[1]: Starting Samba NMB Daemon... Jun 26 22:24:09 jukebox systemd[1]: Reached target Sound Card. Jun 26 22:24:09 jukebox systemd[1]: Starting Music Player Daemon... Jun 26 22:24:09 jukebox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 22:24:09 jukebox systemd[1]: Starting Raspberry Pi bluetooth helper... Jun 26 22:24:09 jukebox systemd[1]: Started /etc/rc.local Compatibility. Jun 26 22:24:09 jukebox systemd[1]: Started Permit User Sessions. Jun 26 22:24:09 jukebox volumio-time-update[656]: volumio-time-update-util: Date not found in response Jun 26 22:24:09 jukebox volumio-time-update[656]: volumio-time-update-util: Retrying in 5 seconds... Jun 26 22:24:09 jukebox volumio[642]: Could not open config: /tmp/upmpdcli.conf Jun 26 22:24:09 jukebox systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 26 22:24:09 jukebox systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 26 22:24:09 jukebox systemd[1]: iptables.service: Succeeded. Jun 26 22:24:09 jukebox loadcpufreq[657]: Loading cpufreq kernel modules...done (none). Jun 26 22:24:09 jukebox systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 26 22:24:09 jukebox systemd[1]: Starting Hold until boot process finishes up... Jun 26 22:24:09 jukebox systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 26 22:24:09 jukebox bthelper[753]: Raspberry Pi BDADDR already set Jun 26 22:24:09 jukebox systemd[1]: Started Raspberry Pi bluetooth helper. Jun 26 22:24:09 jukebox systemd[1]: plymouth-quit.service: Succeeded. Jun 26 22:24:09 jukebox systemd[1]: Started Terminate Plymouth Boot Screen. Jun 26 22:24:09 jukebox systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 26 22:24:09 jukebox systemd[1]: Started Hold until boot process finishes up. Jun 26 22:24:09 jukebox systemd[1]: Received SIGRTMIN+21 from PID 199 (n/a). Jun 26 22:24:09 jukebox systemd[1]: Received SIGRTMIN+21 from PID 199 (n/a). Jun 26 22:24:09 jukebox systemd[1]: Started Getty on tty1. Jun 26 22:24:09 jukebox systemd[1]: Reached target Login Prompts. Jun 26 22:24:09 jukebox systemd[1]: Starting Bluetooth service... Jun 26 22:24:09 jukebox systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 26 22:24:09 jukebox ifplugd(eth0)[797]: ifplugd 0.28 initializing. Jun 26 22:24:09 jukebox ifplugd(eth0)[797]: Using interface eth0/B8:27:EB:67:A0:C2 with driver (version: 6.6.62-v7+) Jun 26 22:24:09 jukebox ifplugd(eth0)[797]: Using detection mode: SIOCETHTOOL Jun 26 22:24:09 jukebox ifplugd(eth0)[797]: Initialization complete, link beat not detected. Jun 26 22:24:09 jukebox ntpd[731]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 26 22:24:09 jukebox ntpd[731]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 26 22:24:09 jukebox ntpd[799]: proto: precision = 0.729 usec (-20) Jun 26 22:24:09 jukebox systemd[1]: Started Network Time Service. Jun 26 22:24:09 jukebox ntpd[799]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 26 22:24:09 jukebox ntpd[799]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 26 22:24:09 jukebox ntpd[799]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 181 days ago Jun 26 22:24:09 jukebox ntpd[799]: Listen and drop on 0 v6wildcard [::]:123 Jun 26 22:24:09 jukebox ntpd[799]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 26 22:24:09 jukebox ntpd[799]: Listen normally on 2 lo 127.0.0.1:123 Jun 26 22:24:09 jukebox ntpd[799]: Listening on routing socket on fd #19 for interface updates Jun 26 22:24:09 jukebox ntpd[799]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 26 22:24:09 jukebox ntpd[799]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 26 22:24:09 jukebox ifplugd[732]: Network Interface Plugging Daemon...start eth0...done. Jun 26 22:24:09 jukebox systemd[1]: Started LSB: Brings up/down network automatically. Jun 26 22:24:10 jukebox cpufrequtils[792]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 26 22:24:10 jukebox systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 26 22:24:10 jukebox bluetoothd[788]: Bluetooth daemon 5.50 Jun 26 22:24:10 jukebox bluetoothd[788]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 26 22:24:10 jukebox systemd[1]: Started Bluetooth service. Jun 26 22:24:10 jukebox systemd[1]: Reached target Bluetooth. Jun 26 22:24:10 jukebox bluetoothd[788]: Starting SDP server Jun 26 22:24:10 jukebox bluetoothd[788]: Excluding (cli) sap Jun 26 22:24:10 jukebox kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 26 22:24:10 jukebox kernel: Bluetooth: BNEP filters: protocol multicast Jun 26 22:24:10 jukebox kernel: Bluetooth: BNEP socket layer initialized Jun 26 22:24:10 jukebox bluetoothd[788]: Bluetooth management interface 1.22 initialized Jun 26 22:24:10 jukebox dbus-daemon[645]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=788 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 26 22:24:10 jukebox kernel: Bluetooth: MGMT ver 1.22 Jun 26 22:24:10 jukebox sudo[745]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 22:24:10 jukebox sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:10 jukebox sudo[745]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 26 22:24:10 jukebox sudo[745]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:10 jukebox bluetoothd[788]: Failed to set privacy: Rejected (0x0b) Jun 26 22:24:10 jukebox systemd[1]: Starting Hostname Service... Jun 26 22:24:10 jukebox ntpd[799]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 26 22:24:10 jukebox dbus-daemon[645]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 26 22:24:10 jukebox systemd[1]: Started Hostname Service. Jun 26 22:24:11 jukebox nmbd[760]: [2025/06/26 22:24:11.219969, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 26 22:24:11 jukebox nmbd[760]: started asyncdns process 822 Jun 26 22:24:11 jukebox nmbd[760]: [2025/06/26 22:24:11.222307, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 26 22:24:11 jukebox nmbd[760]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 26 22:24:11 jukebox nmbd[760]: [2025/06/26 22:24:11.222507, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 26 22:24:11 jukebox nmbd[760]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 26 22:24:11 jukebox ntpd[799]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 26 22:24:11 jukebox wireless.js[647]: Cleaning previous... Jun 26 22:24:12 jukebox sudo[830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 26 22:24:12 jukebox sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:12 jukebox sudo[830]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:12 jukebox sudo[832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 26 22:24:12 jukebox sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:12 jukebox sudo[832]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:12 jukebox wireless.js[647]: Stopped aP Jun 26 22:24:12 jukebox sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 26 22:24:12 jukebox sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:12 jukebox sudo[839]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:12 jukebox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 26 22:24:12 jukebox sudo[841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 26 22:24:12 jukebox sudo[841]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:12 jukebox sudo[841]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:12 jukebox sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 26 22:24:12 jukebox sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:12 jukebox sudo[848]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:12 jukebox sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 26 22:24:12 jukebox sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:12 jukebox ntpd[799]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 26 22:24:12 jukebox systemd[1]: systemd-rfkill.service: Succeeded. Jun 26 22:24:13 jukebox sudo[850]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:13 jukebox wireless.js[647]: SETTING APPROPRIATE REG DOMAIN: DE Jun 26 22:24:13 jukebox sudo[855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 26 22:24:13 jukebox sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:13 jukebox sudo[855]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:13 jukebox sudo[857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jun 26 22:24:13 jukebox sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:13 jukebox sudo[857]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:13 jukebox wireless.js[647]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jun 26 22:24:13 jukebox wireless.js[647]: Start wireless flow Jun 26 22:24:13 jukebox wireless.js[647]: Stopped hotspot (if there).. Jun 26 22:24:13 jukebox sudo[862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 26 22:24:13 jukebox sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:13 jukebox sudo[862]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:13 jukebox sudo[864]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 26 22:24:13 jukebox sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:13 jukebox ntpd[799]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 26 22:24:14 jukebox sudo[864]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:14 jukebox wireless.js[647]: DHCP IP Jun 26 22:24:14 jukebox wireless.js[647]: Start ap Jun 26 22:24:14 jukebox mpd[819]: Jun 26 22:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 26 22:24:14 jukebox wpa_supplicant[866]: Successfully initialized wpa_supplicant Jun 26 22:24:14 jukebox sudo[867]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 26 22:24:14 jukebox sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:14 jukebox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 26 22:24:14 jukebox dhcpcd[868]: dev: loaded udev Jun 26 22:24:14 jukebox dhcpcd-run-hooks[886]: wlan0: starting wpa_supplicant Jun 26 22:24:14 jukebox systemd[1]: Started Music Player Daemon. Jun 26 22:24:14 jukebox mpd_monitor.sh[653]: MPD Monitor Service: MPD restarted due to no mpc output. Jun 26 22:24:14 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:14] [info] asio async_connect error: system:111 (Connection refused) Jun 26 22:24:14 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 26 22:24:14 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:14] [error] handle_connect error: Underlying Transport Error Jun 26 22:24:14 jukebox dhcpcd-run-hooks[894]: wlan0: failed to start wpa_supplicant Jun 26 22:24:14 jukebox dhcpcd-run-hooks[895]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jun 26 22:24:14 jukebox dhcpcd[868]: wlan0: connected to Access Point `' Jun 26 22:24:14 jukebox dhcpcd[868]: no interfaces have a carrier Jun 26 22:24:14 jukebox dhcpcd[868]: forked to background, child pid 905 Jun 26 22:24:14 jukebox dhcpcd[905]: eth0: waiting for carrier Jun 26 22:24:14 jukebox dhcpcd[905]: wlan0: waiting for carrier Jun 26 22:24:14 jukebox volumio-time-update[656]: volumio-time-update-util: Fetching time from Volumio... Jun 26 22:24:14 jukebox sudo[867]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:14 jukebox volumio-time-update[656]: volumio-time-update-util: Date not found in response Jun 26 22:24:14 jukebox volumio-time-update[656]: volumio-time-update-util: Retrying in 5 seconds... Jun 26 22:24:14 jukebox bthelper[753]: Changing power off succeeded Jun 26 22:24:14 jukebox bthelper[753]: [CHG] Controller B8:27:EB:CD:0A:68 Class: 0x0000041c Jun 26 22:24:14 jukebox bthelper[753]: Changing power on succeeded Jun 26 22:24:14 jukebox wpa_supplicant[893]: wlan0: Trying to associate with SSID 'Hulk' Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: carrier acquired Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: connected to Access Point `Hulk' Jun 26 22:24:15 jukebox wpa_supplicant[893]: wlan0: Associated with f4:4d:5c:fb:26:3e Jun 26 22:24:15 jukebox wpa_supplicant[893]: wlan0: CTRL-EVENT-CONNECTED - Connection to f4:4d:5c:fb:26:3e completed [id=0 id_str=] Jun 26 22:24:15 jukebox wpa_supplicant[893]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 26 22:24:15 jukebox wpa_supplicant[893]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 26 22:24:15 jukebox dhcpcd[905]: DUID 00:01:00:01:2c:2b:35:7a:b8:27:eb:67:a0:c2 Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: IAID eb:32:f5:97 Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: adding address fe80::c78c:77e1:4c5a:763a Jun 26 22:24:15 jukebox dhcpcd[905]: ipv6_addaddr1: Permission denied Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: carrier lost Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: carrier acquired Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: IAID eb:32:f5:97 Jun 26 22:24:15 jukebox wireless.js[647]: trying... Jun 26 22:24:15 jukebox sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 26 22:24:15 jukebox sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:15 jukebox sudo[931]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: rebinding lease of 192.168.10.111 Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: probing address 192.168.10.111/24 Jun 26 22:24:15 jukebox dhcpcd[905]: wlan0: soliciting an IPv6 router Jun 26 22:24:16 jukebox wireless.js[647]: trying... Jun 26 22:24:16 jukebox sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 26 22:24:16 jukebox sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:16 jukebox sudo[934]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:17 jukebox wireless.js[647]: trying... Jun 26 22:24:17 jukebox sudo[937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 26 22:24:17 jukebox sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:17 jukebox sudo[937]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:18 jukebox wireless.js[647]: trying... Jun 26 22:24:18 jukebox sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 26 22:24:18 jukebox sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:18 jukebox sudo[940]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:19 jukebox wireless.js[647]: trying... Jun 26 22:24:19 jukebox sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 26 22:24:19 jukebox sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:19 jukebox sudo[943]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:19 jukebox volumio-time-update[656]: volumio-time-update-util: Fetching time from Volumio... Jun 26 22:24:19 jukebox volumio-time-update[656]: volumio-time-update-util: Date not found in response Jun 26 22:24:19 jukebox volumio-time-update[656]: volumio-time-update-util: Retrying in 5 seconds... Jun 26 22:24:20 jukebox wireless.js[647]: trying... Jun 26 22:24:20 jukebox sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 26 22:24:20 jukebox sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:20 jukebox sudo[951]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:21 jukebox wireless.js[647]: trying... Jun 26 22:24:21 jukebox dhcpcd[905]: wlan0: leased 192.168.10.111 for 604800 seconds Jun 26 22:24:21 jukebox avahi-daemon[655]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.111. Jun 26 22:24:21 jukebox sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 26 22:24:21 jukebox sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:21 jukebox dhcpcd[905]: wlan0: adding route to 192.168.10.0/24 Jun 26 22:24:21 jukebox dhcpcd[905]: wlan0: adding default route via 192.168.10.1 Jun 26 22:24:21 jukebox avahi-daemon[655]: New relevant interface wlan0.IPv4 for mDNS. Jun 26 22:24:21 jukebox avahi-daemon[655]: Registering new address record for 192.168.10.111 on wlan0.IPv4. Jun 26 22:24:21 jukebox sudo[954]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:21 jukebox wireless.js[647]: Connected to: ----Hulk Jun 26 22:24:21 jukebox wireless.js[647]: ---- Jun 26 22:24:21 jukebox sudo[961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 26 22:24:21 jukebox sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:21 jukebox sudo[961]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:21 jukebox wireless.js[647]: ... joined AP, wlan0 IPv4 is 192.168.10.111, ipV6 is undefined Jun 26 22:24:21 jukebox wireless.js[647]: It's done! AP Jun 26 22:24:21 jukebox nmbd[760]: [2025/06/26 22:24:21.231491, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 26 22:24:21 jukebox systemd[1]: Started Samba NMB Daemon. Jun 26 22:24:21 jukebox nmbd[760]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 26 22:24:21 jukebox systemd[1]: Starting Samba Winbind Daemon... Jun 26 22:24:21 jukebox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 26 22:24:21 jukebox systemd[1]: Started Wireless Services. Jun 26 22:24:21 jukebox systemd[1]: Started Volumio Backend Module. Jun 26 22:24:21 jukebox systemd[1]: Started Volumio Cpu Tweaker. Jun 26 22:24:21 jukebox volumio-cpu-tweak[985]: Setting RT Priority for mpd Jun 26 22:24:21 jukebox volumio-cpu-tweak[985]: Setting MPD Affinity Jun 26 22:24:21 jukebox volumio-cpu-tweak[985]: pid 819's current affinity mask: f Jun 26 22:24:21 jukebox volumio-cpu-tweak[985]: pid 819's new affinity mask: 3 Jun 26 22:24:21 jukebox volumio-cpu-tweak[985]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 26 22:24:21 jukebox volumio-cpu-tweak[985]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 26 22:24:21 jukebox systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 26 22:24:21 jukebox winbindd[982]: [2025/06/26 22:24:21.660392, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 26 22:24:21 jukebox winbindd[982]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 26 22:24:21 jukebox winbindd[982]: [2025/06/26 22:24:21.693064, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 26 22:24:21 jukebox systemd[1]: Started Samba Winbind Daemon. Jun 26 22:24:21 jukebox winbindd[982]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 26 22:24:21 jukebox systemd[1]: Starting Samba SMB Daemon... Jun 26 22:24:21 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:21] [info] asio async_connect error: system:111 (Connection refused) Jun 26 22:24:21 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 26 22:24:21 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:21] [error] handle_connect error: Underlying Transport Error Jun 26 22:24:22 jukebox winbindd[982]: [2025/06/26 22:24:22.264651, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jun 26 22:24:22 jukebox winbindd[982]: res_names->count = 3, expected 4 Jun 26 22:24:22 jukebox smbd[1004]: [2025/06/26 22:24:22.340777, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 26 22:24:22 jukebox systemd[1]: Started Samba SMB Daemon. Jun 26 22:24:22 jukebox smbd[1004]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 26 22:24:22 jukebox ntpd[799]: Listen normally on 3 wlan0 192.168.10.111:123 Jun 26 22:24:22 jukebox ntpd[799]: new interface(s) found: waking up resolver Jun 26 22:24:24 jukebox volumio[984]: info: ------------------------------------------- Jun 26 22:24:24 jukebox volumio[984]: info: ----- Volumio3 ---- Jun 26 22:24:24 jukebox volumio[984]: info: ------------------------------------------- Jun 26 22:24:24 jukebox volumio[984]: info: ----- System startup ---- Jun 26 22:24:24 jukebox volumio[984]: info: ------------------------------------------- Jun 26 22:24:24 jukebox volumio-time-update[656]: volumio-time-update-util: Fetching time from Volumio... Jun 26 22:24:24 jukebox volumio-time-update[656]: volumio-time-update-util: Setting system time to: 2025-06-26 22:24:45 Jun 26 22:24:24 jukebox sudo[1025]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-26 22:24:45 Jun 26 22:24:24 jukebox sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:24 jukebox dbus-daemon[645]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1026 comm="timedatectl set-time 2025-06-26 22:24:45 ") Jun 26 22:24:24 jukebox systemd[1]: Starting Time & Date Service... Jun 26 22:24:25 jukebox dbus-daemon[645]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 26 22:24:25 jukebox systemd[1]: Started Time & Date Service. Jun 26 22:24:45 jukebox systemd-timedated[1027]: Changed local time to Thu Jun 26 22:24:45 2025 Jun 26 22:24:45 jukebox sudo[1025]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:45 jukebox volumio-time-update[656]: volumio-time-update-util: System time updated successfully. Jun 26 22:24:45 jukebox systemd[1]: Started Volumio Time Update Utility. Jun 26 22:24:45 jukebox systemd[1]: Reached target Multi-User System. Jun 26 22:24:45 jukebox systemd[1]: Reached target Graphical Interface. Jun 26 22:24:45 jukebox systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 26 22:24:45 jukebox systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 26 22:24:45 jukebox systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 26 22:24:45 jukebox systemd[1]: Startup finished in 13.592s (kernel) + 21.585s (userspace) = 35.177s. Jun 26 22:24:45 jukebox volumio[984]: info: MYVOLUMIO Environment detected Jun 26 22:24:45 jukebox volumio[984]: info: Plugin folders cleanup Jun 26 22:24:45 jukebox volumio[984]: info: Scanning into folder /volumio/app/plugins/ Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category audio_interface Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category miscellanea Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category music_service Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category plugins.json Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category system_controller Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category user_interface Jun 26 22:24:45 jukebox volumio[984]: info: Scanning into folder /data/plugins/ Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category audio_interface Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category music_service Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category system_hardware Jun 26 22:24:45 jukebox volumio[984]: info: Scanning category user_interface Jun 26 22:24:45 jukebox volumio[984]: info: Plugin folders cleanup completed Jun 26 22:24:45 jukebox volumio[984]: info: ------------------------------------------- Jun 26 22:24:45 jukebox volumio[984]: info: ----- Core plugins startup ---- Jun 26 22:24:45 jukebox volumio[984]: info: ------------------------------------------- Jun 26 22:24:45 jukebox volumio[984]: info: Loading plugins from folder /volumio/app/plugins/ Jun 26 22:24:45 jukebox volumio[984]: info: Adding plugin upnp to MyMusic Plugins Jun 26 22:24:45 jukebox volumio[984]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 26 22:24:45 jukebox volumio[984]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 26 22:24:45 jukebox volumio[984]: info: Loading plugins from folder /data/plugins/ Jun 26 22:24:45 jukebox volumio[984]: info: Loading plugin "system"... Jun 26 22:24:45 jukebox volumio[984]: info: Loading plugin "appearance"... Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "network"... Jun 26 22:24:47 jukebox volumio[984]: info: Refreshing Cached IP Addresses Jun 26 22:24:47 jukebox sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 26 22:24:47 jukebox sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:47 jukebox sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 26 22:24:47 jukebox sudo[1035]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:47 jukebox sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "services"... Jun 26 22:24:47 jukebox sudo[1037]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "alsa_controller"... Jun 26 22:24:47 jukebox sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 26 22:24:47 jukebox sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:47 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "wizard"... Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "networkfs"... Jun 26 22:24:47 jukebox volumio[984]: info: Starting Udev Watcher for removable devices Jun 26 22:24:47 jukebox sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=vossi,password=Mike150981!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.20.100/Media/Musik /mnt/NAS/Homeserver Jun 26 22:24:47 jukebox sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:24:47 jukebox volumio[984]: info: Ignoring mount for partition: boot Jun 26 22:24:47 jukebox volumio[984]: info: Ignoring mount for partition: volumio Jun 26 22:24:47 jukebox volumio[984]: info: Ignoring mount for partition: volumio_data Jun 26 22:24:47 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "volumio_command_line_client"... Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "upnp"... Jun 26 22:24:47 jukebox volumio[984]: info: [1750969487565] Starting Upmpd Daemon Jun 26 22:24:47 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "my_music"... Jun 26 22:24:47 jukebox volumio[984]: info: Loading plugin "mpd"... Jun 26 22:24:47 jukebox kernel: Key type cifs.spnego registered Jun 26 22:24:47 jukebox kernel: Key type cifs.idmap registered Jun 26 22:24:47 jukebox kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jun 26 22:24:47 jukebox kernel: CIFS: Attempting to mount //192.168.20.100/Media/Musik Jun 26 22:24:47 jukebox kernel: cryptd: max_cpu_qlen set to 1000 Jun 26 22:24:48 jukebox sudo[1046]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:48 jukebox sudo[1063]: pam_unix(sudo:session): session closed for user root Jun 26 22:24:49 jukebox volumio[984]: info: Loading plugin "upnp_browser"... Jun 26 22:24:49 jukebox volumio[984]: info: Loading plugin "alarm-clock"... Jun 26 22:24:50 jukebox volumio[984]: info: Loading plugin "airplay_emulation"... Jun 26 22:24:50 jukebox volumio[984]: info: Starting Shairport Sync Jun 26 22:24:50 jukebox volumio[984]: info: Loading plugin "last_100"... Jun 26 22:24:50 jukebox volumio[984]: info: Loading plugin "webradio"... Jun 26 22:24:50 jukebox volumio[984]: info: Loading plugin "i2s_dacs"... Jun 26 22:24:50 jukebox volumio[984]: info: Loading plugin "volumiodiscovery"... Jun 26 22:24:50 jukebox volumio[984]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 26 22:24:50 jukebox volumio[984]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 22:24:50 jukebox volumio[984]: *** WARNING *** For more information see Jun 26 22:24:50 jukebox volumio[984]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 26 22:24:50 jukebox node[984]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 26 22:24:50 jukebox volumio[984]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 22:24:50 jukebox volumio[984]: *** WARNING *** For more information see Jun 26 22:24:50 jukebox node[984]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 22:24:50 jukebox node[984]: *** WARNING *** For more information see Jun 26 22:24:50 jukebox node[984]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 26 22:24:50 jukebox node[984]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 22:24:50 jukebox node[984]: *** WARNING *** For more information see Jun 26 22:24:50 jukebox volumio[984]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 26 22:24:50 jukebox volumio[984]: info: Discovery: Started advertising with name: JukeBox Jun 26 22:24:50 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 26 22:24:50 jukebox volumio[984]: info: Loading plugin "spop"... Jun 26 22:24:51 jukebox volumio-remote-updater[644]: [2025-06-26 22:24:51] [connect] Successful connection Jun 26 22:24:52 jukebox volumio[984]: info: Loading plugin "youtube2"... Jun 26 22:24:56 jukebox systemd[1]: systemd-fsckd.service: Succeeded. Jun 26 22:24:56 jukebox volumio[984]: info: Loading plugin "ytmusic"... Jun 26 22:24:59 jukebox sh[597]: timed out Jun 26 22:24:59 jukebox dhcpcd[634]: timed out Jun 26 22:24:59 jukebox sh[597]: dhcpcd exited Jun 26 22:24:59 jukebox dhcpcd[634]: dhcpcd exited Jun 26 22:24:59 jukebox sh[597]: ifup: failed to bring up eth0 Jun 26 22:24:59 jukebox systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 26 22:24:59 jukebox systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 26 22:25:00 jukebox volumio[984]: info: Loading plugin "outputs"... Jun 26 22:25:00 jukebox volumio[984]: info: Loading plugin "albumart"... Jun 26 22:25:00 jukebox volumio[984]: info: Plugin example_plugin is not enabled Jun 26 22:25:00 jukebox volumio[984]: info: Loading plugin "inputs"... Jun 26 22:25:00 jukebox volumio[984]: info: Loading plugin "updater_comm"... Jun 26 22:25:00 jukebox systemd[1]: systemd-hostnamed.service: Succeeded. Jun 26 22:25:00 jukebox volumio[984]: info: Plugin mpdemulation is not enabled Jun 26 22:25:00 jukebox volumio[984]: info: Loading plugin "rest_api"... Jun 26 22:25:01 jukebox volumio[984]: info: Loading plugin "websocket"... Jun 26 22:25:01 jukebox volumio[984]: info: Starting Socket.io Server version 2.3.0 Jun 26 22:25:01 jukebox volumio[984]: info: Loading plugin "fusiondsp"... Jun 26 22:25:01 jukebox volumio[984]: Forking 3 albumart workers Jun 26 22:25:01 jukebox volumio[984]: info: Applying required configuration parameters for plugin fusiondsp Jun 26 22:25:01 jukebox volumio[984]: info: Loading plugin "80s80s"... Jun 26 22:25:02 jukebox volumio[984]: Starting albumart workers Jun 26 22:25:02 jukebox volumio[984]: Starting albumart workers Jun 26 22:25:02 jukebox volumio[984]: Starting albumart workers Jun 26 22:25:02 jukebox volumio[984]: info: Applying required configuration parameters for plugin 80s80s Jun 26 22:25:02 jukebox volumio[984]: info: [1750969502252] [80s80s] API delay: 15 Jun 26 22:25:02 jukebox volumio[984]: info: Loading plugin "rotaryencoder2"... Jun 26 22:25:03 jukebox volumio[984]: info: Loading plugin "Systeminfo"... Jun 26 22:25:03 jukebox volumio[984]: info: Loading i18n strings for locale de Jun 26 22:25:03 jukebox volumio[984]: Updating browse sources language Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::initPlayerControls Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:03 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 22:25:04 jukebox volumio[984]: Express server listening on port 3000 Jun 26 22:25:04 jukebox volumio[984]: [Metrics] WebUI: 20s 883.15ms Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::resetVolumioState Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::getcurrentVolume Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 22:25:04 jukebox sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 26 22:25:04 jukebox sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:04 jukebox sudo[1181]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:04 jukebox sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 26 22:25:04 jukebox sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:04 jukebox sudo[1184]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:04 jukebox volumio[984]: info: Volumio Network Manager: Network status updated: 2 Jun 26 22:25:04 jukebox volumio[984]: verbose: New Socket.io Connection to 192.168.10.111 from 192.168.11.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 26 22:25:04 jukebox volumio[984]: verbose: New Socket.io Connection to 192.168.10.111 from 192.168.11.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 26 22:25:04 jukebox volumio[984]: verbose: New Socket.io Connection to 192.168.10.111 from 192.168.11.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 26 22:25:04 jukebox volumio[984]: verbose: New Socket.io Connection to 192.168.10.111 from 192.168.11.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 26 22:25:04 jukebox volumio[984]: info: VolumeController:: Volume=30 Mute =false Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::pushState Jun 26 22:25:04 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioPushState Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::updateTrackBlock Jun 26 22:25:04 jukebox volumio[984]: info: CorePlayQueue::getTrackBlock Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:04 jukebox volumio[984]: info: Reloading queue from file Jun 26 22:25:04 jukebox volumio[984]: info: Setting Device type: Raspberry PI Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::setRepeat false single undefined Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::pushState Jun 26 22:25:04 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioPushState Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::setRandom null Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::pushState Jun 26 22:25:04 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioPushState Jun 26 22:25:04 jukebox volumio[984]: info: Completed loading Core Plugins Jun 26 22:25:04 jukebox volumio[984]: info: Preparing to generate the ALSA configuration file Jun 26 22:25:04 jukebox volumio[984]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 26 22:25:04 jukebox volumio[984]: info: Reading ALSA contributions from plugins. Jun 26 22:25:04 jukebox volumio[984]: info: VolumeController:: Volume=30 Mute =false Jun 26 22:25:04 jukebox volumio[984]: info: CoreStateMachine::pushState Jun 26 22:25:04 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioPushState Jun 26 22:25:04 jukebox volumio[984]: info: Asound.conf file unchanged, so no further update is needed Jun 26 22:25:04 jukebox volumio[984]: info: Output device has changed, restarting MPD Jun 26 22:25:04 jukebox sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 26 22:25:04 jukebox sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:04 jukebox sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 22:25:04 jukebox sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:04 jukebox volumio[984]: info: Output device has changed, restarting Shairport Sync Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:04 jukebox systemd[1]: Started UPnP Renderer front-end to MPD. Jun 26 22:25:04 jukebox sudo[1198]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:04 jukebox sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 22:25:04 jukebox sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:04 jukebox volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 22:25:04 jukebox volumio[984]: info: ___________ START PLUGINS ___________ Jun 26 22:25:04 jukebox sudo[1195]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:04 jukebox systemd[1]: Stopping Music Player Daemon... Jun 26 22:25:04 jukebox volumio[984]: info: ControllerMpd::onStart: Initializing MPD Jun 26 22:25:04 jukebox volumio[984]: info: Creating MPD Configuration file Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 22:25:04 jukebox sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 22:25:04 jukebox sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 22:25:04 jukebox volumio[984]: info: [1750969504775] CoreMusicLibrary::Adding element Medienserver Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 22:25:04 jukebox sudo[1208]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:04 jukebox sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 22:25:04 jukebox sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:04 jukebox volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 22:25:04 jukebox systemd[1]: mpd.service: Succeeded. Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:04 jukebox systemd[1]: Stopped Music Player Daemon. Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:04 jukebox volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 22:25:04 jukebox volumio[984]: info: [1750969504944] CoreMusicLibrary::Adding element Last_100 Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 22:25:04 jukebox volumio[984]: info: [1750969504947] CoreMusicLibrary::Adding element Webradio Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 22:25:04 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 22:25:04 jukebox volumio[984]: info: Initializing BBC Radios Jun 26 22:25:05 jukebox systemd[1]: Starting Music Player Daemon... Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: Creating Spotify config file Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 22:25:05 jukebox volumio[984]: info: [1750969505068] CoreMusicLibrary::Adding element YouTube2 Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 22:25:05 jukebox volumio[984]: Cannot find translation for source YouTube2 Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 22:25:05 jukebox volumio[984]: info: [1750969505079] CoreMusicLibrary::Adding element YouTube Music Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 22:25:05 jukebox volumio[984]: Cannot find translation for source YouTube2 Jun 26 22:25:05 jukebox volumio[984]: Cannot find translation for source YouTube Music Jun 26 22:25:05 jukebox volumio[984]: info: Loading i18n strings for locale de Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: FusionDsp - mixtype--------------------- Hardware Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 22:25:05 jukebox volumio[984]: info: [1750969505110] CoreMusicLibrary::Adding element 80s80s Radio Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 22:25:05 jukebox volumio[984]: Cannot find translation for source YouTube2 Jun 26 22:25:05 jukebox volumio[984]: Cannot find translation for source YouTube Music Jun 26 22:25:05 jukebox volumio[984]: Cannot find translation for source 80s80s Radio Jun 26 22:25:05 jukebox sudo[1216]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 22:25:05 jukebox sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:05 jukebox sudo[1216]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:05 jukebox volumio[984]: info: Volumio Calling Home Jun 26 22:25:05 jukebox volumio[984]: info: Preparing to generate the ALSA configuration file Jun 26 22:25:05 jukebox sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=1 Jun 26 22:25:05 jukebox sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:05 jukebox kernel: rotary-encoder rotary@5: gray Jun 26 22:25:05 jukebox kernel: input: rotary@5 as /devices/platform/rotary@5/input/input0 Jun 26 22:25:05 jukebox sudo[1236]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:05 jukebox volumio[984]: info: Discovery: adding 5bf30886-b075-4873-b222-62ae79c792f2 Jun 26 22:25:05 jukebox volumio[984]: info: Discovery: Found device JukeBox Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::volumioGetState Jun 26 22:25:05 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:05 jukebox volumio[984]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 26 22:25:05 jukebox volumio[984]: info: Reading ALSA contributions from plugins. Jun 26 22:25:05 jukebox volumio[984]: info: MPD Permissions set Jun 26 22:25:05 jukebox volumio[984]: info: MPD Permissions set Jun 26 22:25:05 jukebox volumio[984]: info: Upmpdcli Daemon Started Jun 26 22:25:05 jukebox volumio[984]: info: Spotify config file written Jun 26 22:25:05 jukebox sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 26 22:25:05 jukebox sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:05 jukebox volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 26 22:25:05 jukebox volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 26 22:25:05 jukebox volumio[984]: info: No need to fix Spotify hosts Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox systemd[1]: Started go-librespot Daemon. Jun 26 22:25:05 jukebox go-librespot[1255]: go-librespot daemon starting... Jun 26 22:25:05 jukebox sudo[1251]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:05 jukebox systemd-udevd[1243]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 26 22:25:05 jukebox volumio[984]: info: Starting Shairport Sync Jun 26 22:25:05 jukebox volumio[984]: info: Starting Shairport Sync Jun 26 22:25:05 jukebox sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 22:25:05 jukebox volumio[984]: info: Starting Shairport Sync Jun 26 22:25:05 jukebox sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:05 jukebox sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 22:25:05 jukebox sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:05 jukebox systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 22:25:05 jukebox systemd[1]: shairport-sync.service: Succeeded. Jun 26 22:25:05 jukebox systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 22:25:05 jukebox sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 22:25:05 jukebox sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::volumioGetState Jun 26 22:25:05 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:05 jukebox volumio[984]: info: CoreCommandRouter::volumioGetState Jun 26 22:25:05 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:06 jukebox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 22:25:06 jukebox sudo[1270]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:06 jukebox volumio[984]: info: Asound.conf file unchanged, so no further update is needed Jun 26 22:25:06 jukebox volumio[984]: info: Output device has changed, restarting MPD Jun 26 22:25:06 jukebox systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 22:25:06 jukebox systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 26 22:25:06 jukebox systemd[1]: shairport-sync.service: Succeeded. Jun 26 22:25:06 jukebox systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 22:25:06 jukebox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 22:25:06 jukebox volumio[984]: info: Output device has changed, restarting Shairport Sync Jun 26 22:25:06 jukebox sudo[1272]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:06 jukebox sudo[1275]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:06 jukebox sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:06 jukebox sudo[1280]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:06 jukebox sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 22:25:06 jukebox sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:06 jukebox systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 26 22:25:06 jukebox systemd[1]: mpd.service: Succeeded. Jun 26 22:25:06 jukebox systemd[1]: Stopped Music Player Daemon. Jun 26 22:25:06 jukebox volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 22:25:06 jukebox systemd[1]: Starting Music Player Daemon... Jun 26 22:25:06 jukebox volumio[984]: info: Shairport-Sync Started Jun 26 22:25:06 jukebox volumio[984]: Error adding Membership: Error: addMembership EINVAL Jun 26 22:25:06 jukebox go-librespot[1255]: time="2025-06-26T22:25:06+02:00" level=info msg="running go-librespot 0.2.0" Jun 26 22:25:06 jukebox go-librespot[1255]: time="2025-06-26T22:25:06+02:00" level=debug msg="app state loaded" Jun 26 22:25:06 jukebox go-librespot[1255]: time="2025-06-26T22:25:06+02:00" level=debug msg="stored credentials not found" Jun 26 22:25:06 jukebox go-librespot[1255]: time="2025-06-26T22:25:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 26 22:25:06 jukebox volumio[984]: info: MPD Permissions set Jun 26 22:25:06 jukebox volumio[984]: info: Shairport-Sync Started Jun 26 22:25:06 jukebox volumio[984]: info: Shairport-Sync Started Jun 26 22:25:06 jukebox volumio[984]: verbose: New Socket.io Connection to 192.168.10.111 from 192.168.11.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::volumioGetState Jun 26 22:25:06 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::volumioGetState Jun 26 22:25:06 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:06 jukebox sudo[1291]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 26 22:25:06 jukebox sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 26 22:25:06 jukebox volumio[984]: info: Received Get System Info Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 22:25:06 jukebox sudo[1291]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:06 jukebox volumio[984]: info: Discovery: Getting this device information Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::volumioGetState Jun 26 22:25:06 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 22:25:06 jukebox volumio[984]: info: CoreCommandRouter::volumioGetState Jun 26 22:25:06 jukebox volumio[984]: info: CorePlayQueue::getTrack 0 Jun 26 22:25:06 jukebox volumio[984]: info: Listing playlists Jun 26 22:25:06 jukebox sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 Jun 26 22:25:06 jukebox sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:06 jukebox volumio[984]: info: Starting Shairport Sync Jun 26 22:25:06 jukebox sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 22:25:06 jukebox sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:06 jukebox systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 22:25:06 jukebox systemd[1]: shairport-sync.service: Succeeded. Jun 26 22:25:06 jukebox systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 22:25:06 jukebox kernel: input: button@d as /devices/platform/button@d/input/input1 Jun 26 22:25:06 jukebox sudo[1300]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:06 jukebox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 22:25:06 jukebox sudo[1306]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:06 jukebox volumio-remote-updater[644]: [2025-06-26 22:25:06] [connect] Successful connection Jun 26 22:25:06 jukebox go-librespot[1255]: time="2025-06-26T22:25:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 26 22:25:06 jukebox go-librespot[1255]: time="2025-06-26T22:25:06+02: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]" Jun 26 22:25:06 jukebox go-librespot[1255]: time="2025-06-26T22:25:06+02: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]" Jun 26 22:25:06 jukebox go-librespot[1255]: time="2025-06-26T22:25:06+02:00" level=info msg="zeroconf server listening on port 44903" Jun 26 22:25:06 jukebox volumio[984]: info: Shairport-Sync Started Jun 26 22:25:07 jukebox volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 22:25:07 jukebox volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 22:25:07 jukebox volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 22:25:07 jukebox volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 22:25:07 jukebox systemd-udevd[1243]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 26 22:25:07 jukebox volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 22:25:07 jukebox volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 22:25:07 jukebox volumio-remote-updater[644]: [2025-06-26 22:25:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750969506 101 Jun 26 22:25:07 jukebox volumio[984]: 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: 8 Jun 26 22:25:07 jukebox volumio[984]: info: FusionDsp - Jun 26 22:25:07 jukebox volumio[984]: info: FusionDsp - Jun 26 22:25:07 jukebox volumio[984]: info: camilladsp service started and running in background, instance 1 Jun 26 22:25:07 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 22:25:07 jukebox volumio[984]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 26 22:25:07 jukebox volumio[984]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 26 22:25:07 jukebox volumio[984]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 26 22:25:07 jukebox volumio[984]: info: FusionDsp loaded Jun 26 22:25:07 jukebox volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 22:25:07 jukebox sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 26 22:25:07 jukebox sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 22:25:07 jukebox volumio[984]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 26 22:25:07 jukebox volumio[984]: info: Adding Signal Path Element [object Object] Jun 26 22:25:07 jukebox volumio[984]: info: Adding fusiondspeq DSP Signal Path Element Jun 26 22:25:07 jukebox volumio[984]: info: FusionDsp - ---- installed callbackRead Jun 26 22:25:07 jukebox volumio[984]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 22:25:07 jukebox volumio[984]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 26 22:25:07 jukebox volumio[984]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jun 26 22:25:07 jukebox volumio[984]: at onErrorNT (internal/child_process.js:465:16) Jun 26 22:25:07 jukebox volumio[984]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 26 22:25:07 jukebox volumio[984]: at runNextTicks (internal/process/task_queues.js:62:3) Jun 26 22:25:07 jukebox volumio[984]: at listOnTimeout (internal/timers.js:523:9) Jun 26 22:25:07 jukebox volumio[984]: at processTimers (internal/timers.js:497:7) { Jun 26 22:25:07 jukebox volumio[984]: errno: -2, Jun 26 22:25:07 jukebox volumio[984]: code: 'ENOENT', Jun 26 22:25:07 jukebox volumio[984]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 26 22:25:07 jukebox volumio[984]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 26 22:25:07 jukebox volumio[984]: spawnargs: [ Jun 26 22:25:07 jukebox volumio[984]: '-p', Jun 26 22:25:07 jukebox volumio[984]: 9876, Jun 26 22:25:07 jukebox volumio[984]: '-o', Jun 26 22:25:07 jukebox volumio[984]: '/tmp/camilladsp.log', Jun 26 22:25:07 jukebox volumio[984]: '-l', Jun 26 22:25:07 jukebox volumio[984]: 'warn', Jun 26 22:25:07 jukebox volumio[984]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 26 22:25:07 jukebox volumio[984]: ] Jun 26 22:25:07 jukebox volumio[984]: } Jun 26 22:25:07 jukebox volumio[984]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 22:25:07 jukebox systemd[1]: Started FusionDsp Daemon. Jun 26 22:25:07 jukebox sudo[1342]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:08 jukebox volumio[1344]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jun 26 22:25:08 jukebox systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jun 26 22:25:08 jukebox systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jun 26 22:25:08 jukebox mpd[1298]: Jun 26 22:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 26 22:25:08 jukebox systemd[1]: Started Music Player Daemon. Jun 26 22:25:08 jukebox sudo[1210]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:08 jukebox sudo[1201]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:08 jukebox sudo[1283]: pam_unix(sudo:session): session closed for user root Jun 26 22:25:08 jukebox sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 22:24 Jun 26 22:25:08 jukebox sudo[1356]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"