-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-05-05 07:04:15 CEST. -- May 05 07:03:17 plus-koelhuis-radio fake-hwclock[309]: Mon May 5 05:03:17 UTC 2025 May 05 07:03:17 plus-koelhuis-radio systemd[1]: Starting Apply Kernel Variables... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounting Kernel Configuration File System... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Restore / save the current clock. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounted FUSE Control File System. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounted Kernel Configuration File System. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Load/Save Random Seed. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started udev Coldplug all Devices. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Create System Users. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Starting Create Static Device Nodes in /dev... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Apply Kernel Variables. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Helper to synchronize boot up for ifupdown. May 05 07:03:17 plus-koelhuis-radio systemd[1]: dynamicswap.service: Succeeded. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Create Static Device Nodes in /dev. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Starting udev Kernel Device Manager... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Reached target Local File Systems (Pre). May 05 07:03:17 plus-koelhuis-radio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounting /tmp... May 05 07:03:17 plus-koelhuis-radio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounting /var/log... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounting /var/spool/cups... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounted /tmp. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounted /var/log. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounted /var/spool/cups. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounting /var/spool/cups/tmp... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Starting Flush Journal to Persistent Storage... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Mounted /var/spool/cups/tmp. May 05 07:03:17 plus-koelhuis-radio systemd-udevd[333]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 05 07:03:17 plus-koelhuis-radio systemd-journald[308]: Runtime journal (/run/log/journal/efe5434eb7b099644ef29e3e67c0412b) is 7.5M, max 30.0M, 22.5M free. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started udev Kernel Device Manager. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Starting Show Plymouth Boot Screen... May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Flush Journal to Persistent Storage. May 05 07:03:17 plus-koelhuis-radio systemd[1]: plymouth-start.service: Succeeded. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Show Plymouth Boot Screen. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Reached target Local Encrypted Volumes. May 05 07:03:17 plus-koelhuis-radio systemd[1]: Reached target Paths. May 05 07:03:17 plus-koelhuis-radio systemd-udevd[340]: Using default interface naming scheme 'v240'. May 05 07:03:17 plus-koelhuis-radio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 05 07:03:17 plus-koelhuis-radio kernel: [vc_sm_connected_init]: start May 05 07:03:17 plus-koelhuis-radio kernel: [vc_sm_connected_init]: installed successfully May 05 07:03:17 plus-koelhuis-radio systemd-udevd[340]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 07:03:17 plus-koelhuis-radio kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 May 05 07:03:17 plus-koelhuis-radio kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem May 05 07:03:17 plus-koelhuis-radio kernel: mc: Linux media interface: v0.10 May 05 07:03:17 plus-koelhuis-radio kernel: videodev: Linux video capture interface: v2.00 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 05 07:03:17 plus-koelhuis-radio kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 05 07:03:17 plus-koelhuis-radio kernel: rpivid feb10000.codec: Device registered as /dev/video19 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image May 05 07:03:17 plus-koelhuis-radio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 05 07:03:17 plus-koelhuis-radio kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 05 07:03:17 plus-koelhuis-radio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 05 07:03:17 plus-koelhuis-radio systemd[1]: Found device /dev/disk/by-uuid/1BFB-AD60. May 05 07:03:17 plus-koelhuis-radio systemd-udevd[352]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 07:03:17 plus-koelhuis-radio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 05 07:03:17 plus-koelhuis-radio kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' May 05 07:03:17 plus-koelhuis-radio kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' May 05 07:03:17 plus-koelhuis-radio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 05 07:03:17 plus-koelhuis-radio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: Core ver 2.22 May 05 07:03:18 plus-koelhuis-radio kernel: NET: Registered PF_BLUETOOTH protocol family May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: HCI device and connection manager initialized May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: HCI socket layer initialized May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: L2CAP socket layer initialized May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: SCO socket layer initialized May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: HCI UART driver ver 2.3 May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: HCI UART protocol H4 registered May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: HCI UART protocol Broadcom registered May 05 07:03:18 plus-koelhuis-radio kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator May 05 07:03:18 plus-koelhuis-radio kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator May 05 07:03:18 plus-koelhuis-radio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 May 05 07:03:18 plus-koelhuis-radio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 May 05 07:03:18 plus-koelhuis-radio kernel: usbcore: registered new interface driver brcmfmac May 05 07:03:18 plus-koelhuis-radio kernel: uart-pl011 fe201000.serial: no DMA platform data May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. May 05 07:03:18 plus-koelhuis-radio kernel: alsactl[452]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 05 07:03:18 plus-koelhuis-radio systemd[1]: Created slice system-bthelper.slice. May 05 07:03:18 plus-koelhuis-radio kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) May 05 07:03:18 plus-koelhuis-radio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: hci0: BCM: chip id 107 May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: hci0: BCM: features 0x2f May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: hci0: BCM4345C0 May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 May 05 07:03:18 plus-koelhuis-radio kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Show Plymouth Boot Screen... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting File System Check on /dev/disk/by-uuid/1BFB-AD60... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Load/Save RF Kill Switch Status... May 05 07:03:18 plus-koelhuis-radio systemd[1]: plymouth-start.service: Succeeded. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Show Plymouth Boot Screen. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started File System Check Daemon to report status. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Load/Save RF Kill Switch Status. May 05 07:03:18 plus-koelhuis-radio systemd-udevd[353]: Using default interface naming scheme 'v240'. May 05 07:03:18 plus-koelhuis-radio systemd-udevd[353]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. May 05 07:03:18 plus-koelhuis-radio systemd-fsck[462]: fsck.fat 4.1 (2017-01-24) May 05 07:03:18 plus-koelhuis-radio systemd-fsck[462]: /dev/mmcblk0p1: 489 files, 167641/184582 clusters May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started File System Check on /dev/disk/by-uuid/1BFB-AD60. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Mounting /boot... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Mounted /boot. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Reached target Local File Systems. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Create Volatile Files and Directories... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started ifup for eth0. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started ifup for wlan0. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Preprocess NFS configuration... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Raise network interfaces... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Received SIGRTMIN+20 from PID 197 (plymouthd). May 05 07:03:18 plus-koelhuis-radio systemd[1]: plymouth-read-write.service: Succeeded. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 05 07:03:18 plus-koelhuis-radio systemd[1]: nfs-config.service: Succeeded. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Preprocess NFS configuration. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Reached target NFS client services. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Create Volatile Files and Directories. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 05 07:03:18 plus-koelhuis-radio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting RPC bind portmap service... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Update UTMP about System Boot/Shutdown. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Reached target System Initialization. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Daily Cleanup of Temporary Directories. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Listening on D-Bus System Message Bus Socket. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Listening on triggerhappy.socket. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Reached target Sockets. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Reached target Basic System. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Volumio Log Rotation Service. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started volumio-remote-updater.service. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Wireless Services... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started D-Bus System Message Bus. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Started Manage Sound Card State (restore and store). May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Configure Bluetooth Modems connected by UART... May 05 07:03:18 plus-koelhuis-radio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 05 07:03:18 plus-koelhuis-radio systemd[1]: Starting Save/Restore Sound Card State... May 05 07:03:18 plus-koelhuis-radio kernel: 8021q: 802.1Q VLAN Support v1.8 May 05 07:03:18 plus-koelhuis-radio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 07:03:18 plus-koelhuis-radio alsactl[553]: alsactl 1.1.8 daemon started May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting Login Service... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting Volumio Time Update Utility... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Daily apt download activities. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Daily apt upgrade and clean activities. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Reached target Timers. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting triggerhappy global hotkey daemon... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Volumio Iptables Module. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting dhcpcd on all interfaces... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started MPD Monitor Service. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started UPnP Renderer front-end to MPD. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting WPA supplicant... May 05 07:03:19 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: Fetching time from Volumio... May 05 07:03:19 plus-koelhuis-radio mpd_monitor.sh[572]: MPD Monitor Service: Starting MPD Monitor Service May 05 07:03:19 plus-koelhuis-radio kernel: Bluetooth: hci0: BCM: features 0x2f May 05 07:03:19 plus-koelhuis-radio kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ May 05 07:03:19 plus-koelhuis-radio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 May 05 07:03:19 plus-koelhuis-radio systemd-logind[562]: New seat seat0. May 05 07:03:19 plus-koelhuis-radio thd[566]: Unable to parse trigger line: May 05 07:03:19 plus-koelhuis-radio thd[566]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 05 07:03:19 plus-koelhuis-radio thd[566]: Unable to parse trigger line: May 05 07:03:19 plus-koelhuis-radio thd[566]: Found socket passed from systemd May 05 07:03:19 plus-koelhuis-radio dhcpcd[569]: Not running dhcpcd because /etc/network/interfaces May 05 07:03:19 plus-koelhuis-radio dhcpcd[569]: defines some interfaces that will use a May 05 07:03:19 plus-koelhuis-radio dhcpcd[569]: DHCP client or static address May 05 07:03:19 plus-koelhuis-radio mpd_monitor.sh[572]: MPD error: Connection refused May 05 07:03:19 plus-koelhuis-radio mpd_monitor.sh[572]: MPD Monitor Service: MPD Appears to be inactive, restarting May 05 07:03:19 plus-koelhuis-radio mpd_monitor.sh[572]: mpd: no process found May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: Successfully dropped root privileges. May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: avahi-daemon 0.7 starting up. May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: Successfully called chroot(). May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: Successfully dropped remaining capabilities. May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: Loading service file /services/volumio.service. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started WPA supplicant. May 05 07:03:19 plus-koelhuis-radio dbus-daemon[551]: [system] Successfully activated service 'org.freedesktop.systemd1' May 05 07:03:19 plus-koelhuis-radio wpa_supplicant[574]: Successfully initialized wpa_supplicant May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: Network interface enumeration completed. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Login Service. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started triggerhappy global hotkey daemon. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started RPC bind portmap service. May 05 07:03:19 plus-koelhuis-radio systemd[1]: hciuart.service: Succeeded. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Configure Bluetooth Modems connected by UART. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Save/Restore Sound Card State. May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: Server startup complete. Host name is plus-koelhuis-radio.local. Local service cookie is 705471064. May 05 07:03:19 plus-koelhuis-radio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED May 05 07:03:19 plus-koelhuis-radio avahi-daemon[557]: Service "PLUS Koelhuis Radio" (/services/volumio.service) successfully established. May 05 07:03:19 plus-koelhuis-radio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Failed to start dhcpcd on all interfaces. May 05 07:03:19 plus-koelhuis-radio sh[478]: eth0: waiting for carrier May 05 07:03:19 plus-koelhuis-radio dhcpcd[532]: eth0: waiting for carrier May 05 07:03:19 plus-koelhuis-radio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) May 05 07:03:19 plus-koelhuis-radio kernel: bcmgenet fd580000.ethernet eth0: Link is Down May 05 07:03:19 plus-koelhuis-radio sh[479]: wlan0=wlan0 May 05 07:03:19 plus-koelhuis-radio systemd[1]: Listening on mpd.socket. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Reached target Sound Card. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting Raspberry Pi bluetooth helper... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Reached target Remote File Systems (Pre). May 05 07:03:19 plus-koelhuis-radio systemd[1]: Reached target Remote File Systems. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Reached target RPC Port Mapper. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Raise network interfaces. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Reached target Network. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting Permit User Sessions... May 05 07:03:19 plus-koelhuis-radio bthelper[654]: Raspberry Pi BDADDR already set May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting Network Time Service... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Reached target Network is Online. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting LSB: Brings up/down network automatically... May 05 07:03:19 plus-koelhuis-radio volumio-remote-updater[549]: Error: No active session May 05 07:03:19 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:03:19] [info] asio async_connect error: system:111 (Connection refused) May 05 07:03:19 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:03:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 05 07:03:19 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:03:19] [error] handle_connect error: Underlying Transport Error May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting Samba NMB Daemon... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting /etc/rc.local Compatibility... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Starting Music Player Daemon... May 05 07:03:19 plus-koelhuis-radio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Raspberry Pi bluetooth helper. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started Permit User Sessions. May 05 07:03:19 plus-koelhuis-radio systemd[1]: Started /etc/rc.local Compatibility. May 05 07:03:19 plus-koelhuis-radio volumio[573]: Could not open config: /tmp/upmpdcli.conf May 05 07:03:20 plus-koelhuis-radio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 05 07:03:20 plus-koelhuis-radio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Starting Terminate Plymouth Boot Screen... May 05 07:03:20 plus-koelhuis-radio systemd[1]: Starting Hold until boot process finishes up... May 05 07:03:20 plus-koelhuis-radio systemd[1]: Starting Bluetooth service... May 05 07:03:20 plus-koelhuis-radio systemd[1]: Received SIGRTMIN+21 from PID 197 (plymouthd). May 05 07:03:20 plus-koelhuis-radio ifplugd(eth0)[709]: ifplugd 0.28 initializing. May 05 07:03:20 plus-koelhuis-radio ifplugd(eth0)[709]: Using interface eth0/D8:3A:DD:3A:50:89 with driver (version: 6.6.62-v7l+) May 05 07:03:20 plus-koelhuis-radio ifplugd(eth0)[709]: Using detection mode: SIOCETHTOOL May 05 07:03:20 plus-koelhuis-radio ifplugd(eth0)[709]: Initialization complete, link beat not detected. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Received SIGRTMIN+21 from PID 197 (plymouthd). May 05 07:03:20 plus-koelhuis-radio systemd[1]: plymouth-quit.service: Succeeded. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started Terminate Plymouth Boot Screen. May 05 07:03:20 plus-koelhuis-radio systemd[1]: plymouth-quit-wait.service: Succeeded. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started Hold until boot process finishes up. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started Getty on tty1. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Reached target Login Prompts. May 05 07:03:20 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: Date not found in response May 05 07:03:20 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: Retrying in 5 seconds... May 05 07:03:20 plus-koelhuis-radio systemd[1]: iptables.service: Succeeded. May 05 07:03:20 plus-koelhuis-radio haveged[517]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K May 05 07:03:20 plus-koelhuis-radio haveged[517]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 May 05 07:03:20 plus-koelhuis-radio haveged[517]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99703 May 05 07:03:20 plus-koelhuis-radio haveged[517]: haveged: fills: 0, generated: 0 May 05 07:03:20 plus-koelhuis-radio ifplugd[677]: Network Interface Plugging Daemon...start eth0...done. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started LSB: Brings up/down network automatically. May 05 07:03:20 plus-koelhuis-radio loadcpufreq[655]: Loading cpufreq kernel modules...done (none). May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 05 07:03:20 plus-koelhuis-radio ntpd[673]: ntpd 4.2.8p12@1.3728-o (1): Starting May 05 07:03:20 plus-koelhuis-radio ntpd[673]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 May 05 07:03:20 plus-koelhuis-radio ntpd[738]: proto: precision = 0.814 usec (-20) May 05 07:03:20 plus-koelhuis-radio systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started Network Time Service. May 05 07:03:20 plus-koelhuis-radio ntpd[738]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 05 07:03:20 plus-koelhuis-radio ntpd[738]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 05 07:03:20 plus-koelhuis-radio ntpd[738]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 129 days ago May 05 07:03:20 plus-koelhuis-radio ntpd[738]: Listen and drop on 0 v6wildcard [::]:123 May 05 07:03:20 plus-koelhuis-radio ntpd[738]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 05 07:03:20 plus-koelhuis-radio ntpd[738]: Listen normally on 2 lo 127.0.0.1:123 May 05 07:03:20 plus-koelhuis-radio ntpd[738]: Listening on routing socket on fd #19 for interface updates May 05 07:03:20 plus-koelhuis-radio ntpd[738]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 07:03:20 plus-koelhuis-radio ntpd[738]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 07:03:20 plus-koelhuis-radio bluetoothd[704]: Bluetooth daemon 5.50 May 05 07:03:20 plus-koelhuis-radio bluetoothd[704]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started Bluetooth service. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Reached target Bluetooth. May 05 07:03:20 plus-koelhuis-radio bluetoothd[704]: Starting SDP server May 05 07:03:20 plus-koelhuis-radio bluetoothd[704]: Excluding (cli) sap May 05 07:03:20 plus-koelhuis-radio cpufrequtils[737]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started LSB: set CPUFreq kernel parameters. May 05 07:03:20 plus-koelhuis-radio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 05 07:03:20 plus-koelhuis-radio kernel: Bluetooth: BNEP filters: protocol multicast May 05 07:03:20 plus-koelhuis-radio kernel: Bluetooth: BNEP socket layer initialized May 05 07:03:20 plus-koelhuis-radio dbus-daemon[551]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=704 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 05 07:03:20 plus-koelhuis-radio bluetoothd[704]: Bluetooth management interface 1.22 initialized May 05 07:03:20 plus-koelhuis-radio kernel: Bluetooth: MGMT ver 1.22 May 05 07:03:20 plus-koelhuis-radio sudo[682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 05 07:03:20 plus-koelhuis-radio sudo[682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:20 plus-koelhuis-radio sudo[682]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 05 07:03:20 plus-koelhuis-radio sudo[682]: pam_unix(sudo:session): session closed for user root May 05 07:03:20 plus-koelhuis-radio bluetoothd[704]: Failed to set privacy: Rejected (0x0b) May 05 07:03:20 plus-koelhuis-radio systemd[1]: Starting Hostname Service... May 05 07:03:20 plus-koelhuis-radio dbus-daemon[551]: [system] Successfully activated service 'org.freedesktop.hostname1' May 05 07:03:20 plus-koelhuis-radio systemd[1]: Started Hostname Service. May 05 07:03:21 plus-koelhuis-radio nmbd[684]: [2025/05/05 07:03:21.261536, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 05 07:03:21 plus-koelhuis-radio nmbd[684]: started asyncdns process 761 May 05 07:03:21 plus-koelhuis-radio nmbd[684]: [2025/05/05 07:03:21.263095, 0] ../lib/util/become_daemon.c:149(daemon_status) May 05 07:03:21 plus-koelhuis-radio nmbd[684]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 05 07:03:21 plus-koelhuis-radio nmbd[684]: [2025/05/05 07:03:21.263213, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 05 07:03:21 plus-koelhuis-radio nmbd[684]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 05 07:03:21 plus-koelhuis-radio ntpd[738]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 05 07:03:21 plus-koelhuis-radio wireless.js[550]: Cleaning previous... May 05 07:03:21 plus-koelhuis-radio sudo[769]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 05 07:03:21 plus-koelhuis-radio sudo[769]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:21 plus-koelhuis-radio sudo[769]: pam_unix(sudo:session): session closed for user root May 05 07:03:21 plus-koelhuis-radio sudo[771]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 05 07:03:21 plus-koelhuis-radio sudo[771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:22 plus-koelhuis-radio sudo[771]: pam_unix(sudo:session): session closed for user root May 05 07:03:22 plus-koelhuis-radio wireless.js[550]: Stopped aP May 05 07:03:22 plus-koelhuis-radio sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 07:03:22 plus-koelhuis-radio sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:22 plus-koelhuis-radio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 07:03:22 plus-koelhuis-radio sudo[778]: pam_unix(sudo:session): session closed for user root May 05 07:03:22 plus-koelhuis-radio sudo[780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 05 07:03:22 plus-koelhuis-radio sudo[780]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:22 plus-koelhuis-radio sudo[780]: pam_unix(sudo:session): session closed for user root May 05 07:03:22 plus-koelhuis-radio sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 07:03:22 plus-koelhuis-radio sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:22 plus-koelhuis-radio sudo[787]: pam_unix(sudo:session): session closed for user root May 05 07:03:22 plus-koelhuis-radio sudo[789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 05 07:03:22 plus-koelhuis-radio sudo[789]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:22 plus-koelhuis-radio ntpd[738]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 05 07:03:22 plus-koelhuis-radio mpd[758]: May 05 07:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 07:03:22 plus-koelhuis-radio systemd[1]: Started Music Player Daemon. May 05 07:03:22 plus-koelhuis-radio mpd_monitor.sh[572]: MPD Monitor Service: MPD restarted due to no mpc output. May 05 07:03:23 plus-koelhuis-radio ntpd[738]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 05 07:03:23 plus-koelhuis-radio systemd[1]: systemd-rfkill.service: Succeeded. May 05 07:03:23 plus-koelhuis-radio sh[478]: eth0: carrier acquired May 05 07:03:23 plus-koelhuis-radio dhcpcd[532]: eth0: carrier acquired May 05 07:03:23 plus-koelhuis-radio sh[478]: DUID 00:01:00:01:2f:52:ff:7e:d8:3a:dd:3a:50:89 May 05 07:03:23 plus-koelhuis-radio sh[478]: eth0: IAID dd:3a:50:89 May 05 07:03:23 plus-koelhuis-radio dhcpcd[532]: DUID 00:01:00:01:2f:52:ff:7e:d8:3a:dd:3a:50:89 May 05 07:03:23 plus-koelhuis-radio dhcpcd[532]: eth0: IAID dd:3a:50:89 May 05 07:03:23 plus-koelhuis-radio dhcpcd[532]: eth0: adding address fe80::c288:bc45:7676:b198 May 05 07:03:23 plus-koelhuis-radio sh[478]: eth0: adding address fe80::c288:bc45:7676:b198 May 05 07:03:23 plus-koelhuis-radio sh[478]: ipv6_addaddr1: Permission denied May 05 07:03:23 plus-koelhuis-radio dhcpcd[532]: ipv6_addaddr1: Permission denied May 05 07:03:23 plus-koelhuis-radio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 05 07:03:24 plus-koelhuis-radio sh[478]: eth0: soliciting a DHCP lease May 05 07:03:24 plus-koelhuis-radio dhcpcd[532]: eth0: soliciting a DHCP lease May 05 07:03:24 plus-koelhuis-radio sh[478]: eth0: offered 192.168.12.50 from 192.168.12.1 May 05 07:03:24 plus-koelhuis-radio dhcpcd[532]: eth0: offered 192.168.12.50 from 192.168.12.1 May 05 07:03:24 plus-koelhuis-radio sh[478]: eth0: probing address 192.168.12.50/24 May 05 07:03:24 plus-koelhuis-radio dhcpcd[532]: eth0: probing address 192.168.12.50/24 May 05 07:03:24 plus-koelhuis-radio ifplugd(eth0)[709]: Link beat detected. May 05 07:03:24 plus-koelhuis-radio sh[478]: eth0: soliciting an IPv6 router May 05 07:03:24 plus-koelhuis-radio dhcpcd[532]: eth0: soliciting an IPv6 router May 05 07:03:24 plus-koelhuis-radio ntpd[738]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 05 07:03:24 plus-koelhuis-radio ifplugd(eth0)[709]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 05 07:03:24 plus-koelhuis-radio ifplugd(eth0)[709]: client: ifup: waiting for lock on /run/network/ifstate.eth0 May 05 07:03:24 plus-koelhuis-radio sudo[789]: pam_unix(sudo:session): session closed for user root May 05 07:03:24 plus-koelhuis-radio wireless.js[550]: SETTING APPROPRIATE REG DOMAIN: NL May 05 07:03:24 plus-koelhuis-radio sudo[803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 07:03:24 plus-koelhuis-radio sudo[803]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:24 plus-koelhuis-radio sudo[803]: pam_unix(sudo:session): session closed for user root May 05 07:03:24 plus-koelhuis-radio sudo[805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set NL May 05 07:03:24 plus-koelhuis-radio sudo[805]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:24 plus-koelhuis-radio sudo[805]: pam_unix(sudo:session): session closed for user root May 05 07:03:24 plus-koelhuis-radio wireless.js[550]: SUCCESSFULLY SET NEW REGDOMAIN: NL May 05 07:03:24 plus-koelhuis-radio bthelper[654]: Changing power off succeeded May 05 07:03:24 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:03:24] [info] asio async_connect error: system:111 (Connection refused) May 05 07:03:24 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:03:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 05 07:03:24 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:03:24] [error] handle_connect error: Underlying Transport Error May 05 07:03:24 plus-koelhuis-radio sudo[813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 05 07:03:24 plus-koelhuis-radio sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:24 plus-koelhuis-radio sudo[813]: pam_unix(sudo:session): session closed for user root May 05 07:03:24 plus-koelhuis-radio sudo[815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 05 07:03:24 plus-koelhuis-radio sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:25 plus-koelhuis-radio bthelper[654]: [CHG] Controller D8:3A:DD:3A:50:8D Class: 0x0000041c May 05 07:03:25 plus-koelhuis-radio bthelper[654]: Changing power on succeeded May 05 07:03:25 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: Fetching time from Volumio... May 05 07:03:25 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: Date not found in response May 05 07:03:25 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: Retrying in 5 seconds... May 05 07:03:25 plus-koelhuis-radio sudo[815]: pam_unix(sudo:session): session closed for user root May 05 07:03:25 plus-koelhuis-radio avahi-daemon[557]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 05 07:03:25 plus-koelhuis-radio avahi-daemon[557]: New relevant interface wlan0.IPv4 for mDNS. May 05 07:03:25 plus-koelhuis-radio avahi-daemon[557]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 05 07:03:25 plus-koelhuis-radio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 07:03:25 plus-koelhuis-radio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... May 05 07:03:25 plus-koelhuis-radio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... May 05 07:03:25 plus-koelhuis-radio dnsmasq[833]: dnsmasq: syntax check OK. May 05 07:03:25 plus-koelhuis-radio hostapd[834]: Configuration file: /etc/hostapd/hostapd.conf May 05 07:03:25 plus-koelhuis-radio hostapd[834]: wlan0: Could not connect to kernel driver May 05 07:03:25 plus-koelhuis-radio hostapd[834]: Using interface wlan0 with hwaddr d8:3a:dd:3a:50:8b and ssid "PLUS Koelhuis Radio" May 05 07:03:25 plus-koelhuis-radio dnsmasq[840]: started, version 2.80 cachesize 150 May 05 07:03:25 plus-koelhuis-radio dnsmasq[840]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile May 05 07:03:25 plus-koelhuis-radio dnsmasq-dhcp[840]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d May 05 07:03:25 plus-koelhuis-radio dnsmasq[840]: reading /etc/resolv.conf May 05 07:03:25 plus-koelhuis-radio dnsmasq[840]: using nameserver 208.67.222.222#53 May 05 07:03:25 plus-koelhuis-radio dnsmasq[840]: using nameserver 208.67.220.220#53 May 05 07:03:25 plus-koelhuis-radio dnsmasq[840]: read /etc/hosts - 1 addresses May 05 07:03:25 plus-koelhuis-radio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. May 05 07:03:25 plus-koelhuis-radio systemd[1]: Reached target Host and Network Name Lookups. May 05 07:03:25 plus-koelhuis-radio hostapd[834]: wlan0: interface state UNINITIALIZED->ENABLED May 05 07:03:25 plus-koelhuis-radio hostapd[834]: wlan0: AP-ENABLED May 05 07:03:25 plus-koelhuis-radio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. May 05 07:03:25 plus-koelhuis-radio iwconfig[849]: Error for wireless request "Set Power Management" (8B2C) : May 05 07:03:25 plus-koelhuis-radio iwconfig[849]: SET failed on device wlan0 ; Invalid argument. May 05 07:03:25 plus-koelhuis-radio systemd[1]: wireless.service: Succeeded. May 05 07:03:25 plus-koelhuis-radio systemd[1]: Started Wireless Services. May 05 07:03:25 plus-koelhuis-radio systemd[1]: Started Volumio Backend Module. May 05 07:03:25 plus-koelhuis-radio systemd[1]: Started Volumio Cpu Tweaker. May 05 07:03:25 plus-koelhuis-radio volumio-cpu-tweak[851]: Setting RT Priority for mpd May 05 07:03:25 plus-koelhuis-radio volumio-cpu-tweak[851]: Setting MPD Affinity May 05 07:03:25 plus-koelhuis-radio volumio-cpu-tweak[851]: pid 758's current affinity mask: f May 05 07:03:25 plus-koelhuis-radio volumio-cpu-tweak[851]: pid 758's new affinity mask: 3 May 05 07:03:25 plus-koelhuis-radio volumio-cpu-tweak[851]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 05 07:03:25 plus-koelhuis-radio volumio-cpu-tweak[851]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 05 07:03:25 plus-koelhuis-radio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 05 07:03:26 plus-koelhuis-radio nmbd[684]: [2025/05/05 07:03:26.267272, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 07:03:26 plus-koelhuis-radio nmbd[684]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 05 07:03:26 plus-koelhuis-radio systemd[1]: Started Samba NMB Daemon. May 05 07:03:26 plus-koelhuis-radio systemd[1]: Starting Samba Winbind Daemon... May 05 07:03:26 plus-koelhuis-radio winbindd[867]: [2025/05/05 07:03:26.591127, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 05 07:03:26 plus-koelhuis-radio winbindd[867]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 05 07:03:26 plus-koelhuis-radio winbindd[867]: [2025/05/05 07:03:26.616436, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 07:03:26 plus-koelhuis-radio winbindd[867]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 05 07:03:26 plus-koelhuis-radio systemd[1]: Started Samba Winbind Daemon. May 05 07:03:26 plus-koelhuis-radio systemd[1]: Starting Samba SMB Daemon... May 05 07:03:27 plus-koelhuis-radio smbd[872]: [2025/05/05 07:03:27.019914, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 07:03:27 plus-koelhuis-radio smbd[872]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 05 07:03:27 plus-koelhuis-radio systemd[1]: Started Samba SMB Daemon. May 05 07:03:27 plus-koelhuis-radio ntpd[738]: Listen normally on 3 wlan0 192.168.211.1:123 May 05 07:03:27 plus-koelhuis-radio ntpd[738]: new interface(s) found: waking up resolver May 05 07:03:27 plus-koelhuis-radio volumio[850]: info: ------------------------------------------- May 05 07:03:27 plus-koelhuis-radio volumio[850]: info: ----- Volumio3 ---- May 05 07:03:27 plus-koelhuis-radio volumio[850]: info: ------------------------------------------- May 05 07:03:27 plus-koelhuis-radio volumio[850]: info: ----- System startup ---- May 05 07:03:27 plus-koelhuis-radio volumio[850]: info: ------------------------------------------- May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: MYVOLUMIO Environment detected May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Plugin folders cleanup May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning into folder /volumio/app/plugins/ May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category audio_interface May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category miscellanea May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category music_service May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category plugins.json May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category system_controller May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category user_interface May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning into folder /data/plugins/ May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category music_service May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category system_controller May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Scanning category user_interface May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Plugin folders cleanup completed May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: ------------------------------------------- May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: ----- Core plugins startup ---- May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: ------------------------------------------- May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Loading plugins from folder /volumio/app/plugins/ May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Adding plugin upnp to MyMusic Plugins May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Adding plugin airplay_emulation to MyMusic Plugins May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Adding plugin upnp_browser to MyMusic Plugins May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Loading plugins from folder /data/plugins/ May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Loading plugin "system"... May 05 07:03:28 plus-koelhuis-radio volumio[850]: info: Loading plugin "appearance"... May 05 07:03:28 plus-koelhuis-radio sh[478]: eth0: leased 192.168.12.50 for 86400 seconds May 05 07:03:28 plus-koelhuis-radio sh[478]: eth0: adding route to 192.168.12.0/24 May 05 07:03:28 plus-koelhuis-radio sh[478]: eth0: adding default route via 192.168.12.1 May 05 07:03:28 plus-koelhuis-radio dhcpcd[532]: eth0: leased 192.168.12.50 for 86400 seconds May 05 07:03:28 plus-koelhuis-radio dhcpcd[532]: eth0: adding route to 192.168.12.0/24 May 05 07:03:28 plus-koelhuis-radio dhcpcd[532]: eth0: adding default route via 192.168.12.1 May 05 07:03:28 plus-koelhuis-radio avahi-daemon[557]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.12.50. May 05 07:03:28 plus-koelhuis-radio avahi-daemon[557]: New relevant interface eth0.IPv4 for mDNS. May 05 07:03:28 plus-koelhuis-radio avahi-daemon[557]: Registering new address record for 192.168.12.50 on eth0.IPv4. May 05 07:03:29 plus-koelhuis-radio dnsmasq[840]: reading /etc/resolv.conf May 05 07:03:29 plus-koelhuis-radio dnsmasq[840]: using nameserver 192.168.12.1#53 May 05 07:03:29 plus-koelhuis-radio dnsmasq[840]: using nameserver 208.67.222.222#53 May 05 07:03:29 plus-koelhuis-radio dnsmasq[840]: using nameserver 208.67.220.220#53 May 05 07:03:29 plus-koelhuis-radio sh[478]: forked to background, child pid 913 May 05 07:03:29 plus-koelhuis-radio dhcpcd[532]: forked to background, child pid 913 May 05 07:03:29 plus-koelhuis-radio ifplugd(eth0)[709]: client: ifup: interface eth0 already configured May 05 07:03:29 plus-koelhuis-radio sh[478]: eth0=eth0 May 05 07:03:29 plus-koelhuis-radio ifplugd(eth0)[709]: Program executed successfully. May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "network"... May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Refreshing Cached IP Addresses May 05 07:03:29 plus-koelhuis-radio sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 07:03:29 plus-koelhuis-radio sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:29 plus-koelhuis-radio sudo[940]: pam_unix(sudo:session): session closed for user root May 05 07:03:29 plus-koelhuis-radio sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 07:03:29 plus-koelhuis-radio sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:29 plus-koelhuis-radio sudo[942]: pam_unix(sudo:session): session closed for user root May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "services"... May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "alsa_controller"... May 05 07:03:29 plus-koelhuis-radio sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 05 07:03:29 plus-koelhuis-radio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "wizard"... May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "networkfs"... May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Starting Udev Watcher for removable devices May 05 07:03:29 plus-koelhuis-radio sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=administrator,password=KoelhuisPWD2430!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.1.10/music /mnt/NAS/Koelhuis_Server_01 May 05 07:03:29 plus-koelhuis-radio sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Ignoring mount for partition: boot May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Ignoring mount for partition: volumio May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Ignoring mount for partition: volumio_data May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "volumio_command_line_client"... May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "upnp"... May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: [1746421409602] Starting Upmpd Daemon May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "my_music"... May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "mpd"... May 05 07:03:29 plus-koelhuis-radio kernel: Key type cifs.spnego registered May 05 07:03:29 plus-koelhuis-radio kernel: Key type cifs.idmap registered May 05 07:03:29 plus-koelhuis-radio 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. May 05 07:03:29 plus-koelhuis-radio kernel: CIFS: Attempting to mount //10.0.1.10/music May 05 07:03:29 plus-koelhuis-radio kernel: cryptd: max_cpu_qlen set to 1000 May 05 07:03:29 plus-koelhuis-radio sudo[968]: pam_unix(sudo:session): session closed for user root May 05 07:03:29 plus-koelhuis-radio volumio[850]: info: Loading plugin "upnp_browser"... May 05 07:03:30 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: Fetching time from Volumio... May 05 07:03:30 plus-koelhuis-radio volumio[850]: info: Loading plugin "alarm-clock"... May 05 07:03:30 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: Setting system time to: 2025-05-05 07:03:58 May 05 07:03:30 plus-koelhuis-radio sudo[1010]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-05 07:03:58 May 05 07:03:30 plus-koelhuis-radio sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:03:30 plus-koelhuis-radio dbus-daemon[551]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1011 comm="timedatectl set-time 2025-05-05 07:03:58 ") May 05 07:03:30 plus-koelhuis-radio ntpd[738]: Listen normally on 4 eth0 192.168.12.50:123 May 05 07:03:30 plus-koelhuis-radio ntpd[738]: new interface(s) found: waking up resolver May 05 07:03:30 plus-koelhuis-radio systemd[1]: Starting Time & Date Service... May 05 07:03:30 plus-koelhuis-radio volumio[850]: info: Loading plugin "airplay_emulation"... May 05 07:03:30 plus-koelhuis-radio volumio[850]: info: Starting Shairport Sync May 05 07:03:30 plus-koelhuis-radio volumio[850]: info: Loading plugin "last_100"... May 05 07:03:30 plus-koelhuis-radio volumio[850]: info: Loading plugin "webradio"... May 05 07:03:30 plus-koelhuis-radio dbus-daemon[551]: [system] Successfully activated service 'org.freedesktop.timedate1' May 05 07:03:30 plus-koelhuis-radio systemd[1]: Started Time & Date Service. May 05 07:03:58 plus-koelhuis-radio systemd-timedated[1012]: Changed local time to Mon May 5 07:03:58 2025 May 05 07:03:58 plus-koelhuis-radio sudo[1010]: pam_unix(sudo:session): session closed for user root May 05 07:03:58 plus-koelhuis-radio volumio-time-update[564]: volumio-time-update-util: System time updated successfully. May 05 07:03:58 plus-koelhuis-radio systemd[1]: Started Volumio Time Update Utility. May 05 07:03:58 plus-koelhuis-radio systemd[1]: Reached target Multi-User System. May 05 07:03:58 plus-koelhuis-radio systemd[1]: Reached target Graphical Interface. May 05 07:03:58 plus-koelhuis-radio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 05 07:03:58 plus-koelhuis-radio volumio[850]: info: Loading plugin "i2s_dacs"... May 05 07:03:58 plus-koelhuis-radio volumio[850]: info: I2S DAC not set, start Auto-detection May 05 07:03:58 plus-koelhuis-radio volumio[850]: info: Loading plugin "volumiodiscovery"... May 05 07:03:58 plus-koelhuis-radio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 05 07:03:58 plus-koelhuis-radio systemd[1]: Started Update UTMP about System Runlevel Changes. May 05 07:03:58 plus-koelhuis-radio volumio[850]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 07:03:58 plus-koelhuis-radio volumio[850]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 07:03:58 plus-koelhuis-radio volumio[850]: *** WARNING *** For more information see May 05 07:03:58 plus-koelhuis-radio node[850]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 07:03:58 plus-koelhuis-radio volumio[850]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 07:03:58 plus-koelhuis-radio volumio[850]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 07:03:58 plus-koelhuis-radio volumio[850]: *** WARNING *** For more information see May 05 07:03:58 plus-koelhuis-radio node[850]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 07:03:58 plus-koelhuis-radio node[850]: *** WARNING *** For more information see May 05 07:03:58 plus-koelhuis-radio node[850]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 07:03:58 plus-koelhuis-radio node[850]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 07:03:58 plus-koelhuis-radio node[850]: *** WARNING *** For more information see May 05 07:03:58 plus-koelhuis-radio volumio[850]: info: Applying required configuration parameters for plugin volumiodiscovery May 05 07:03:58 plus-koelhuis-radio volumio[850]: info: Discovery: Started advertising with name: PLUS Koelhuis Radio May 05 07:03:58 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 07:03:58 plus-koelhuis-radio volumio[850]: info: Loading plugin "spop"... May 05 07:03:58 plus-koelhuis-radio systemd[1]: Startup finished in 12.260s (kernel) + 14.419s (userspace) = 26.680s. May 05 07:03:59 plus-koelhuis-radio volumio[850]: info: Loading plugin "ytcr"... May 05 07:03:59 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:03:59] [connect] Successful connection May 05 07:04:00 plus-koelhuis-radio sudo[951]: pam_unix(sudo:session): session closed for user root May 05 07:04:02 plus-koelhuis-radio volumio[850]: info: Loading plugin "autostart"... May 05 07:04:02 plus-koelhuis-radio volumio[850]: info: Applying required configuration parameters for plugin autostart May 05 07:04:02 plus-koelhuis-radio volumio[850]: info: AutoStart - onVolumioStart - read config.json May 05 07:04:02 plus-koelhuis-radio volumio[850]: info: Loading plugin "now_playing"... May 05 07:04:03 plus-koelhuis-radio volumio[850]: info: Loading plugin "outputs"... May 05 07:04:03 plus-koelhuis-radio volumio[850]: info: Loading plugin "albumart"... May 05 07:04:03 plus-koelhuis-radio volumio[850]: info: Plugin example_plugin is not enabled May 05 07:04:03 plus-koelhuis-radio volumio[850]: info: Loading plugin "inputs"... May 05 07:04:03 plus-koelhuis-radio volumio[850]: info: Loading plugin "updater_comm"... May 05 07:04:04 plus-koelhuis-radio volumio[850]: info: Plugin mpdemulation is not enabled May 05 07:04:04 plus-koelhuis-radio volumio[850]: info: Loading plugin "rest_api"... May 05 07:04:04 plus-koelhuis-radio volumio[850]: info: Loading plugin "websocket"... May 05 07:04:04 plus-koelhuis-radio volumio[850]: info: Starting Socket.io Server version 2.3.0 May 05 07:04:04 plus-koelhuis-radio volumio[850]: info: Loading plugin "Systeminfo"... May 05 07:04:04 plus-koelhuis-radio volumio[850]: Forking 3 albumart workers May 05 07:04:04 plus-koelhuis-radio volumio[850]: info: Loading plugin "randomizer"... May 05 07:04:04 plus-koelhuis-radio volumio[850]: Starting albumart workers May 05 07:04:04 plus-koelhuis-radio volumio[850]: Starting albumart workers May 05 07:04:04 plus-koelhuis-radio volumio[850]: Starting albumart workers May 05 07:04:04 plus-koelhuis-radio volumio[850]: info: Applying required configuration parameters for plugin randomizer May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Loading i18n strings for locale nl May 05 07:04:05 plus-koelhuis-radio volumio[850]: Updating browse sources language May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::initPlayerControls May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 07:04:05 plus-koelhuis-radio volumio[850]: Express server listening on port 3000 May 05 07:04:05 plus-koelhuis-radio volumio[850]: [Metrics] WebUI: 10s 922.52ms May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::resetVolumioState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::getcurrentVolume May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioRetrievevolume May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Volumio Network Manager: Network status updated: 1 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::setRepeat true single undefined May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::pushState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioPushState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::setRandom true May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::pushState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioPushState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: VolumeController:: Volume=40 Mute =false May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::pushState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioPushState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::updateTrackBlock May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrackBlock May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioRetrievevolume May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Reloading queue from file May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Setting Device type: Raspberry PI May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Completed loading Core Plugins May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Preparing to generate the ALSA configuration file May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Discovery: adding 3884955b-adc7-4a28-b0b8-a112b00659c9 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Discovery: Found device PLUS Koelhuis Radio May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioGetState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: VolumeController:: Volume=40 Mute =false May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::pushState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioPushState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Discovery: this is already registered, 3884955b-adc7-4a28-b0b8-a112b00659c9 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Discovery: Found device PLUS Koelhuis Radio May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioGetState May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Asound.conf file unchanged, so no further update is needed May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Output device has changed, restarting MPD May 05 07:04:05 plus-koelhuis-radio sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 07:04:05 plus-koelhuis-radio sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Output device has changed, restarting Shairport Sync May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 07:04:05 plus-koelhuis-radio sudo[1082]: pam_unix(sudo:session): session closed for user root May 05 07:04:05 plus-koelhuis-radio sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 07:04:05 plus-koelhuis-radio sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:05 plus-koelhuis-radio systemd[1]: Stopping Music Player Daemon... May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: ___________ START PLUGINS ___________ May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: ControllerMpd::onStart: Initializing MPD May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Creating MPD Configuration file May 05 07:04:05 plus-koelhuis-radio sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 07:04:05 plus-koelhuis-radio sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 07:04:05 plus-koelhuis-radio sudo[1091]: pam_unix(sudo:session): session closed for user root May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: [1746421445847] CoreMusicLibrary::Adding element Media Servers May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 07:04:05 plus-koelhuis-radio sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 07:04:05 plus-koelhuis-radio sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 07:04:05 plus-koelhuis-radio systemd[1]: mpd.service: Succeeded. May 05 07:04:05 plus-koelhuis-radio systemd[1]: Stopped Music Player Daemon. May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: [1746421445942] CoreMusicLibrary::Adding element Last_100 May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: [1746421445944] CoreMusicLibrary::Adding element Webradio May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 07:04:05 plus-koelhuis-radio volumio[850]: info: Initializing BBC Radios May 05 07:04:05 plus-koelhuis-radio systemd[1]: Starting Music Player Daemon... May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Creating Spotify config file May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio sudo[1099]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 05 07:04:06 plus-koelhuis-radio sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:06 plus-koelhuis-radio sudo[1099]: pam_unix(sudo:session): session closed for user root May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: AutoStart - onStart May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: [now-playing] ConfigUpdater: config is up to date. May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: [1746421446153] CoreMusicLibrary::Adding element Randomizer May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 07:04:06 plus-koelhuis-radio volumio[850]: Cannot find translation for source Randomizer May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Volumio Calling Home May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: [now-playing] App is listening on port 4004. May 05 07:04:06 plus-koelhuis-radio volumio[850]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: MPD Permissions set May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Spotify config file written May 05 07:04:06 plus-koelhuis-radio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 05 07:04:06 plus-koelhuis-radio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:06 plus-koelhuis-radio volumio[850]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 05 07:04:06 plus-koelhuis-radio volumio[850]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Volumio called home May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: No need to fix Spotify hosts May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio systemd[1]: Started go-librespot Daemon. May 05 07:04:06 plus-koelhuis-radio go-librespot[1121]: go-librespot daemon starting... May 05 07:04:06 plus-koelhuis-radio sudo[1119]: pam_unix(sudo:session): session closed for user root May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: An error occurred while refreshing Spotify Token Error: Bad Request May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Starting Shairport Sync May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Starting Shairport Sync May 05 07:04:06 plus-koelhuis-radio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 07:04:06 plus-koelhuis-radio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Starting Shairport Sync May 05 07:04:06 plus-koelhuis-radio sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 07:04:06 plus-koelhuis-radio sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioGetState May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:06 plus-koelhuis-radio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 07:04:06 plus-koelhuis-radio systemd[1]: shairport-sync.service: Succeeded. May 05 07:04:06 plus-koelhuis-radio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 07:04:06 plus-koelhuis-radio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 07:04:06 plus-koelhuis-radio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:06 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:06+02:00" level=info msg="running go-librespot 0.2.0" May 05 07:04:06 plus-koelhuis-radio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 07:04:06 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:06+02:00" level=debug msg="app state loaded" May 05 07:04:06 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:06+02:00" level=debug msg="stored credentials not found" May 05 07:04:06 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 07:04:06 plus-koelhuis-radio sudo[1137]: pam_unix(sudo:session): session closed for user root May 05 07:04:06 plus-koelhuis-radio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 07:04:06 plus-koelhuis-radio systemd[1]: shairport-sync.service: Succeeded. May 05 07:04:06 plus-koelhuis-radio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 07:04:06 plus-koelhuis-radio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 07:04:06 plus-koelhuis-radio sudo[1140]: pam_unix(sudo:session): session closed for user root May 05 07:04:06 plus-koelhuis-radio sudo[1142]: pam_unix(sudo:session): session closed for user root May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Shairport-Sync Started May 05 07:04:06 plus-koelhuis-radio volumio[850]: Error adding Membership: Error: addMembership EINVAL May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Shairport-Sync Started May 05 07:04:06 plus-koelhuis-radio volumio[850]: info: Shairport-Sync Started May 05 07:04:06 plus-koelhuis-radio mpd[1114]: May 05 07:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 07:04:06 plus-koelhuis-radio systemd[1]: Started Music Player Daemon. May 05 07:04:06 plus-koelhuis-radio sudo[1085]: pam_unix(sudo:session): session closed for user root May 05 07:04:06 plus-koelhuis-radio sudo[1094]: pam_unix(sudo:session): session closed for user root May 05 07:04:06 plus-koelhuis-radio volumio[850]: error: updateQueue error: null May 05 07:04:07 plus-koelhuis-radio volumio[850]: error: updateQueue error: null May 05 07:04:07 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:07+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 05 07:04:07 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:07+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 05 07:04:07 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:07+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: MPD running with PID1114 May 05 07:04:07 plus-koelhuis-radio volumio[850]: ,establishing connection May 05 07:04:07 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:07+02:00" level=info msg="zeroconf server listening on port 42201" May 05 07:04:07 plus-koelhuis-radio volumio[850]: error: updateQueue error: null May 05 07:04:07 plus-koelhuis-radio sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 07:04:07 plus-koelhuis-radio sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:07 plus-koelhuis-radio sudo[1173]: pam_unix(sudo:session): session closed for user root May 05 07:04:07 plus-koelhuis-radio sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 07:04:07 plus-koelhuis-radio sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:07 plus-koelhuis-radio sudo[1176]: pam_unix(sudo:session): session closed for user root May 05 07:04:07 plus-koelhuis-radio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 05 07:04:07 plus-koelhuis-radio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: [yt-cast-receiver] DIAL server listening on port 8098 May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioRetrievevolume May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: VolumeController:: Volume=40 Mute =false May 05 07:04:07 plus-koelhuis-radio systemd[1]: Started UPnP Renderer front-end to MPD. May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioGetState May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: CoreStateMachine::pushState May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioPushState May 05 07:04:07 plus-koelhuis-radio sudo[1179]: pam_unix(sudo:session): session closed for user root May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: Upmpdcli Daemon Started May 05 07:04:07 plus-koelhuis-radio volumio[1181]: Generating RSA private key, 4096 bit long modulus (2 primes) May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: Completed starting Core Plugins May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: ------------------------------------------- May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: ----- MyVolumio plugins startup ---- May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: ------------------------------------------- May 05 07:04:07 plus-koelhuis-radio volumio[850]: info: [MyVolumio PluginManager] Fetching plans data.... May 05 07:04:09 plus-koelhuis-radio volumio[850]: info: go-librespot daemon successfully initialized May 05 07:04:12 plus-koelhuis-radio volumio[850]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 05 07:04:12 plus-koelhuis-radio volumio[850]: info: Initializing connection to go-librespot Websocket May 05 07:04:12 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:12+02:00" level=debug msg="new websocket client" May 05 07:04:12 plus-koelhuis-radio volumio[850]: info: Connection to go-librespot Websocket established May 05 07:04:14 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:04:14] [connect] Successful connection May 05 07:04:14 plus-koelhuis-radio volumio-remote-updater[549]: [2025-05-05 07:04:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746421454 101 May 05 07:04:14 plus-koelhuis-radio volumio[850]: 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: 3 May 05 07:04:14 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:14+02:00" level=debug msg="obtained new client token: AAD28vxawEWz1poHyd4X2KE8pMdRfhGZI+mVAUfrbrl1UBcoSBLoGRGra8Ziss0Vq0HEkZs820WmnxODuCuy4MEbJvYhNarzt9Cy7x4c5P84ag3r1VaXzrPfU5XM/6sgqaxIGC5nUpdCknru9BegidIFjMiqY2sSVn5whr60P/g+NSbTGO8Ur0FfTiIkTwR9lZXxltXsyCFVUNye6lGjjMdVzbx3jkdtpl1JYx29dcXDnUMgpXS5pd4SAw==" May 05 07:04:14 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 05 07:04:14 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:14+02:00" level=debug msg="completed keyexchange" May 05 07:04:14 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:14+02:00" level=debug msg="completed challenge" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=info msg="authenticated AP as 3164ei6zooa6xtz62b7cwrfgmcli" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=info msg="authenticated Login5 as 3164ei6zooa6xtz62b7cwrfgmcli" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=info msg="accepted zeroconf user 3164ei6zooa6xtz62b7cwrfgmcli from Galaxy S25 Ultra" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="dealer connection opened" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="starting accesspoint recv loop" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="starting dealer recv loop" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="received accesspoint ping" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="received connection id: NjJmM2ZhM2UtYjMzOC00NTQ4LTliNjItMzBlYzVhODBhYjU3K2RlYWxlcit0Y3A6Ly8wYWNhNTkxZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNDk0QUEyN0RBQjA2ODlBODQ3MTBCMDU3NDBENUFBREU5MUFCNjI4MzMzNzZDNUVENDk5NTFDRjYxQTQyMzQ5MA==" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="received accesspoint pong ack" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="put connect state because NEW_DEVICE" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="handling transfer player command from d00d937732a0b34d3ae0cff1fe8470810d5f8681" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="resolved context of track" uri="spotify:search:smoo" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:search:smoo" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="loading track (paused: true, position: 657ms)" uri="spotify:track:1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="emitting websocket event: will_play" May 05 07:04:15 plus-koelhuis-radio volumio[850]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Hv1VTm8zeOeybub15mA2R","play_origin":"search"}} May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="selected format OGG_VORBIS_320 (c4cfabb1468531841876ced220d79e69d583bec0)" uri="spotify:track:1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="requested aes key for file c4cfabb1468531841876ced220d79e69d583bec0, gid: 1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="fetched first chunk of 19, total size is 9755060 bytes" uri="spotify:track:1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="seek to 657ms (diff: 296ms, samples: 28973, bytes: 8241)" uri="spotify:track:1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="created new output device" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=info msg="loaded track \"Smooth Operator - Single Version\" (paused: true, position: 657ms, duration: 258693ms, prefetched: false)" uri="spotify:track:1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:1Hv1VTm8zeOeybub15mA2R" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="emitting websocket event: metadata" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="emitting websocket event: active" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="sending successful reply for dealer request" May 05 07:04:15 plus-koelhuis-radio volumio[850]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1Hv1VTm8zeOeybub15mA2R","name":"Smooth Operator - Single Version","artist_names":["Sade"],"album_name":"The Best of Sade","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025e25e034e25258b356774c79","position":657,"duration":258693,"release_date":"year:1994 month:10 day:31","track_number":3,"disc_number":1}} May 05 07:04:15 plus-koelhuis-radio volumio[850]: SPOTIFY: received: {"type":"active","data":null} May 05 07:04:15 plus-koelhuis-radio volumio[850]: info: Aligning Spotify Volume to Volumio Volume May 05 07:04:15 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioGetState May 05 07:04:15 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:15 plus-koelhuis-radio volumio[850]: info: Setting Spotify Volume from Volumio: 40 May 05 07:04:15 plus-koelhuis-radio volumio[850]: info: Getting Spotify volume May 05 07:04:15 plus-koelhuis-radio volumio[850]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 07:04:15 plus-koelhuis-radio go-librespot[1121]: time="2025-05-05T07:04:15+02:00" level=trace msg="emitting websocket event: paused" May 05 07:04:15 plus-koelhuis-radio volumio[850]: info: CoreCommandRouter::volumioGetState May 05 07:04:15 plus-koelhuis-radio volumio[850]: info: CorePlayQueue::getTrack 0 May 05 07:04:15 plus-koelhuis-radio volumio[850]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1Hv1VTm8zeOeybub15mA2R","play_origin":"search"}} May 05 07:04:15 plus-koelhuis-radio volumio[850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 07:04:15 plus-koelhuis-radio volumio[850]: TypeError: Cannot read property 'service' of undefined May 05 07:04:15 plus-koelhuis-radio volumio[850]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:340:50) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:274:18) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at WebSocket.emit (events.js:315:20) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Receiver.emit (events.js:315:20) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at writeOrBuffer (internal/streams/writable.js:358:12) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Receiver.Writable.write (internal/streams/writable.js:303:10) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at Socket.emit (events.js:315:20) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at addChunk (internal/streams/readable.js:309:12) May 05 07:04:15 plus-koelhuis-radio volumio[850]: at readableAddChunk (internal/streams/readable.js:284:9) May 05 07:04:15 plus-koelhuis-radio volumio[850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 07:04:15 plus-koelhuis-radio sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 07:03 May 05 07:04:15 plus-koelhuis-radio sudo[1211]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"