-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2025-05-04 01:01:35 CEST. -- May 04 01:00:40 musikkiste fake-hwclock[300]: Sat May 3 23:00:40 UTC 2025 May 04 01:00:40 musikkiste systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 04 01:00:40 musikkiste systemd[1]: Starting Load/Save Random Seed... May 04 01:00:40 musikkiste systemd[1]: Started Restore / save the current clock. May 04 01:00:40 musikkiste systemd[1]: Started Apply Kernel Variables. May 04 01:00:40 musikkiste volumio[292]: 512 MB or less RAM Detected, need to enable swap May 04 01:00:40 musikkiste systemd[1]: Started Load/Save Random Seed. May 04 01:00:40 musikkiste systemd[1]: Started udev Coldplug all Devices. May 04 01:00:40 musikkiste volumio[292]: Enabling Swap May 04 01:00:40 musikkiste kernel: ext4 filesystem being mounted at /swap supports timestamps until 2038 (0x7fffffff) May 04 01:00:40 musikkiste systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 04 01:00:40 musikkiste systemd[1]: Started Create System Users. May 04 01:00:40 musikkiste systemd[1]: Starting Create Static Device Nodes in /dev... May 04 01:00:40 musikkiste volumio[292]: Setting swappiness to 40 May 04 01:00:40 musikkiste kernel: Adding 524240k swap on /swap/swapfile. Priority:-2 extents:11 across:974840k SSFS May 04 01:00:40 musikkiste systemd[1]: Started Helper to synchronize boot up for ifupdown. May 04 01:00:40 musikkiste volumio[292]: vm.swappiness = 40 May 04 01:00:40 musikkiste systemd[1]: dynamicswap.service: Succeeded. May 04 01:00:40 musikkiste systemd[1]: Started Create Static Device Nodes in /dev. May 04 01:00:40 musikkiste systemd[1]: Starting udev Kernel Device Manager... May 04 01:00:40 musikkiste systemd[1]: Reached target Local File Systems (Pre). May 04 01:00:40 musikkiste systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 04 01:00:40 musikkiste systemd[1]: Mounting /tmp... May 04 01:00:40 musikkiste systemd[1]: Mounting /var/spool/cups... May 04 01:00:40 musikkiste systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 04 01:00:40 musikkiste systemd[1]: Mounting /var/log... May 04 01:00:40 musikkiste systemd[1]: Mounted /tmp. May 04 01:00:40 musikkiste systemd[1]: Mounted /var/spool/cups. May 04 01:00:40 musikkiste systemd[1]: Mounted /var/log. May 04 01:00:40 musikkiste systemd[1]: Starting Flush Journal to Persistent Storage... May 04 01:00:40 musikkiste systemd[1]: Mounting /var/spool/cups/tmp... May 04 01:00:40 musikkiste systemd-udevd[327]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 04 01:00:40 musikkiste systemd[1]: Mounted /var/spool/cups/tmp. May 04 01:00:40 musikkiste systemd-journald[295]: Runtime journal (/run/log/journal/31189cf30d98866a049b08ee61b75d9e) is 7.5M, max 30.0M, 22.5M free. May 04 01:00:40 musikkiste systemd[1]: Started udev Kernel Device Manager. May 04 01:00:40 musikkiste systemd[1]: Started Flush Journal to Persistent Storage. May 04 01:00:40 musikkiste systemd[1]: Starting Show Plymouth Boot Screen... May 04 01:00:40 musikkiste systemd[1]: plymouth-start.service: Succeeded. May 04 01:00:40 musikkiste systemd[1]: Started Show Plymouth Boot Screen. May 04 01:00:40 musikkiste systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 04 01:00:40 musikkiste systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 04 01:00:40 musikkiste systemd[1]: Reached target Paths. May 04 01:00:40 musikkiste systemd[1]: Reached target Local Encrypted Volumes. May 04 01:00:40 musikkiste kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 04 01:00:40 musikkiste kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 04 01:00:40 musikkiste kernel: [vc_sm_connected_init]: start May 04 01:00:41 musikkiste kernel: [vc_sm_connected_init]: installed successfully May 04 01:00:41 musikkiste kernel: mc: Linux media interface: v0.10 May 04 01:00:41 musikkiste kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 May 04 01:00:41 musikkiste kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem May 04 01:00:41 musikkiste systemd[1]: Found device /dev/ttyAMA0. May 04 01:00:41 musikkiste kernel: videodev: Linux video capture interface: v2.00 May 04 01:00:41 musikkiste kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 04 01:00:41 musikkiste kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 04 01:00:41 musikkiste kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 04 01:00:41 musikkiste kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. May 04 01:00:41 musikkiste kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 04 01:00:41 musikkiste kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 May 04 01:00:41 musikkiste kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image May 04 01:00:41 musikkiste kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 04 01:00:41 musikkiste kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored May 04 01:00:41 musikkiste kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 04 01:00:41 musikkiste kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 04 01:00:41 musikkiste systemd[1]: Found device /dev/disk/by-uuid/E28E-B7B1. May 04 01:00:42 musikkiste systemd[1]: Starting File System Check on /dev/disk/by-uuid/E28E-B7B1... May 04 01:00:42 musikkiste kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 04 01:00:42 musikkiste kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' May 04 01:00:42 musikkiste kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' May 04 01:00:42 musikkiste kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' May 04 01:00:42 musikkiste kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 04 01:00:42 musikkiste systemd-udevd[337]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 04 01:00:42 musikkiste kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 May 04 01:00:42 musikkiste kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 May 04 01:00:42 musikkiste kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,model-zero-2-w.bin failed with error -2 May 04 01:00:42 musikkiste kernel: usbcore: registered new interface driver brcmfmac May 04 01:00:42 musikkiste systemd-fsck[395]: fsck.fat 4.1 (2017-01-24) May 04 01:00:42 musikkiste systemd-fsck[395]: /dev/mmcblk0p1: 380 files, 33678/46774 clusters May 04 01:00:42 musikkiste systemd[1]: Started File System Check on /dev/disk/by-uuid/E28E-B7B1. May 04 01:00:42 musikkiste kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 May 04 01:00:42 musikkiste systemd-udevd[341]: Using default interface naming scheme 'v240'. May 04 01:00:42 musikkiste systemd-udevd[341]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 04 01:00:43 musikkiste kernel: wm8804 1-003b: revision E May 04 01:00:43 musikkiste systemd[1]: Starting Show Plymouth Boot Screen... May 04 01:00:43 musikkiste systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 04 01:00:43 musikkiste systemd[1]: Mounting /boot... May 04 01:00:43 musikkiste systemd[1]: Started File System Check Daemon to report status. May 04 01:00:43 musikkiste systemd[1]: Mounted /boot. May 04 01:00:43 musikkiste systemd[1]: plymouth-start.service: Succeeded. May 04 01:00:43 musikkiste systemd[1]: Started Show Plymouth Boot Screen. May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 04 01:00:43 musikkiste systemd[1]: Reached target Local File Systems. May 04 01:00:43 musikkiste systemd[1]: Starting Preprocess NFS configuration... May 04 01:00:43 musikkiste systemd[1]: Starting Raise network interfaces... May 04 01:00:43 musikkiste systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 04 01:00:43 musikkiste systemd[1]: Starting Create Volatile Files and Directories... May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 04 01:00:43 musikkiste systemd[1]: Starting Load/Save RF Kill Switch Status... May 04 01:00:43 musikkiste systemd[1]: Started Load/Save RF Kill Switch Status. May 04 01:00:43 musikkiste systemd[1]: nfs-config.service: Succeeded. May 04 01:00:43 musikkiste systemd[1]: Started Preprocess NFS configuration. May 04 01:00:43 musikkiste systemd[1]: plymouth-read-write.service: Succeeded. May 04 01:00:43 musikkiste systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 04 01:00:43 musikkiste systemd[1]: Started Create Volatile Files and Directories. May 04 01:00:43 musikkiste systemd[1]: Received SIGRTMIN+20 from PID 184 (plymouthd). May 04 01:00:43 musikkiste systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 04 01:00:43 musikkiste systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 04 01:00:43 musikkiste systemd[1]: Starting RPC bind portmap service... May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 04 01:00:43 musikkiste systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 04 01:00:43 musikkiste systemd[1]: Reached target NFS client services. May 04 01:00:43 musikkiste systemd[1]: Started Update UTMP about System Boot/Shutdown. May 04 01:00:43 musikkiste systemd[1]: Reached target System Initialization. May 04 01:00:43 musikkiste systemd[1]: Started Daily apt download activities. May 04 01:00:44 musikkiste systemd[1]: Started Daily man-db regeneration. May 04 01:00:44 musikkiste systemd[1]: Listening on triggerhappy.socket. May 04 01:00:44 musikkiste systemd[1]: Listening on lircd.socket. May 04 01:00:44 musikkiste systemd[1]: Started Daily Cleanup of Temporary Directories. May 04 01:00:44 musikkiste systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 04 01:00:44 musikkiste systemd[1]: Listening on D-Bus System Message Bus Socket. May 04 01:00:44 musikkiste systemd[1]: Reached target Sockets. May 04 01:00:44 musikkiste systemd[1]: Reached target Basic System. May 04 01:00:44 musikkiste systemd[1]: Started Volumio Iptables Module. May 04 01:00:44 musikkiste systemd[1]: Started UPnP Renderer front-end to MPD. May 04 01:00:44 musikkiste systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 04 01:00:44 musikkiste systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 04 01:00:44 musikkiste systemd[1]: Starting Play shutdown sound when system goes down.... May 04 01:00:44 musikkiste systemd[1]: Started D-Bus System Message Bus. May 04 01:00:44 musikkiste systemd[1]: Starting triggerhappy global hotkey daemon... May 04 01:00:44 musikkiste systemd[1]: Started volumio-remote-updater.service. May 04 01:00:44 musikkiste systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 04 01:00:44 musikkiste systemd[1]: Starting dhcpcd on all interfaces... May 04 01:00:44 musikkiste systemd[1]: Started Volumio Log Rotation Service. May 04 01:00:44 musikkiste systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. May 04 01:00:44 musikkiste systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 04 01:00:44 musikkiste systemd[1]: Started Manage Sound Card State (restore and store). May 04 01:00:44 musikkiste systemd[1]: Starting Save/Restore Sound Card State... May 04 01:00:44 musikkiste alsactl[553]: alsactl 1.1.8 daemon started May 04 01:00:44 musikkiste systemd[1]: Starting WPA supplicant... May 04 01:00:44 musikkiste systemd[1]: Started Daily apt upgrade and clean activities. May 04 01:00:44 musikkiste systemd[1]: Reached target Timers. May 04 01:00:44 musikkiste thd[548]: Unable to parse trigger line: May 04 01:00:44 musikkiste thd[548]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 04 01:00:44 musikkiste thd[548]: Unable to parse trigger line: May 04 01:00:44 musikkiste thd[548]: Found socket passed from systemd May 04 01:00:44 musikkiste systemd[1]: Starting Wireless Services... May 04 01:00:44 musikkiste dhcpcd[550]: Not running dhcpcd because /etc/network/interfaces May 04 01:00:44 musikkiste dhcpcd[550]: defines some interfaces that will use a May 04 01:00:44 musikkiste dhcpcd[550]: DHCP client or static address May 04 01:00:44 musikkiste systemd[1]: Starting Login Service... May 04 01:00:44 musikkiste systemd[1]: Started RPC bind portmap service. May 04 01:00:44 musikkiste systemd[1]: Started triggerhappy global hotkey daemon. May 04 01:00:44 musikkiste systemd[1]: Started Play shutdown sound when system goes down.. May 04 01:00:44 musikkiste systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED May 04 01:00:44 musikkiste systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 04 01:00:44 musikkiste systemd[1]: Failed to start dhcpcd on all interfaces. May 04 01:00:44 musikkiste avahi-daemon[552]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). May 04 01:00:44 musikkiste avahi-daemon[552]: Successfully dropped root privileges. May 04 01:00:44 musikkiste avahi-daemon[552]: avahi-daemon 0.7 starting up. May 04 01:00:44 musikkiste systemd-logind[558]: New seat seat0. May 04 01:00:45 musikkiste systemd[1]: Found device /sys/subsystem/net/devices/wlan0. May 04 01:00:45 musikkiste systemd[1]: Started ifup for wlan0. May 04 01:00:45 musikkiste systemd[1]: Reached target Remote File Systems (Pre). May 04 01:00:45 musikkiste systemd[1]: Reached target Remote File Systems. May 04 01:00:45 musikkiste systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 04 01:00:45 musikkiste systemd[1]: Reached target RPC Port Mapper. May 04 01:00:45 musikkiste kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 04 01:00:45 musikkiste systemd[1]: Started Login Service. May 04 01:00:45 musikkiste systemd[1]: Started Save/Restore Sound Card State. May 04 01:00:45 musikkiste avahi-daemon[552]: Successfully called chroot(). May 04 01:00:45 musikkiste avahi-daemon[552]: Successfully dropped remaining capabilities. May 04 01:00:45 musikkiste wpa_supplicant[555]: Successfully initialized wpa_supplicant May 04 01:00:45 musikkiste avahi-daemon[552]: Loading service file /services/volumio.service. May 04 01:00:45 musikkiste avahi-daemon[552]: Network interface enumeration completed. May 04 01:00:45 musikkiste avahi-daemon[552]: Server startup complete. Host name is musikkiste.local. Local service cookie is 826829007. May 04 01:00:45 musikkiste avahi-daemon[552]: Service "Musikkiste" (/services/volumio.service) successfully established. May 04 01:00:45 musikkiste volumio-remote-updater[549]: Error: No active session May 04 01:00:45 musikkiste systemd[1]: Started Raise network interfaces. May 04 01:00:45 musikkiste sh[576]: wlan0=wlan0 May 04 01:00:45 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:45] [info] asio async_connect error: system:111 (Connection refused) May 04 01:00:45 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 04 01:00:45 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:45] [error] handle_connect error: Underlying Transport Error May 04 01:00:46 musikkiste systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 04 01:00:46 musikkiste volumio[545]: Could not open config: /tmp/upmpdcli.conf May 04 01:00:46 musikkiste systemd[1]: iptables.service: Succeeded. May 04 01:00:46 musikkiste systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 04 01:00:46 musikkiste systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 04 01:00:46 musikkiste systemd[1]: Started WPA supplicant. May 04 01:00:46 musikkiste systemd[1]: Reached target Network. May 04 01:00:46 musikkiste systemd[1]: Reached target Network is Online. May 04 01:00:46 musikkiste systemd[1]: Starting LSB: Brings up/down network automatically... May 04 01:00:46 musikkiste systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 04 01:00:46 musikkiste systemd[1]: Starting OpenBSD Secure Shell server... May 04 01:00:46 musikkiste systemd[1]: Started MFRC5222 RFID reader service. May 04 01:00:46 musikkiste systemd[1]: Starting Daily apt download activities... May 04 01:00:46 musikkiste systemd[1]: Starting Samba NMB Daemon... May 04 01:00:46 musikkiste systemd[1]: Started Trigger command with GPIO push button. May 04 01:00:46 musikkiste systemd[1]: Starting Network Time Service... May 04 01:00:46 musikkiste systemd[1]: Started Trigger command with GPIO push button. May 04 01:00:46 musikkiste systemd[1]: Starting /etc/rc.local Compatibility... May 04 01:00:46 musikkiste systemd[1]: Starting Permit User Sessions... May 04 01:00:46 musikkiste systemd[1]: Reached target Sound Card. May 04 01:00:46 musikkiste systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 04 01:00:46 musikkiste systemd[1]: Started /etc/rc.local Compatibility. May 04 01:00:46 musikkiste systemd[1]: Started Permit User Sessions. May 04 01:00:46 musikkiste loadcpufreq[577]: Loading cpufreq kernel modules...done (none). May 04 01:00:46 musikkiste systemd[1]: Starting Hold until boot process finishes up... May 04 01:00:46 musikkiste systemd[1]: Starting Terminate Plymouth Boot Screen... May 04 01:00:46 musikkiste systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 04 01:00:46 musikkiste systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 04 01:00:46 musikkiste systemd[1]: Received SIGRTMIN+21 from PID 184 (plymouthd). May 04 01:00:47 musikkiste systemd[1]: Received SIGRTMIN+21 from PID 184 (plymouthd). May 04 01:00:47 musikkiste ifplugd[649]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon May 04 01:00:47 musikkiste ifplugd[649]: skip eth0...done. May 04 01:00:47 musikkiste systemd[1]: Started LSB: Brings up/down network automatically. May 04 01:00:47 musikkiste systemd[1]: plymouth-quit-wait.service: Succeeded. May 04 01:00:47 musikkiste systemd[1]: Started Hold until boot process finishes up. May 04 01:00:47 musikkiste systemd[1]: plymouth-quit.service: Succeeded. May 04 01:00:47 musikkiste systemd[1]: Started Terminate Plymouth Boot Screen. May 04 01:00:47 musikkiste systemd[1]: Started Serial Getty on ttyAMA0. May 04 01:00:47 musikkiste systemd[1]: Started Getty on tty1. May 04 01:00:47 musikkiste systemd[1]: Reached target Login Prompts. May 04 01:00:47 musikkiste cpufrequtils[703]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 04 01:00:47 musikkiste systemd[1]: Started LSB: set CPUFreq kernel parameters. May 04 01:00:47 musikkiste ntpd[665]: ntpd 4.2.8p12@1.3728-o (1): Starting May 04 01:00:47 musikkiste ntpd[665]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 May 04 01:00:47 musikkiste ntpd[727]: proto: precision = 0.782 usec (-20) May 04 01:00:47 musikkiste systemd[1]: Started Network Time Service. May 04 01:00:47 musikkiste haveged[510]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K May 04 01:00:47 musikkiste haveged[510]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 May 04 01:00:47 musikkiste haveged[510]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99876 May 04 01:00:47 musikkiste haveged[510]: haveged: fills: 0, generated: 0 May 04 01:00:47 musikkiste sshd[726]: Server listening on 0.0.0.0 port 22. May 04 01:00:47 musikkiste sshd[726]: Server listening on :: port 22. May 04 01:00:47 musikkiste systemd[1]: Started OpenBSD Secure Shell server. May 04 01:00:47 musikkiste ntpd[727]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 04 01:00:47 musikkiste ntpd[727]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 04 01:00:47 musikkiste ntpd[727]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 127 days ago May 04 01:00:47 musikkiste ntpd[727]: Listen and drop on 0 v6wildcard [::]:123 May 04 01:00:47 musikkiste ntpd[727]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 04 01:00:47 musikkiste ntpd[727]: Listen normally on 2 lo 127.0.0.1:123 May 04 01:00:47 musikkiste ntpd[727]: Listening on routing socket on fd #19 for interface updates May 04 01:00:47 musikkiste ntpd[727]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 04 01:00:47 musikkiste ntpd[727]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 04 01:00:48 musikkiste ntpd[727]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 04 01:00:48 musikkiste systemd[1]: systemd-rfkill.service: Succeeded. May 04 01:00:49 musikkiste ntpd[727]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 04 01:00:49 musikkiste nmbd[676]: [2025/05/04 01:00:49.623393, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 04 01:00:49 musikkiste nmbd[676]: started asyncdns process 750 May 04 01:00:49 musikkiste nmbd[676]: [2025/05/04 01:00:49.627145, 0] ../lib/util/become_daemon.c:149(daemon_status) May 04 01:00:49 musikkiste nmbd[676]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 04 01:00:49 musikkiste nmbd[676]: [2025/05/04 01:00:49.627402, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 04 01:00:49 musikkiste nmbd[676]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 04 01:00:50 musikkiste wireless.js[557]: Cleaning previous... May 04 01:00:50 musikkiste ntpd[727]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 04 01:00:50 musikkiste sudo[774]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 04 01:00:50 musikkiste sudo[774]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:50 musikkiste sudo[774]: pam_unix(sudo:session): session closed for user root May 04 01:00:50 musikkiste sudo[776]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 04 01:00:50 musikkiste sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:50 musikkiste MFRC522-trigger.py[655]: 2025-05-04 01:00:50,835 WARNING Your config is invalid: data.volumio must contain only specified properties! May 04 01:00:50 musikkiste MFRC522-trigger.py[655]: 2025-05-04 01:00:50,837 WARNING Please update your configuration file according to https://github.com/tal33/MFRC522-trigger#json-schema. May 04 01:00:51 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:51] [info] asio async_connect error: system:111 (Connection refused) May 04 01:00:51 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:51] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 04 01:00:51 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:51] [error] handle_connect error: Underlying Transport Error May 04 01:00:51 musikkiste sudo[776]: pam_unix(sudo:session): session closed for user root May 04 01:00:51 musikkiste wireless.js[557]: Stopped aP May 04 01:00:51 musikkiste sudo[783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 04 01:00:51 musikkiste sudo[783]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:51 musikkiste kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 04 01:00:51 musikkiste sudo[783]: pam_unix(sudo:session): session closed for user root May 04 01:00:51 musikkiste sudo[785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 04 01:00:51 musikkiste sudo[785]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:51 musikkiste sudo[785]: pam_unix(sudo:session): session closed for user root May 04 01:00:51 musikkiste sudo[792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 04 01:00:51 musikkiste sudo[792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:51 musikkiste sudo[792]: pam_unix(sudo:session): session closed for user root May 04 01:00:51 musikkiste sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 04 01:00:51 musikkiste sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:51 musikkiste ntpd[727]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 04 01:00:51 musikkiste sudo[794]: pam_unix(sudo:session): session closed for user root May 04 01:00:51 musikkiste wireless.js[557]: SETTING APPROPRIATE REG DOMAIN: DE May 04 01:00:51 musikkiste sudo[825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 04 01:00:51 musikkiste sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:51 musikkiste sudo[825]: pam_unix(sudo:session): session closed for user root May 04 01:00:52 musikkiste sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE May 04 01:00:52 musikkiste sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:52 musikkiste systemd[1]: apt-daily.service: Succeeded. May 04 01:00:52 musikkiste systemd[1]: Started Daily apt download activities. May 04 01:00:52 musikkiste sudo[829]: pam_unix(sudo:session): session closed for user root May 04 01:00:52 musikkiste wireless.js[557]: SUCCESSFULLY SET NEW REGDOMAIN: DE May 04 01:00:52 musikkiste wireless.js[557]: Start wireless flow May 04 01:00:52 musikkiste wireless.js[557]: Stopped hotspot (if there).. May 04 01:00:52 musikkiste sudo[835]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 04 01:00:52 musikkiste sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:52 musikkiste sudo[835]: pam_unix(sudo:session): session closed for user root May 04 01:00:52 musikkiste sudo[837]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 04 01:00:52 musikkiste sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:52 musikkiste sudo[837]: pam_unix(sudo:session): session closed for user root May 04 01:00:52 musikkiste wireless.js[557]: DHCP IP May 04 01:00:52 musikkiste wireless.js[557]: Start ap May 04 01:00:52 musikkiste wpa_supplicant[839]: Successfully initialized wpa_supplicant May 04 01:00:52 musikkiste sudo[840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 04 01:00:52 musikkiste sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:52 musikkiste kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 04 01:00:52 musikkiste dhcpcd[841]: dev: loaded udev May 04 01:00:52 musikkiste kernel: 8021q: 802.1Q VLAN Support v1.8 May 04 01:00:52 musikkiste dhcpcd[841]: wlan0: connected to Access Point `' May 04 01:00:52 musikkiste dhcpcd[841]: no interfaces have a carrier May 04 01:00:52 musikkiste dhcpcd[841]: forked to background, child pid 865 May 04 01:00:52 musikkiste dhcpcd[865]: wlan0: waiting for carrier May 04 01:00:52 musikkiste sudo[840]: pam_unix(sudo:session): session closed for user root May 04 01:00:53 musikkiste wpa_supplicant[851]: wlan0: Trying to associate with SSID 'WALL-E' May 04 01:00:53 musikkiste wireless.js[557]: trying... May 04 01:00:53 musikkiste sudo[867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:00:53 musikkiste sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:53 musikkiste sudo[867]: pam_unix(sudo:session): session closed for user root May 04 01:00:54 musikkiste wpa_supplicant[851]: wlan0: Associated with 84:90:0a:01:01:0e May 04 01:00:54 musikkiste wpa_supplicant[851]: wlan0: CTRL-EVENT-CONNECTED - Connection to 84:90:0a:01:01:0e completed [id=0 id_str=] May 04 01:00:54 musikkiste dhcpcd[865]: wlan0: carrier acquired May 04 01:00:54 musikkiste wpa_supplicant[851]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 04 01:00:54 musikkiste dhcpcd[865]: wlan0: connected to Access Point `WALL-E' May 04 01:00:54 musikkiste wpa_supplicant[851]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE May 04 01:00:54 musikkiste dhcpcd[865]: DUID 00:01:00:01:29:4a:1c:6b:b8:27:eb:c5:a9:08 May 04 01:00:54 musikkiste dhcpcd[865]: wlan0: IAID 01:94:22:6d May 04 01:00:54 musikkiste dhcpcd[865]: wlan0: adding address fe80::6183:aac8:c5:b783 May 04 01:00:54 musikkiste dhcpcd[865]: ipv6_addaddr1: Permission denied May 04 01:00:54 musikkiste wireless.js[557]: trying... May 04 01:00:54 musikkiste sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:00:54 musikkiste sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:54 musikkiste sudo[871]: pam_unix(sudo:session): session closed for user root May 04 01:00:55 musikkiste dhcpcd[865]: wlan0: soliciting an IPv6 router May 04 01:00:55 musikkiste dhcpcd[865]: wlan0: rebinding lease of 192.168.2.117 May 04 01:00:55 musikkiste wireless.js[557]: trying... May 04 01:00:55 musikkiste sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:00:55 musikkiste sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:55 musikkiste sudo[874]: pam_unix(sudo:session): session closed for user root May 04 01:00:56 musikkiste wireless.js[557]: trying... May 04 01:00:56 musikkiste sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:00:56 musikkiste sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:56 musikkiste sudo[877]: pam_unix(sudo:session): session closed for user root May 04 01:00:57 musikkiste wireless.js[557]: trying... May 04 01:00:57 musikkiste sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:00:57 musikkiste sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:57 musikkiste sudo[880]: pam_unix(sudo:session): session closed for user root May 04 01:00:58 musikkiste dhcpcd[865]: wlan0: probing address 192.168.2.117/24 May 04 01:00:58 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:58] [info] asio async_connect error: system:111 (Connection refused) May 04 01:00:58 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:58] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 04 01:00:58 musikkiste volumio-remote-updater[549]: [2025-05-04 01:00:58] [error] handle_connect error: Underlying Transport Error May 04 01:00:58 musikkiste wireless.js[557]: trying... May 04 01:00:58 musikkiste sudo[883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:00:58 musikkiste sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:58 musikkiste sudo[883]: pam_unix(sudo:session): session closed for user root May 04 01:00:59 musikkiste wireless.js[557]: trying... May 04 01:00:59 musikkiste sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:00:59 musikkiste sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:00:59 musikkiste sudo[886]: pam_unix(sudo:session): session closed for user root May 04 01:01:00 musikkiste wireless.js[557]: trying... May 04 01:01:00 musikkiste sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:01:00 musikkiste sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:00 musikkiste sudo[889]: pam_unix(sudo:session): session closed for user root May 04 01:01:01 musikkiste wireless.js[557]: trying... May 04 01:01:01 musikkiste sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:01:01 musikkiste sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:01 musikkiste sudo[892]: pam_unix(sudo:session): session closed for user root May 04 01:01:02 musikkiste wireless.js[557]: trying... May 04 01:01:02 musikkiste sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:01:02 musikkiste sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:02 musikkiste sudo[895]: pam_unix(sudo:session): session closed for user root May 04 01:01:03 musikkiste dhcpcd[865]: wlan0: leased 192.168.2.117 for 1814400 seconds May 04 01:01:03 musikkiste avahi-daemon[552]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.117. May 04 01:01:03 musikkiste avahi-daemon[552]: New relevant interface wlan0.IPv4 for mDNS. May 04 01:01:03 musikkiste dhcpcd[865]: wlan0: adding route to 192.168.2.0/24 May 04 01:01:03 musikkiste avahi-daemon[552]: Registering new address record for 192.168.2.117 on wlan0.IPv4. May 04 01:01:03 musikkiste dhcpcd[865]: wlan0: adding default route via 192.168.2.1 May 04 01:01:03 musikkiste wireless.js[557]: trying... May 04 01:01:03 musikkiste sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 04 01:01:03 musikkiste sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:03 musikkiste sudo[919]: pam_unix(sudo:session): session closed for user root May 04 01:01:03 musikkiste wireless.js[557]: Connected to: ----WALL-E May 04 01:01:03 musikkiste wireless.js[557]: ---- May 04 01:01:03 musikkiste sudo[922]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 01:01:03 musikkiste sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:03 musikkiste sudo[922]: pam_unix(sudo:session): session closed for user root May 04 01:01:03 musikkiste wireless.js[557]: ... joined AP, wlan0 IPv4 is 192.168.2.117, ipV6 is undefined May 04 01:01:03 musikkiste wireless.js[557]: It's done! AP May 04 01:01:04 musikkiste systemd[1]: Started Wireless Services. May 04 01:01:04 musikkiste kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 04 01:01:04 musikkiste systemd[1]: Started Volumio Backend Module. May 04 01:01:04 musikkiste systemd[1]: Started Volumio Cpu Tweaker. May 04 01:01:04 musikkiste volumio-cpu-tweak[928]: Setting RT Priority for mpd May 04 01:01:04 musikkiste volumio-cpu-tweak[928]: pid 35's current scheduling policy: SCHED_OTHER May 04 01:01:04 musikkiste volumio-cpu-tweak[928]: pid 35's current scheduling priority: 0 May 04 01:01:04 musikkiste volumio-cpu-tweak[928]: Setting MPD Affinity May 04 01:01:04 musikkiste volumio-cpu-tweak[928]: pid 3's current affinity mask: f May 04 01:01:04 musikkiste volumio-cpu-tweak[928]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 04 01:01:04 musikkiste volumio-cpu-tweak[928]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 04 01:01:04 musikkiste systemd[1]: volumio_cpu_tweak.service: Succeeded. May 04 01:01:04 musikkiste nmbd[676]: [2025/05/04 01:01:04.633540, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 04 01:01:04 musikkiste systemd[1]: Started Samba NMB Daemon. May 04 01:01:04 musikkiste nmbd[676]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 04 01:01:04 musikkiste systemd[1]: Starting Samba Winbind Daemon... May 04 01:01:04 musikkiste nmbd[676]: [2025/05/04 01:01:04.689145, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 04 01:01:04 musikkiste nmbd[676]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.117 for name WORKGROUP<1d>. May 04 01:01:04 musikkiste nmbd[676]: This response was from IP 192.168.2.10, reporting an IP address of 192.168.2.10. May 04 01:01:05 musikkiste winbindd[944]: [2025/05/04 01:01:05.113096, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 04 01:01:05 musikkiste winbindd[944]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 04 01:01:05 musikkiste ntpd[727]: Listen normally on 3 wlan0 192.168.2.117:123 May 04 01:01:05 musikkiste ntpd[727]: new interface(s) found: waking up resolver May 04 01:01:05 musikkiste winbindd[944]: [2025/05/04 01:01:05.647953, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 04 01:01:05 musikkiste winbindd[944]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 04 01:01:05 musikkiste systemd[1]: Started Samba Winbind Daemon. May 04 01:01:05 musikkiste systemd[1]: Starting Samba SMB Daemon... May 04 01:01:06 musikkiste systemd[1]: Started Samba SMB Daemon. May 04 01:01:06 musikkiste systemd[1]: Reached target Multi-User System. May 04 01:01:06 musikkiste smbd[949]: [2025/05/04 01:01:06.871394, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 04 01:01:06 musikkiste systemd[1]: Reached target Graphical Interface. May 04 01:01:06 musikkiste smbd[949]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 04 01:01:06 musikkiste systemd[1]: Starting Update UTMP about System Runlevel Changes... May 04 01:01:06 musikkiste systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 04 01:01:06 musikkiste systemd[1]: Started Update UTMP about System Runlevel Changes. May 04 01:01:06 musikkiste systemd[1]: Startup finished in 20.484s (kernel) + 29.388s (userspace) = 49.872s. May 04 01:01:08 musikkiste volumio[927]: info: ------------------------------------------- May 04 01:01:08 musikkiste volumio[927]: info: ----- Volumio3 ---- May 04 01:01:08 musikkiste volumio[927]: info: ------------------------------------------- May 04 01:01:08 musikkiste volumio[927]: info: ----- System startup ---- May 04 01:01:08 musikkiste volumio[927]: info: ------------------------------------------- May 04 01:01:08 musikkiste volumio-remote-updater[549]: [2025-05-04 01:01:08] [connect] Successful connection May 04 01:01:09 musikkiste volumio[927]: info: MYVOLUMIO Environment detected May 04 01:01:10 musikkiste volumio[927]: info: Plugin folders cleanup May 04 01:01:10 musikkiste volumio[927]: info: Scanning into folder /volumio/app/plugins/ May 04 01:01:10 musikkiste volumio[927]: info: Scanning category audio_interface May 04 01:01:10 musikkiste volumio[927]: info: Scanning category miscellanea May 04 01:01:10 musikkiste volumio[927]: info: Scanning category music_service May 04 01:01:10 musikkiste volumio[927]: info: Scanning category plugins.json May 04 01:01:10 musikkiste volumio[927]: info: Scanning category system_controller May 04 01:01:10 musikkiste volumio[927]: info: Scanning category user_interface May 04 01:01:10 musikkiste volumio[927]: info: Scanning into folder /data/plugins/ May 04 01:01:10 musikkiste volumio[927]: info: Scanning category music_service May 04 01:01:10 musikkiste volumio[927]: info: Scanning category system_hardware May 04 01:01:10 musikkiste volumio[927]: info: Plugin folders cleanup completed May 04 01:01:10 musikkiste volumio[927]: info: ------------------------------------------- May 04 01:01:10 musikkiste volumio[927]: info: ----- Core plugins startup ---- May 04 01:01:10 musikkiste volumio[927]: info: ------------------------------------------- May 04 01:01:10 musikkiste volumio[927]: info: Loading plugins from folder /volumio/app/plugins/ May 04 01:01:10 musikkiste volumio[927]: info: Adding plugin upnp to MyMusic Plugins May 04 01:01:10 musikkiste volumio[927]: info: Adding plugin airplay_emulation to MyMusic Plugins May 04 01:01:10 musikkiste volumio[927]: info: Adding plugin upnp_browser to MyMusic Plugins May 04 01:01:10 musikkiste volumio[927]: info: Loading plugins from folder /data/plugins/ May 04 01:01:10 musikkiste volumio[927]: info: Loading plugin "system"... May 04 01:01:10 musikkiste volumio[927]: info: Loading plugin "appearance"... May 04 01:01:13 musikkiste systemd[1]: systemd-fsckd.service: Succeeded. May 04 01:01:18 musikkiste volumio[927]: info: Loading plugin "network"... May 04 01:01:18 musikkiste volumio[927]: info: Refreshing Cached IP Addresses May 04 01:01:18 musikkiste volumio[927]: info: Loading plugin "services"... May 04 01:01:18 musikkiste volumio[927]: info: Loading plugin "alsa_controller"... May 04 01:01:18 musikkiste sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 01:01:18 musikkiste sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 01:01:18 musikkiste sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 04 01:01:18 musikkiste sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:18 musikkiste sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:18 musikkiste sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:18 musikkiste sudo[974]: pam_unix(sudo:session): session closed for user root May 04 01:01:18 musikkiste sudo[972]: pam_unix(sudo:session): session closed for user root May 04 01:01:18 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 01:01:18 musikkiste volumio[927]: info: Loading plugin "wizard"... May 04 01:01:18 musikkiste volumio[927]: info: Loading plugin "networkfs"... May 04 01:01:18 musikkiste volumio[927]: info: Starting Udev Watcher for removable devices May 04 01:01:18 musikkiste sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=Primel37,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.10/share_usb/Eichenmusik_volumio /mnt/NAS/Eichenmusik_Raspbee3 May 04 01:01:18 musikkiste sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:18 musikkiste volumio[927]: info: Ignoring mount for partition: boot May 04 01:01:18 musikkiste volumio[927]: info: Ignoring mount for partition: volumio May 04 01:01:18 musikkiste volumio[927]: info: Ignoring mount for partition: volumio_data May 04 01:01:18 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 01:01:18 musikkiste volumio[927]: info: Loading plugin "volumio_command_line_client"... May 04 01:01:18 musikkiste volumio[927]: info: Plugin upnp is not enabled May 04 01:01:18 musikkiste volumio[927]: info: Loading plugin "my_music"... May 04 01:01:18 musikkiste volumio[927]: info: Loading plugin "mpd"... May 04 01:01:18 musikkiste kernel: Key type cifs.spnego registered May 04 01:01:18 musikkiste kernel: Key type cifs.idmap registered May 04 01:01:18 musikkiste 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 04 01:01:18 musikkiste kernel: CIFS: Attempting to mount \\192.168.2.10\share_usb May 04 01:01:19 musikkiste sudo[976]: pam_unix(sudo:session): session closed for user root May 04 01:01:19 musikkiste volumio[927]: info: Loading plugin "upnp_browser"... May 04 01:01:19 musikkiste sudo[985]: pam_unix(sudo:session): session closed for user root May 04 01:01:19 musikkiste volumio[927]: info: Loading plugin "alarm-clock"... May 04 01:01:20 musikkiste volumio[927]: info: Plugin airplay_emulation is not enabled May 04 01:01:20 musikkiste volumio[927]: info: Loading plugin "last_100"... May 04 01:01:20 musikkiste volumio[927]: info: Loading plugin "webradio"... May 04 01:01:20 musikkiste volumio[927]: info: Loading plugin "i2s_dacs"... May 04 01:01:20 musikkiste volumio[927]: info: Loading plugin "volumiodiscovery"... May 04 01:01:20 musikkiste volumio[927]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 01:01:20 musikkiste node[927]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 01:01:20 musikkiste volumio[927]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 01:01:20 musikkiste node[927]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 01:01:20 musikkiste volumio[927]: *** WARNING *** For more information see May 04 01:01:20 musikkiste node[927]: *** WARNING *** For more information see May 04 01:01:20 musikkiste volumio[927]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 01:01:20 musikkiste node[927]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 01:01:20 musikkiste volumio[927]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 01:01:20 musikkiste node[927]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 01:01:20 musikkiste volumio[927]: *** WARNING *** For more information see May 04 01:01:20 musikkiste node[927]: *** WARNING *** For more information see May 04 01:01:20 musikkiste volumio[927]: info: Applying required configuration parameters for plugin volumiodiscovery May 04 01:01:20 musikkiste volumio[927]: info: Discovery: Started advertising with name: Musikkiste May 04 01:01:20 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 01:01:20 musikkiste volumio[927]: info: Loading plugin "outputs"... May 04 01:01:20 musikkiste volumio[927]: info: Loading plugin "albumart"... May 04 01:01:20 musikkiste volumio[927]: info: Plugin example_plugin is not enabled May 04 01:01:20 musikkiste volumio[927]: info: Loading plugin "inputs"... May 04 01:01:20 musikkiste volumio[927]: info: Loading plugin "updater_comm"... May 04 01:01:21 musikkiste volumio[927]: info: Plugin mpdemulation is not enabled May 04 01:01:21 musikkiste volumio[927]: info: Loading plugin "rest_api"... May 04 01:01:21 musikkiste volumio[927]: info: Loading plugin "websocket"... May 04 01:01:21 musikkiste volumio[927]: info: Starting Socket.io Server version 2.3.0 May 04 01:01:21 musikkiste volumio[927]: info: Loading plugin "podcast"... May 04 01:01:21 musikkiste volumio[927]: Forking 3 albumart workers May 04 01:01:21 musikkiste volumio[927]: info: ControllerPodcast::constructor May 04 01:01:22 musikkiste volumio[927]: info: Loading plugin "ir_controller"... May 04 01:01:22 musikkiste volumio[927]: info: Applying required configuration parameters for plugin ir_controller May 04 01:01:22 musikkiste volumio[927]: Starting albumart workers May 04 01:01:22 musikkiste volumio[927]: Starting albumart workers May 04 01:01:22 musikkiste volumio[927]: Starting albumart workers May 04 01:01:23 musikkiste volumio-remote-updater[549]: [2025-05-04 01:01:23] [connect] Successful connection May 04 01:01:24 musikkiste volumio[927]: info: Loading i18n strings for locale de May 04 01:01:24 musikkiste volumio[927]: Updating browse sources language May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::initPlayerControls May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 01:01:24 musikkiste volumio[927]: Express server listening on port 3000 May 04 01:01:24 musikkiste volumio[927]: [Metrics] WebUI: 17s 297.46ms May 04 01:01:24 musikkiste volumio[927]: info: CoreStateMachine::resetVolumioState May 04 01:01:24 musikkiste volumio[927]: info: CoreStateMachine::getcurrentVolume May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::volumioRetrievevolume May 04 01:01:24 musikkiste volumio[927]: info: CoreStateMachine::pushState May 04 01:01:24 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:01:24 musikkiste volumio[927]: info: CoreCommandRouter::volumioPushState May 04 01:01:25 musikkiste volumio-remote-updater[549]: [2025-05-04 01:01:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746313283 101 May 04 01:01:25 musikkiste volumio[927]: 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: 1 May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioGetState May 04 01:01:25 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioGetState May 04 01:01:25 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioGetState May 04 01:01:25 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioGetState May 04 01:01:25 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 01:01:25 musikkiste volumio[927]: info: Reloading queue from file May 04 01:01:25 musikkiste volumio[927]: info: CoreStateMachine::setRepeat null single undefined May 04 01:01:25 musikkiste volumio[927]: info: CoreStateMachine::pushState May 04 01:01:25 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioPushState May 04 01:01:25 musikkiste volumio[927]: info: CoreStateMachine::setRandom false May 04 01:01:25 musikkiste volumio[927]: info: CoreStateMachine::pushState May 04 01:01:25 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioPushState May 04 01:01:25 musikkiste volumio[927]: info: Setting Device type: Raspberry PI May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioGetState May 04 01:01:25 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:25 musikkiste volumio[927]: info: Completed loading Core Plugins May 04 01:01:25 musikkiste volumio[927]: info: Preparing to generate the ALSA configuration file May 04 01:01:25 musikkiste volumio[927]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 04 01:01:25 musikkiste volumio[927]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 May 04 01:01:25 musikkiste volumio[927]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 04 01:01:25 musikkiste volumio[927]: info: Asound.conf file unchanged, so no further update is needed May 04 01:01:25 musikkiste volumio[927]: info: Output device has changed, restarting MPD May 04 01:01:25 musikkiste volumio[927]: info: ___________ START PLUGINS ___________ May 04 01:01:25 musikkiste sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 01:01:25 musikkiste volumio[927]: info: ControllerMpd::onStart: Initializing MPD May 04 01:01:25 musikkiste volumio[927]: info: Creating MPD Configuration file May 04 01:01:25 musikkiste sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:25 musikkiste sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 01:01:25 musikkiste sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:25 musikkiste sudo[1079]: pam_unix(sudo:session): session closed for user root May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 01:01:25 musikkiste volumio[927]: info: [1746313285422] CoreMusicLibrary::Adding element Medienserver May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 01:01:25 musikkiste sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 01:01:25 musikkiste sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:25 musikkiste sudo[1085]: pam_unix(sudo:session): session closed for user root May 04 01:01:25 musikkiste sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 01:01:25 musikkiste sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 01:01:25 musikkiste volumio[927]: info: [1746313285463] CoreMusicLibrary::Adding element Last_100 May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 01:01:25 musikkiste volumio[927]: info: [1746313285467] CoreMusicLibrary::Adding element Webradio May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 01:01:25 musikkiste volumio[927]: info: Initializing BBC Radios May 04 01:01:25 musikkiste systemd[1]: Listening on mpd.socket. May 04 01:01:25 musikkiste volumio[927]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 01:01:25 musikkiste systemd[1]: Starting Music Player Daemon... May 04 01:01:25 musikkiste systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 04 01:01:25 musikkiste systemd[1]: mpd.service: Succeeded. May 04 01:01:25 musikkiste systemd[1]: Stopped Music Player Daemon. May 04 01:01:25 musikkiste systemd[1]: Starting Music Player Daemon... May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 01:01:25 musikkiste volumio[927]: info: [1746313285582] CoreMusicLibrary::Adding element Podcast May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 01:01:25 musikkiste volumio[927]: Cannot find translation for source Podcast May 04 01:01:25 musikkiste volumio[927]: info: Loading i18n strings for locale de May 04 01:01:25 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 01:01:25 musikkiste volumio[927]: info: Volumio Calling Home May 04 01:01:25 musikkiste sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc May 04 01:01:25 musikkiste sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:25 musikkiste sudo[1106]: pam_unix(sudo:session): session closed for user root May 04 01:01:26 musikkiste volumio[927]: info: Discovery: adding 2fcc8dbb-90e7-4586-8781-ce14cded0bf4 May 04 01:01:26 musikkiste volumio[927]: info: Discovery: Found device Musikkiste May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::volumioGetState May 04 01:01:26 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:26 musikkiste volumio[927]: info: ir_controller: File permissions successfully set on /etc/lirc/*. May 04 01:01:26 musikkiste volumio[927]: info: MPD Permissions set May 04 01:01:26 musikkiste volumio[927]: info: MPD Permissions set May 04 01:01:26 musikkiste volumio[927]: 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 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 01:01:26 musikkiste volumio[927]: info: CoreCommandRouter::volumioGetState May 04 01:01:26 musikkiste volumio[927]: info: CorePlayQueue::getTrack 0 May 04 01:01:26 musikkiste volumio[927]: info: ir_controller: LIRC correctly updated. May 04 01:01:26 musikkiste volumio[927]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! May 04 01:01:26 musikkiste volumio[927]: info: Volumio called home May 04 01:01:26 musikkiste volumio[927]: info: ir_controller: Raspberry Pi revision code: 902120 May 04 01:01:27 musikkiste sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 May 04 01:01:27 musikkiste sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:27 musikkiste sudo[1124]: pam_unix(sudo:session): session closed for user root May 04 01:01:27 musikkiste sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=6 gpio_pull=up May 04 01:01:27 musikkiste sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:27 musikkiste kernel: Registered IR keymap rc-rc6-mce May 04 01:01:27 musikkiste sudo[1132]: pam_unix(sudo:session): session closed for user root May 04 01:01:27 musikkiste volumio[927]: info: ir_controller: Overlay gpio-ir gpio_pin=6 gpio_pull=up loaded. May 04 01:01:27 musikkiste kernel: IR RC6 protocol handler initialized May 04 01:01:27 musikkiste sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service May 04 01:01:27 musikkiste sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:27 musikkiste kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@6/rc/rc0 May 04 01:01:27 musikkiste kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter May 04 01:01:27 musikkiste kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@6/rc/rc0/input0 May 04 01:01:27 musikkiste systemd[1]: Starting lircd(8) initialization helper tool... May 04 01:01:27 musikkiste systemd-udevd[1139]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 04 01:01:27 musikkiste systemd-logind[558]: Watching system buttons on /dev/input/event0 (gpio_ir_recv) May 04 01:01:27 musikkiste sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service May 04 01:01:27 musikkiste sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:31 musikkiste lircd-setup[1148]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 04 01:01:31 musikkiste lircd-setup[1148]: parser = configparser.SafeConfigParser() May 04 01:01:31 musikkiste kernel: rc rc0: two consecutive events of type space May 04 01:01:31 musikkiste systemd[1]: lircd-setup.service: Succeeded. May 04 01:01:31 musikkiste systemd[1]: Started lircd(8) initialization helper tool. May 04 01:01:31 musikkiste systemd[1]: Starting Flexible IR remote input/output application support... May 04 01:01:31 musikkiste lircd-0.10.1[1161]: Info: lircd: Opening log, level: Info May 04 01:01:31 musikkiste lircd-0.10.1[1161]: Notice: Version: lircd 0.10.1 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: System info: Linux musikkiste 6.1.77-v7+ #1730 SMP Thu Feb 8 15:17:51 GMT 2024 armv7l GNU/Linux May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Info: Initial device: /dev/lirc0 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Info: Initial device: /dev/lirc0 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Info: [lirc] protocol is enabled May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: driver: default May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: output: /var/run/lirc/lircd May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: nodaemon: 1 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: logfile: syslog May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: immediate-init: 0 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: permission: 666 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: driver-options: May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: listen: 0 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: connect: (null) May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: userelease: 0 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: effective_user: (null) May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: release_suffix: _EVUP May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: allow_simulate: 0 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: repeat_max: 600 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: configfile: /etc/lirc/lircd.conf May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Options: dynamic_codes: (null) May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Current driver: default May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Driver API version: 3 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Driver version: 0.10.0 May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 04 01:01:32 musikkiste lircd[1161]: lircd-0.10.1[1161]: Info: lircd: Opening log, level: Info May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Info: lircd: Opening log, level: Info May 04 01:01:32 musikkiste lircd[1161]: lircd-0.10.1[1161]: Notice: Using systemd fd May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: Using systemd fd May 04 01:01:32 musikkiste lircd[1161]: lircd-0.10.1[1161]: Warning: Running as root May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Warning: Running as root May 04 01:01:32 musikkiste lircd[1161]: lircd-0.10.1[1161]: Info: Using remote: Roku_Soundbridge. May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Info: Using remote: Roku_Soundbridge. May 04 01:01:32 musikkiste lircd[1161]: lircd-0.10.1[1161]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 04 01:01:32 musikkiste systemd[1]: Started Flexible IR remote input/output application support. May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 04 01:01:32 musikkiste sudo[1151]: pam_unix(sudo:session): session closed for user root May 04 01:01:32 musikkiste sudo[1144]: pam_unix(sudo:session): session closed for user root May 04 01:01:32 musikkiste volumio[927]: info: ir_controller: systemctl restart lircd.service succeeded. May 04 01:01:32 musikkiste volumio[927]: info: ir_controller: systemctl restart lircd.service succeeded. May 04 01:01:32 musikkiste sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 04 01:01:32 musikkiste sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:32 musikkiste sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 04 01:01:32 musikkiste sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 01:01:32 musikkiste systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 04 01:01:32 musikkiste lircd[1161]: lircd-0.10.1[1161]: Notice: accepted new client on /var/run/lirc/lircd May 04 01:01:32 musikkiste lircd[1161]: lircd-0.10.1[1161]: Info: [lirc] protocol is enabled May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Notice: accepted new client on /var/run/lirc/lircd May 04 01:01:32 musikkiste lircd-0.10.1[1161]: Info: [lirc] protocol is enabled May 04 01:01:32 musikkiste sudo[1166]: pam_unix(sudo:session): session closed for user root May 04 01:01:32 musikkiste volumio[927]: info: ir_controller: systemctl restart irexec.service succeeded. May 04 01:01:32 musikkiste sudo[1168]: pam_unix(sudo:session): session closed for user root May 04 01:01:32 musikkiste volumio[927]: info: ir_controller: systemctl restart irexec.service succeeded. May 04 01:01:33 musikkiste volumio[927]: info: May 04 01:01:33 musikkiste volumio[927]: ---------------------------- Client requests Volumio Play Playlist Karte064 May 04 01:01:33 musikkiste volumio[927]: info: Play playlist Karte064 May 04 01:01:34 musikkiste volumio[927]: info: CoreCommandRouter::volumioClearQueue May 04 01:01:34 musikkiste volumio[927]: info: CoreStateMachine::ClearQueue May 04 01:01:34 musikkiste volumio[927]: info: CoreStateMachine::stop May 04 01:01:34 musikkiste volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 01:01:34 musikkiste volumio[927]: info: CorePlayQueue::clearPlayQueue May 04 01:01:34 musikkiste volumio[927]: info: CorePlayQueue::saveQueue May 04 01:01:34 musikkiste volumio[927]: info: CoreCommandRouter::volumioPushState May 04 01:01:34 musikkiste volumio[927]: info: CoreCommandRouter::volumioPushQueue May 04 01:01:34 musikkiste volumio[927]: info: CoreCommandRouter::volumioAddQueueItems May 04 01:01:34 musikkiste volumio[927]: info: CoreStateMachine::addQueueItems May 04 01:01:34 musikkiste volumio[927]: info: CorePlayQueue::addQueueItems May 04 01:01:34 musikkiste volumio[927]: info: Preload queue cleared May 04 01:01:34 musikkiste volumio[927]: info: Adding Item to queue: NAS/Eichenmusik_Raspbee3/Simon Oslender/Peace of Mind/01 - Healing.mp3 May 04 01:01:34 musikkiste volumio[927]: info: Exploding uri NAS/Eichenmusik_Raspbee3/Simon Oslender/Peace of Mind/01 - Healing.mp3 in service mpd May 04 01:01:34 musikkiste volumio[927]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 01:01:34 musikkiste volumio[927]: TypeError: Cannot read property 'then' of undefined May 04 01:01:34 musikkiste volumio[927]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) May 04 01:01:34 musikkiste volumio[927]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) May 04 01:01:34 musikkiste volumio[927]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1365:16) May 04 01:01:34 musikkiste volumio[927]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 04 01:01:34 musikkiste volumio[927]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 04 01:01:34 musikkiste volumio[927]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 04 01:01:34 musikkiste volumio[927]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) May 04 01:01:34 musikkiste volumio[927]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:447:28) May 04 01:01:34 musikkiste volumio[927]: at /volumio/app/playlistManager.js:726:30 May 04 01:01:34 musikkiste volumio[927]: at /volumio/node_modules/jsonfile/index.js:46:5 May 04 01:01:34 musikkiste volumio[927]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 May 04 01:01:34 musikkiste volumio[927]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) May 04 01:01:34 musikkiste volumio[927]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 01:01:35 musikkiste sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 01:00 May 04 01:01:35 musikkiste sudo[1181]: 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="ef589a0572b08c3d1e9a984ec6a655ef3698e415" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 28 May 2024 12:17:12 PM CEST" VOLUMIO_VERSION="3.695" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f37ff40efaaf3752fac55d6a95d61cb"