-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-05-05 14:51:43 CEST. -- May 05 14:50:52 volumiogaraz fake-hwclock[195]: Sun May 5 12:50:52 UTC 2024 May 05 14:50:52 volumiogaraz systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 05 14:50:52 volumiogaraz systemd[1]: Started Restore / save the current clock. May 05 14:50:52 volumiogaraz systemd[1]: Mounted Kernel Configuration File System. May 05 14:50:52 volumiogaraz systemd[1]: Started Apply Kernel Variables. May 05 14:50:52 volumiogaraz systemd[1]: Started Load/Save Random Seed. May 05 14:50:52 volumiogaraz systemd[1]: Started Create System Users. May 05 14:50:52 volumiogaraz systemd[1]: Starting Create Static Device Nodes in /dev... May 05 14:50:52 volumiogaraz systemd[1]: dynamicswap.service: Succeeded. May 05 14:50:52 volumiogaraz systemd[1]: Started Create Static Device Nodes in /dev. May 05 14:50:52 volumiogaraz systemd[1]: Reached target Local File Systems (Pre). May 05 14:50:52 volumiogaraz systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 05 14:50:52 volumiogaraz systemd[1]: Mounting /var/log... May 05 14:50:52 volumiogaraz systemd[1]: Mounting /var/spool/cups... May 05 14:50:52 volumiogaraz systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 05 14:50:52 volumiogaraz systemd[1]: Mounting /tmp... May 05 14:50:52 volumiogaraz systemd[1]: Starting udev Kernel Device Manager... May 05 14:50:52 volumiogaraz systemd[1]: Mounted /var/log. May 05 14:50:52 volumiogaraz systemd[1]: Mounted /var/spool/cups. May 05 14:50:52 volumiogaraz systemd[1]: Mounting /var/spool/cups/tmp... May 05 14:50:52 volumiogaraz systemd[1]: Starting Flush Journal to Persistent Storage... May 05 14:50:52 volumiogaraz systemd[1]: Mounted /tmp. May 05 14:50:52 volumiogaraz systemd-udevd[225]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 05 14:50:52 volumiogaraz systemd[1]: Mounted /var/spool/cups/tmp. May 05 14:50:52 volumiogaraz systemd[1]: Started udev Coldplug all Devices. May 05 14:50:52 volumiogaraz systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 05 14:50:52 volumiogaraz systemd-journald[191]: Runtime journal (/run/log/journal/32836d460d421189c56ead0a6628f62c) is 7.5M, max 30.0M, 22.5M free. May 05 14:50:52 volumiogaraz systemd[1]: Started Flush Journal to Persistent Storage. May 05 14:50:52 volumiogaraz systemd[1]: Started udev Kernel Device Manager. May 05 14:50:52 volumiogaraz systemd[1]: Starting Show Plymouth Boot Screen... May 05 14:50:52 volumiogaraz systemd[1]: Started Helper to synchronize boot up for ifupdown. May 05 14:50:52 volumiogaraz systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd). May 05 14:50:52 volumiogaraz systemd[1]: Started Show Plymouth Boot Screen. May 05 14:50:52 volumiogaraz systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 05 14:50:52 volumiogaraz systemd[1]: Reached target Local Encrypted Volumes. May 05 14:50:52 volumiogaraz systemd[1]: Reached target Paths. May 05 14:50:52 volumiogaraz systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 05 14:50:52 volumiogaraz kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:50:52 volumiogaraz kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 05 14:50:52 volumiogaraz kernel: [vc_sm_connected_init]: start May 05 14:50:52 volumiogaraz kernel: mc: Linux media interface: v0.10 May 05 14:50:52 volumiogaraz kernel: [vc_sm_connected_init]: installed successfully May 05 14:50:52 volumiogaraz kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 May 05 14:50:52 volumiogaraz kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem May 05 14:50:53 volumiogaraz kernel: videodev: Linux video capture interface: v2.00 May 05 14:50:53 volumiogaraz kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:50:53 volumiogaraz kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:50:53 volumiogaraz kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:50:53 volumiogaraz kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 05 14:50:53 volumiogaraz kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp May 05 14:50:53 volumiogaraz kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 05 14:50:53 volumiogaraz kernel: Bluetooth: Core ver 2.22 May 05 14:50:53 volumiogaraz kernel: NET: Registered PF_BLUETOOTH protocol family May 05 14:50:53 volumiogaraz kernel: Bluetooth: HCI device and connection manager initialized May 05 14:50:53 volumiogaraz kernel: Bluetooth: HCI socket layer initialized May 05 14:50:53 volumiogaraz kernel: Bluetooth: L2CAP socket layer initialized May 05 14:50:53 volumiogaraz kernel: Bluetooth: SCO socket layer initialized May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 May 05 14:50:53 volumiogaraz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image May 05 14:50:53 volumiogaraz kernel: Bluetooth: HCI UART driver ver 2.3 May 05 14:50:53 volumiogaraz kernel: Bluetooth: HCI UART protocol H4 registered May 05 14:50:53 volumiogaraz kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered May 05 14:50:53 volumiogaraz kernel: Bluetooth: HCI UART protocol Broadcom registered May 05 14:50:53 volumiogaraz kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator May 05 14:50:53 volumiogaraz kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator May 05 14:50:53 volumiogaraz kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:50:53 volumiogaraz kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored May 05 14:50:53 volumiogaraz kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 05 14:50:53 volumiogaraz kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 05 14:50:53 volumiogaraz kernel: uart-pl011 3f201000.serial: no DMA platform data May 05 14:50:53 volumiogaraz systemd[1]: Found device /dev/mmcblk0p1. May 05 14:50:53 volumiogaraz systemd-udevd[234]: Using default interface naming scheme 'v240'. May 05 14:50:53 volumiogaraz kernel: Bluetooth: hci0: BCM: chip id 107 May 05 14:50:53 volumiogaraz kernel: Bluetooth: hci0: BCM: features 0x2f May 05 14:50:53 volumiogaraz kernel: Bluetooth: hci0: BCM4345C0 May 05 14:50:53 volumiogaraz kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 May 05 14:50:53 volumiogaraz systemd-udevd[234]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 14:50:53 volumiogaraz kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch May 05 14:50:54 volumiogaraz kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 05 14:50:54 volumiogaraz systemd[1]: Starting File System Check on /dev/mmcblk0p1... May 05 14:50:54 volumiogaraz kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' May 05 14:50:54 volumiogaraz kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' May 05 14:50:54 volumiogaraz kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 05 14:50:54 volumiogaraz systemd-udevd[230]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 14:50:54 volumiogaraz kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 May 05 14:50:54 volumiogaraz kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 May 05 14:50:54 volumiogaraz kernel: usbcore: registered new interface driver brcmfmac May 05 14:50:54 volumiogaraz systemd-fsck[318]: fsck.fat 4.1 (2017-01-24) May 05 14:50:54 volumiogaraz systemd-fsck[318]: /dev/mmcblk0p1: 364 files, 33360/46774 clusters May 05 14:50:54 volumiogaraz systemd[1]: Started File System Check on /dev/mmcblk0p1. May 05 14:50:54 volumiogaraz kernel: Bluetooth: hci0: BCM: features 0x2f May 05 14:50:54 volumiogaraz kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ May 05 14:50:54 volumiogaraz kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 May 05 14:50:54 volumiogaraz kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) May 05 14:50:54 volumiogaraz 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 14:50:55 volumiogaraz systemd-udevd[230]: Using default interface naming scheme 'v240'. May 05 14:50:55 volumiogaraz systemd-udevd[230]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 14:50:55 volumiogaraz kernel: hwmon hwmon1: Undervoltage detected! May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in 7800 being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Created slice system-bthelper.slice. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in FUSE Control File System being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 05 14:50:56 volumiogaraz systemd[1]: Mounting /boot... May 05 14:50:56 volumiogaraz systemd[1]: Started File System Check Daemon to report status. May 05 14:50:56 volumiogaraz systemd[1]: Starting Load/Save RF Kill Switch Status... May 05 14:50:56 volumiogaraz systemd[1]: Mounted /boot. May 05 14:50:56 volumiogaraz systemd[1]: Reached target Local File Systems. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Starting Create Volatile Files and Directories... May 05 14:50:56 volumiogaraz systemd[1]: Starting Raise network interfaces... May 05 14:50:56 volumiogaraz systemd[1]: Started ifup for eth0. May 05 14:50:56 volumiogaraz systemd[1]: Starting Preprocess NFS configuration... May 05 14:50:56 volumiogaraz systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 05 14:50:56 volumiogaraz systemd[1]: Started ifup for wlan0. May 05 14:50:56 volumiogaraz systemd[1]: Started Load/Save RF Kill Switch Status. May 05 14:50:56 volumiogaraz systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd). May 05 14:50:56 volumiogaraz systemd[1]: plymouth-read-write.service: Succeeded. May 05 14:50:56 volumiogaraz systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 05 14:50:56 volumiogaraz systemd[1]: nfs-config.service: Succeeded. May 05 14:50:56 volumiogaraz systemd[1]: Started Preprocess NFS configuration. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Reached target NFS client services. May 05 14:50:56 volumiogaraz systemd[1]: Started Create Volatile Files and Directories. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 05 14:50:56 volumiogaraz systemd[1]: Starting RPC bind portmap service... May 05 14:50:56 volumiogaraz systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 05 14:50:56 volumiogaraz kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 14:50:56 volumiogaraz systemd[1]: Started Update UTMP about System Boot/Shutdown. May 05 14:50:56 volumiogaraz systemd[1]: Reached target System Initialization. May 05 14:50:56 volumiogaraz systemd[1]: Started Volumio Splash. May 05 14:50:56 volumiogaraz systemd[485]: volumiosplash.service: Failed to execute command: No such file or directory May 05 14:50:56 volumiogaraz systemd[485]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory May 05 14:50:56 volumiogaraz systemd[1]: Listening on triggerhappy.socket. May 05 14:50:56 volumiogaraz systemd[1]: Started Daily Cleanup of Temporary Directories. May 05 14:50:56 volumiogaraz kernel: 8021q: 802.1Q VLAN Support v1.8 May 05 14:50:56 volumiogaraz systemd[1]: Started Daily apt download activities. May 05 14:50:56 volumiogaraz systemd[1]: Started Daily apt upgrade and clean activities. May 05 14:50:56 volumiogaraz systemd[1]: Reached target Timers. May 05 14:50:56 volumiogaraz systemd[1]: Listening on D-Bus System Message Bus Socket. May 05 14:50:56 volumiogaraz systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 05 14:50:56 volumiogaraz systemd[1]: Reached target Sockets. May 05 14:50:56 volumiogaraz systemd[1]: Reached target Basic System. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 05 14:50:56 volumiogaraz systemd[1]: Starting Wireless Services... May 05 14:50:56 volumiogaraz systemd[1]: Started D-Bus System Message Bus. May 05 14:50:57 volumiogaraz systemd[1]: Starting WPA supplicant... May 05 14:50:57 volumiogaraz systemd[1]: Started Volumio Log Rotation Service. May 05 14:50:57 volumiogaraz systemd[1]: Started Manage Sound Card State (restore and store). May 05 14:50:57 volumiogaraz systemd[1]: Starting Save/Restore Sound Card State... May 05 14:50:57 volumiogaraz systemd[1]: Started volumio-remote-updater.service. May 05 14:50:57 volumiogaraz alsactl[515]: alsactl 1.1.8 daemon started May 05 14:50:57 volumiogaraz systemd[1]: Started Volumio Iptables Module. May 05 14:50:57 volumiogaraz systemd[1]: Starting triggerhappy global hotkey daemon... May 05 14:50:57 volumiogaraz systemd[1]: Starting Login Service... May 05 14:50:57 volumiogaraz systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 05 14:50:57 volumiogaraz systemd[1]: Started UPnP Renderer front-end to MPD. May 05 14:50:57 volumiogaraz systemd[1]: Starting Configure Bluetooth Modems connected by UART... May 05 14:50:57 volumiogaraz systemd[1]: Starting dhcpcd on all interfaces... May 05 14:50:57 volumiogaraz systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 05 14:50:57 volumiogaraz systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC May 05 14:50:57 volumiogaraz systemd[1]: volumiosplash.service: Failed with result 'exit-code'. May 05 14:50:57 volumiogaraz thd[519]: Unable to parse trigger line: May 05 14:50:57 volumiogaraz thd[519]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 05 14:50:57 volumiogaraz thd[519]: Unable to parse trigger line: May 05 14:50:57 volumiogaraz thd[519]: Found socket passed from systemd May 05 14:50:57 volumiogaraz systemd[1]: Started triggerhappy global hotkey daemon. May 05 14:50:57 volumiogaraz systemd[1]: Started RPC bind portmap service. May 05 14:50:57 volumiogaraz systemd[1]: Started Save/Restore Sound Card State. May 05 14:50:57 volumiogaraz systemd[1]: Reached target Sound Card. May 05 14:50:57 volumiogaraz systemd[1]: Reached target RPC Port Mapper. May 05 14:50:57 volumiogaraz systemd[1]: Reached target Remote File Systems (Pre). May 05 14:50:57 volumiogaraz systemd[1]: Reached target Remote File Systems. May 05 14:50:57 volumiogaraz sh[420]: wlan0=wlan0 May 05 14:50:57 volumiogaraz systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 05 14:50:57 volumiogaraz dhcpcd[523]: Not running dhcpcd because /etc/network/interfaces May 05 14:50:57 volumiogaraz dhcpcd[523]: defines some interfaces that will use a May 05 14:50:57 volumiogaraz dhcpcd[523]: DHCP client or static address May 05 14:50:57 volumiogaraz systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED May 05 14:50:57 volumiogaraz systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 05 14:50:57 volumiogaraz systemd[1]: Failed to start dhcpcd on all interfaces. May 05 14:50:57 volumiogaraz systemd-logind[520]: New seat seat0. May 05 14:50:57 volumiogaraz avahi-daemon[525]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). May 05 14:50:57 volumiogaraz avahi-daemon[525]: Successfully dropped root privileges. May 05 14:50:57 volumiogaraz avahi-daemon[525]: avahi-daemon 0.7 starting up. May 05 14:50:57 volumiogaraz kernel: 8021q: adding VLAN 0 to HW filter on device eth0 May 05 14:50:57 volumiogaraz sh[415]: eth0: waiting for carrier May 05 14:50:57 volumiogaraz dhcpcd[463]: eth0: waiting for carrier May 05 14:50:57 volumiogaraz kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down May 05 14:50:58 volumiogaraz systemd[1]: hciuart.service: Succeeded. May 05 14:50:58 volumiogaraz systemd[1]: Started Configure Bluetooth Modems connected by UART. May 05 14:50:58 volumiogaraz wpa_supplicant[513]: Successfully initialized wpa_supplicant May 05 14:50:58 volumiogaraz avahi-daemon[525]: Successfully called chroot(). May 05 14:50:58 volumiogaraz avahi-daemon[525]: Successfully dropped remaining capabilities. May 05 14:50:58 volumiogaraz avahi-daemon[525]: Loading service file /services/volumio.service. May 05 14:50:58 volumiogaraz avahi-daemon[525]: Network interface enumeration completed. May 05 14:50:58 volumiogaraz avahi-daemon[525]: Server startup complete. Host name is volumiogaraz.local. Local service cookie is 2565967630. May 05 14:50:58 volumiogaraz avahi-daemon[525]: Service "VolumioGaraz" (/services/volumio.service) successfully established. May 05 14:50:58 volumiogaraz systemd[1]: Started Login Service. May 05 14:50:58 volumiogaraz systemd[1]: Started WPA supplicant. May 05 14:50:58 volumiogaraz systemd[1]: Started Raise network interfaces. May 05 14:50:58 volumiogaraz systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 05 14:50:58 volumiogaraz systemd[1]: Reached target Network. May 05 14:50:58 volumiogaraz systemd[1]: Starting Permit User Sessions... May 05 14:50:58 volumiogaraz systemd[1]: Starting OpenBSD Secure Shell server... May 05 14:50:58 volumiogaraz systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 05 14:50:58 volumiogaraz systemd[1]: Reached target Network is Online. May 05 14:50:58 volumiogaraz systemd[1]: Starting LSB: Brings up/down network automatically... May 05 14:50:58 volumiogaraz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 14:50:58 volumiogaraz systemd[1]: Starting /etc/rc.local Compatibility... May 05 14:50:58 volumiogaraz systemd[1]: Starting Network Time Service... May 05 14:50:58 volumiogaraz volumio-remote-updater[517]: Error: No active session May 05 14:50:58 volumiogaraz systemd[1]: Starting Samba NMB Daemon... May 05 14:50:58 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:50:58] [info] asio async_connect error: system:111 (Connection refused) May 05 14:50:58 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:50:58] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 05 14:50:58 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:50:58] [error] handle_connect error: Underlying Transport Error May 05 14:50:58 volumiogaraz systemd[1]: Starting Raspberry Pi bluetooth helper... May 05 14:50:59 volumiogaraz systemd[1]: Started Permit User Sessions. May 05 14:50:59 volumiogaraz systemd[1]: Started /etc/rc.local Compatibility. May 05 14:50:59 volumiogaraz volumio[521]: Could not open config: /tmp/upmpdcli.conf May 05 14:50:59 volumiogaraz systemd[1]: Starting Terminate Plymouth Boot Screen... May 05 14:50:59 volumiogaraz systemd[1]: Starting Hold until boot process finishes up... May 05 14:50:59 volumiogaraz systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 05 14:50:59 volumiogaraz systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 05 14:50:59 volumiogaraz systemd[1]: plymouth-start.service: Succeeded. May 05 14:50:59 volumiogaraz loadcpufreq[547]: Loading cpufreq kernel modules...done (none). May 05 14:50:59 volumiogaraz systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 05 14:50:59 volumiogaraz systemd[1]: plymouth-quit.service: Succeeded. May 05 14:50:59 volumiogaraz systemd[1]: Started Terminate Plymouth Boot Screen. May 05 14:50:59 volumiogaraz systemd[1]: plymouth-quit-wait.service: Succeeded. May 05 14:50:59 volumiogaraz systemd[1]: Started Hold until boot process finishes up. May 05 14:50:59 volumiogaraz systemd[1]: Received SIGRTMIN+21 from PID 240 (n/a). May 05 14:50:59 volumiogaraz systemd[1]: Received SIGRTMIN+21 from PID 240 (n/a). May 05 14:50:59 volumiogaraz bthelper[630]: Raspberry Pi BDADDR already set May 05 14:50:59 volumiogaraz systemd[1]: Started Raspberry Pi bluetooth helper. May 05 14:50:59 volumiogaraz systemd[1]: Starting Bluetooth service... May 05 14:50:59 volumiogaraz systemd[1]: Started Getty on tty1. May 05 14:50:59 volumiogaraz ntpd[625]: ntpd 4.2.8p12@1.3728-o (1): Starting May 05 14:50:59 volumiogaraz ntpd[625]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 May 05 14:50:59 volumiogaraz systemd[1]: Reached target Login Prompts. May 05 14:50:59 volumiogaraz systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 05 14:50:59 volumiogaraz ntpd[674]: proto: precision = 1.458 usec (-19) May 05 14:50:59 volumiogaraz ifplugd(eth0)[679]: ifplugd 0.28 initializing. May 05 14:50:59 volumiogaraz ifplugd(eth0)[679]: Using interface eth0/B8:27:EB:9D:C7:5B with driver (version: 6.1.69-v7+) May 05 14:50:59 volumiogaraz ntpd[674]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 05 14:50:59 volumiogaraz ntpd[674]: 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 14:50:59 volumiogaraz ntpd[674]: Listen and drop on 0 v6wildcard [::]:123 May 05 14:50:59 volumiogaraz ntpd[674]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 05 14:50:59 volumiogaraz ifplugd(eth0)[679]: Using detection mode: SIOCETHTOOL May 05 14:50:59 volumiogaraz ifplugd(eth0)[679]: Initialization complete, link beat not detected. May 05 14:50:59 volumiogaraz ntpd[674]: Listen normally on 2 lo 127.0.0.1:123 May 05 14:50:59 volumiogaraz ntpd[674]: Listening on routing socket on fd #19 for interface updates May 05 14:50:59 volumiogaraz ntpd[674]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 14:50:59 volumiogaraz ntpd[674]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 14:50:59 volumiogaraz systemd[1]: Started Network Time Service. May 05 14:50:59 volumiogaraz systemd[1]: iptables.service: Succeeded. May 05 14:50:59 volumiogaraz ifplugd[616]: Network Interface Plugging Daemon...start eth0...done. May 05 14:50:59 volumiogaraz sshd[685]: Server listening on 0.0.0.0 port 22. May 05 14:50:59 volumiogaraz sshd[685]: Server listening on :: port 22. May 05 14:50:59 volumiogaraz systemd[1]: Started OpenBSD Secure Shell server. May 05 14:50:59 volumiogaraz systemd[1]: Started LSB: Brings up/down network automatically. May 05 14:50:59 volumiogaraz bluetoothd[667]: Bluetooth daemon 5.50 May 05 14:51:00 volumiogaraz bluetoothd[667]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 05 14:51:00 volumiogaraz systemd[1]: Started Bluetooth service. May 05 14:51:00 volumiogaraz systemd[1]: Reached target Bluetooth. May 05 14:51:00 volumiogaraz bluetoothd[667]: Starting SDP server May 05 14:51:00 volumiogaraz bluetoothd[667]: Excluding (cli) sap May 05 14:51:00 volumiogaraz cpufrequtils[677]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 05 14:51:00 volumiogaraz systemd[1]: Started LSB: set CPUFreq kernel parameters. May 05 14:51:00 volumiogaraz kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 05 14:51:00 volumiogaraz kernel: Bluetooth: BNEP filters: protocol multicast May 05 14:51:00 volumiogaraz kernel: Bluetooth: BNEP socket layer initialized May 05 14:51:00 volumiogaraz bluetoothd[667]: Bluetooth management interface 1.22 initialized May 05 14:51:00 volumiogaraz dbus-daemon[512]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=667 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 05 14:51:00 volumiogaraz haveged[472]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K May 05 14:51:00 volumiogaraz haveged[472]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 May 05 14:51:00 volumiogaraz haveged[472]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00387 May 05 14:51:00 volumiogaraz kernel: Bluetooth: MGMT ver 1.22 May 05 14:51:00 volumiogaraz haveged[472]: haveged: fills: 0, generated: 0 May 05 14:51:00 volumiogaraz bluetoothd[667]: Failed to set privacy: Rejected (0x0b) May 05 14:51:00 volumiogaraz systemd[1]: Starting Hostname Service... May 05 14:51:00 volumiogaraz ntpd[674]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 05 14:51:00 volumiogaraz dbus-daemon[512]: [system] Successfully activated service 'org.freedesktop.hostname1' May 05 14:51:00 volumiogaraz systemd[1]: Started Hostname Service. May 05 14:51:01 volumiogaraz nmbd[640]: [2024/05/05 14:51:01.277684, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 05 14:51:01 volumiogaraz nmbd[640]: started asyncdns process 704 May 05 14:51:01 volumiogaraz nmbd[640]: [2024/05/05 14:51:01.281499, 0] ../lib/util/become_daemon.c:149(daemon_status) May 05 14:51:01 volumiogaraz nmbd[640]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 05 14:51:01 volumiogaraz nmbd[640]: [2024/05/05 14:51:01.281877, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 05 14:51:01 volumiogaraz nmbd[640]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 05 14:51:01 volumiogaraz systemd[1]: systemd-rfkill.service: Succeeded. May 05 14:51:01 volumiogaraz ntpd[674]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 05 14:51:02 volumiogaraz wireless.js[511]: Cleaning previous... May 05 14:51:02 volumiogaraz sudo[712]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 05 14:51:02 volumiogaraz sudo[712]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:02 volumiogaraz sudo[712]: pam_unix(sudo:session): session closed for user root May 05 14:51:02 volumiogaraz sudo[714]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 05 14:51:02 volumiogaraz sudo[714]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:02 volumiogaraz ntpd[674]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 05 14:51:02 volumiogaraz sudo[714]: pam_unix(sudo:session): session closed for user root May 05 14:51:02 volumiogaraz wireless.js[511]: Stopped aP May 05 14:51:03 volumiogaraz sudo[721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 14:51:03 volumiogaraz sudo[721]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:03 volumiogaraz kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 14:51:03 volumiogaraz sudo[721]: pam_unix(sudo:session): session closed for user root May 05 14:51:03 volumiogaraz sudo[723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 05 14:51:03 volumiogaraz sudo[723]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:03 volumiogaraz sudo[723]: pam_unix(sudo:session): session closed for user root May 05 14:51:03 volumiogaraz sudo[730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 14:51:03 volumiogaraz sudo[730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:03 volumiogaraz sudo[730]: pam_unix(sudo:session): session closed for user root May 05 14:51:03 volumiogaraz sudo[732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 05 14:51:03 volumiogaraz sudo[732]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:03 volumiogaraz ntpd[674]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 05 14:51:03 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:03] [info] asio async_connect error: system:111 (Connection refused) May 05 14:51:03 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 05 14:51:03 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:03] [error] handle_connect error: Underlying Transport Error May 05 14:51:04 volumiogaraz bthelper[630]: Changing power off succeeded May 05 14:51:04 volumiogaraz bthelper[630]: [CHG] Controller B8:27:EB:37:6D:F1 Class: 0x0000041c May 05 14:51:04 volumiogaraz bthelper[630]: Changing power on succeeded May 05 14:51:06 volumiogaraz sudo[732]: pam_unix(sudo:session): session closed for user root May 05 14:51:06 volumiogaraz wireless.js[511]: Start wireless flow May 05 14:51:06 volumiogaraz wireless.js[511]: Stopped hotspot (if there).. May 05 14:51:06 volumiogaraz sudo[744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 05 14:51:06 volumiogaraz sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:06 volumiogaraz sudo[744]: pam_unix(sudo:session): session closed for user root May 05 14:51:06 volumiogaraz sudo[746]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 05 14:51:06 volumiogaraz sudo[746]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:06 volumiogaraz sudo[746]: pam_unix(sudo:session): session closed for user root May 05 14:51:06 volumiogaraz wireless.js[511]: DHCP IP May 05 14:51:06 volumiogaraz wireless.js[511]: Start ap May 05 14:51:06 volumiogaraz wpa_supplicant[748]: Successfully initialized wpa_supplicant May 05 14:51:06 volumiogaraz sudo[749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 05 14:51:06 volumiogaraz sudo[749]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:06 volumiogaraz kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 14:51:06 volumiogaraz dhcpcd[750]: dev: loaded udev May 05 14:51:06 volumiogaraz dhcpcd-run-hooks[768]: wlan0: starting wpa_supplicant May 05 14:51:07 volumiogaraz dhcpcd-run-hooks[773]: wlan0: failed to start wpa_supplicant May 05 14:51:07 volumiogaraz dhcpcd-run-hooks[774]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma May 05 14:51:07 volumiogaraz dhcpcd[750]: wlan0: connected to Access Point `' May 05 14:51:07 volumiogaraz dhcpcd[750]: no interfaces have a carrier May 05 14:51:07 volumiogaraz dhcpcd[750]: forked to background, child pid 784 May 05 14:51:07 volumiogaraz dhcpcd[784]: eth0: waiting for carrier May 05 14:51:07 volumiogaraz dhcpcd[784]: wlan0: waiting for carrier May 05 14:51:07 volumiogaraz sudo[749]: pam_unix(sudo:session): session closed for user root May 05 14:51:07 volumiogaraz wireless.js[511]: trying... May 05 14:51:07 volumiogaraz sudo[786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:07 volumiogaraz sudo[786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:07 volumiogaraz sudo[786]: pam_unix(sudo:session): session closed for user root May 05 14:51:08 volumiogaraz wireless.js[511]: trying... May 05 14:51:08 volumiogaraz sudo[789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:08 volumiogaraz sudo[789]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:08 volumiogaraz sudo[789]: pam_unix(sudo:session): session closed for user root May 05 14:51:09 volumiogaraz wireless.js[511]: trying... May 05 14:51:09 volumiogaraz sudo[792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:09 volumiogaraz sudo[792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:09 volumiogaraz sudo[792]: pam_unix(sudo:session): session closed for user root May 05 14:51:09 volumiogaraz wpa_supplicant[772]: wlan0: Trying to associate with SSID 'IoT media' May 05 14:51:10 volumiogaraz wireless.js[511]: trying... May 05 14:51:10 volumiogaraz sudo[795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:10 volumiogaraz sudo[795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:10 volumiogaraz sudo[795]: pam_unix(sudo:session): session closed for user root May 05 14:51:11 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:11] [info] asio async_connect error: system:111 (Connection refused) May 05 14:51:11 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 05 14:51:11 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:11] [error] handle_connect error: Underlying Transport Error May 05 14:51:11 volumiogaraz wireless.js[511]: trying... May 05 14:51:11 volumiogaraz sudo[798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:11 volumiogaraz sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:11 volumiogaraz sudo[798]: pam_unix(sudo:session): session closed for user root May 05 14:51:12 volumiogaraz dhcpcd[784]: wlan0: carrier acquired May 05 14:51:12 volumiogaraz dhcpcd[784]: wlan0: connected to Access Point `IoT media' May 05 14:51:12 volumiogaraz wpa_supplicant[772]: wlan0: Associated with 4a:3f:8c:b6:46:99 May 05 14:51:12 volumiogaraz wpa_supplicant[772]: wlan0: CTRL-EVENT-CONNECTED - Connection to 4a:3f:8c:b6:46:99 completed [id=0 id_str=] May 05 14:51:12 volumiogaraz wpa_supplicant[772]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 05 14:51:12 volumiogaraz dhcpcd[784]: DUID 00:01:00:01:2d:bb:b6:35:b8:27:eb:c8:92:0e May 05 14:51:12 volumiogaraz dhcpcd[784]: wlan0: IAID eb:c8:92:0e May 05 14:51:12 volumiogaraz dhcpcd[784]: wlan0: adding address fe80::1471:5de6:c221:d917 May 05 14:51:12 volumiogaraz dhcpcd[784]: ipv6_addaddr1: Permission denied May 05 14:51:12 volumiogaraz dhcpcd[784]: wlan0: carrier lost May 05 14:51:12 volumiogaraz wireless.js[511]: trying... May 05 14:51:12 volumiogaraz dhcpcd[784]: wlan0: carrier acquired May 05 14:51:12 volumiogaraz dhcpcd[784]: wlan0: IAID eb:c8:92:0e May 05 14:51:12 volumiogaraz sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:12 volumiogaraz sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:12 volumiogaraz sudo[812]: pam_unix(sudo:session): session closed for user root May 05 14:51:13 volumiogaraz dhcpcd[784]: wlan0: rebinding lease of 192.168.15.56 May 05 14:51:13 volumiogaraz dhcpcd[784]: wlan0: probing address 192.168.15.56/24 May 05 14:51:13 volumiogaraz dhcpcd[784]: wlan0: soliciting an IPv6 router May 05 14:51:13 volumiogaraz wireless.js[511]: trying... May 05 14:51:13 volumiogaraz sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:13 volumiogaraz sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:13 volumiogaraz sudo[816]: pam_unix(sudo:session): session closed for user root May 05 14:51:14 volumiogaraz wireless.js[511]: trying... May 05 14:51:14 volumiogaraz sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:14 volumiogaraz sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:14 volumiogaraz sudo[819]: pam_unix(sudo:session): session closed for user root May 05 14:51:15 volumiogaraz wireless.js[511]: trying... May 05 14:51:15 volumiogaraz sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:15 volumiogaraz sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:15 volumiogaraz sudo[822]: pam_unix(sudo:session): session closed for user root May 05 14:51:16 volumiogaraz wireless.js[511]: trying... May 05 14:51:16 volumiogaraz sudo[825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:16 volumiogaraz sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:16 volumiogaraz sudo[825]: pam_unix(sudo:session): session closed for user root May 05 14:51:17 volumiogaraz wireless.js[511]: trying... May 05 14:51:17 volumiogaraz dhcpcd[784]: wlan0: leased 192.168.15.56 for 600 seconds May 05 14:51:17 volumiogaraz avahi-daemon[525]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.15.56. May 05 14:51:17 volumiogaraz dhcpcd[784]: wlan0: adding route to 192.168.15.0/24 May 05 14:51:17 volumiogaraz avahi-daemon[525]: New relevant interface wlan0.IPv4 for mDNS. May 05 14:51:17 volumiogaraz avahi-daemon[525]: Registering new address record for 192.168.15.56 on wlan0.IPv4. May 05 14:51:17 volumiogaraz dhcpcd[784]: wlan0: adding default route via 192.168.15.1 May 05 14:51:17 volumiogaraz sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 14:51:17 volumiogaraz sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:17 volumiogaraz sudo[828]: pam_unix(sudo:session): session closed for user root May 05 14:51:17 volumiogaraz wireless.js[511]: Connected to: ----IoT media May 05 14:51:17 volumiogaraz wireless.js[511]: ---- May 05 14:51:17 volumiogaraz sudo[839]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 14:51:17 volumiogaraz sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:17 volumiogaraz sudo[839]: pam_unix(sudo:session): session closed for user root May 05 14:51:17 volumiogaraz wireless.js[511]: ... joined AP, wlan0 IPv4 is 192.168.15.56, ipV6 is undefined May 05 14:51:17 volumiogaraz wireless.js[511]: It's done! AP May 05 14:51:17 volumiogaraz kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 14:51:17 volumiogaraz systemd[1]: Started Wireless Services. May 05 14:51:17 volumiogaraz systemd[1]: Started Volumio Backend Module. May 05 14:51:17 volumiogaraz systemd[1]: Started Volumio Cpu Tweaker. May 05 14:51:18 volumiogaraz volumio-cpu-tweak[857]: Setting RT Priority for mpd May 05 14:51:18 volumiogaraz volumio-cpu-tweak[857]: pid 35's current scheduling policy: SCHED_OTHER May 05 14:51:18 volumiogaraz volumio-cpu-tweak[857]: pid 35's current scheduling priority: 0 May 05 14:51:18 volumiogaraz volumio-cpu-tweak[857]: Setting MPD Affinity May 05 14:51:18 volumiogaraz volumio-cpu-tweak[857]: pid 3's current affinity mask: f May 05 14:51:18 volumiogaraz volumio-cpu-tweak[857]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 05 14:51:18 volumiogaraz volumio-cpu-tweak[857]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 05 14:51:18 volumiogaraz systemd[1]: volumio_cpu_tweak.service: Succeeded. May 05 14:51:19 volumiogaraz ntpd[674]: Listen normally on 3 wlan0 192.168.15.56:123 May 05 14:51:19 volumiogaraz ntpd[674]: new interface(s) found: waking up resolver May 05 14:51:21 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:21] [connect] Successful connection May 05 14:51:21 volumiogaraz systemd[1]: Started Samba NMB Daemon. May 05 14:51:21 volumiogaraz nmbd[640]: [2024/05/05 14:51:21.667735, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 14:51:21 volumiogaraz nmbd[640]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 05 14:51:21 volumiogaraz systemd[1]: Starting Samba Winbind Daemon... May 05 14:51:22 volumiogaraz winbindd[873]: [2024/05/05 14:51:22.402821, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 05 14:51:22 volumiogaraz winbindd[873]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 05 14:51:22 volumiogaraz winbindd[873]: [2024/05/05 14:51:22.432150, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 14:51:22 volumiogaraz winbindd[873]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 05 14:51:22 volumiogaraz systemd[1]: Started Samba Winbind Daemon. May 05 14:51:22 volumiogaraz systemd[1]: Starting Samba SMB Daemon... May 05 14:51:23 volumiogaraz volumio[856]: info: ------------------------------------------- May 05 14:51:23 volumiogaraz volumio[856]: info: ----- Volumio3 ---- May 05 14:51:23 volumiogaraz volumio[856]: info: ------------------------------------------- May 05 14:51:23 volumiogaraz volumio[856]: info: ----- System startup ---- May 05 14:51:23 volumiogaraz volumio[856]: info: ------------------------------------------- May 05 14:51:23 volumiogaraz smbd[878]: [2024/05/05 14:51:23.397389, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 14:51:23 volumiogaraz smbd[878]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 05 14:51:23 volumiogaraz systemd[1]: Started Samba SMB Daemon. May 05 14:51:23 volumiogaraz systemd[1]: Reached target Multi-User System. May 05 14:51:23 volumiogaraz systemd[1]: Reached target Graphical Interface. May 05 14:51:23 volumiogaraz systemd[1]: Starting Update UTMP about System Runlevel Changes... May 05 14:51:23 volumiogaraz systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 05 14:51:23 volumiogaraz systemd[1]: Started Update UTMP about System Runlevel Changes. May 05 14:51:23 volumiogaraz systemd[1]: Startup finished in 10.053s (kernel) + 33.567s (userspace) = 43.620s. May 05 14:51:25 volumiogaraz volumio[856]: info: MYVOLUMIO Environment detected May 05 14:51:25 volumiogaraz volumio[856]: info: Plugin folders cleanup May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning into folder /volumio/app/plugins/ May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning category audio_interface May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning category miscellanea May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning category music_service May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning category plugins.json May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning category system_controller May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning category user_interface May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning into folder /data/plugins/ May 05 14:51:25 volumiogaraz volumio[856]: info: Scanning category system_hardware May 05 14:51:25 volumiogaraz volumio[856]: info: Plugin folders cleanup completed May 05 14:51:25 volumiogaraz volumio[856]: info: ------------------------------------------- May 05 14:51:25 volumiogaraz volumio[856]: info: ----- Core plugins startup ---- May 05 14:51:25 volumiogaraz volumio[856]: info: ------------------------------------------- May 05 14:51:25 volumiogaraz volumio[856]: info: Loading plugins from folder /volumio/app/plugins/ May 05 14:51:25 volumiogaraz volumio[856]: info: Adding plugin upnp to MyMusic Plugins May 05 14:51:25 volumiogaraz volumio[856]: info: Adding plugin airplay_emulation to MyMusic Plugins May 05 14:51:25 volumiogaraz volumio[856]: info: Adding plugin upnp_browser to MyMusic Plugins May 05 14:51:25 volumiogaraz volumio[856]: info: Loading plugins from folder /data/plugins/ May 05 14:51:25 volumiogaraz volumio[856]: info: Loading plugin "system"... May 05 14:51:25 volumiogaraz volumio[856]: info: Loading plugin "appearance"... May 05 14:51:26 volumiogaraz systemd[1]: systemd-fsckd.service: Succeeded. May 05 14:51:27 volumiogaraz sh[415]: timed out May 05 14:51:27 volumiogaraz dhcpcd[463]: timed out May 05 14:51:27 volumiogaraz sh[415]: dhcpcd exited May 05 14:51:27 volumiogaraz dhcpcd[463]: dhcpcd exited May 05 14:51:27 volumiogaraz sh[415]: ifup: failed to bring up eth0 May 05 14:51:27 volumiogaraz systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 05 14:51:27 volumiogaraz systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 05 14:51:28 volumiogaraz volumio[856]: info: Loading plugin "network"... May 05 14:51:28 volumiogaraz volumio[856]: info: Refreshing Cached IP Addresses May 05 14:51:28 volumiogaraz sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 14:51:28 volumiogaraz sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:28 volumiogaraz sudo[901]: pam_unix(sudo:session): session closed for user root May 05 14:51:28 volumiogaraz volumio[856]: info: Loading plugin "services"... May 05 14:51:28 volumiogaraz sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 14:51:28 volumiogaraz sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:28 volumiogaraz volumio[856]: info: Loading plugin "alsa_controller"... May 05 14:51:28 volumiogaraz sudo[903]: pam_unix(sudo:session): session closed for user root May 05 14:51:28 volumiogaraz sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 05 14:51:28 volumiogaraz sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:28 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 14:51:28 volumiogaraz volumio[856]: info: Loading plugin "wizard"... May 05 14:51:29 volumiogaraz volumio[856]: info: Loading plugin "networkfs"... May 05 14:51:29 volumiogaraz volumio[856]: info: Starting Udev Watcher for removable devices May 05 14:51:29 volumiogaraz sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=samba,password=samba,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.57/muzyka /mnt/NAS/NAS May 05 14:51:29 volumiogaraz sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:29 volumiogaraz volumio[856]: info: Ignoring mount for partition: boot May 05 14:51:29 volumiogaraz volumio[856]: info: Ignoring mount for partition: volumio May 05 14:51:29 volumiogaraz volumio[856]: info: Ignoring mount for partition: volumio_data May 05 14:51:29 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 14:51:29 volumiogaraz volumio[856]: info: Loading plugin "volumio_command_line_client"... May 05 14:51:29 volumiogaraz volumio[856]: info: Loading plugin "upnp"... May 05 14:51:29 volumiogaraz volumio[856]: info: [1714913489269] Starting Upmpd Daemon May 05 14:51:29 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 14:51:29 volumiogaraz volumio[856]: info: Loading plugin "my_music"... May 05 14:51:29 volumiogaraz volumio[856]: info: Loading plugin "mpd"... May 05 14:51:29 volumiogaraz kernel: Key type cifs.spnego registered May 05 14:51:29 volumiogaraz kernel: Key type cifs.idmap registered May 05 14:51:29 volumiogaraz 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 14:51:29 volumiogaraz kernel: CIFS: Attempting to mount \\192.168.2.57\muzyka May 05 14:51:30 volumiogaraz sudo[912]: pam_unix(sudo:session): session closed for user root May 05 14:51:30 volumiogaraz volumio[856]: info: Loading plugin "upnp_browser"... May 05 14:51:30 volumiogaraz systemd[1]: systemd-hostnamed.service: Succeeded. May 05 14:51:31 volumiogaraz volumio[856]: info: Loading plugin "alarm-clock"... May 05 14:51:31 volumiogaraz volumio[856]: info: Loading plugin "airplay_emulation"... May 05 14:51:31 volumiogaraz volumio[856]: info: Starting Shairport Sync May 05 14:51:31 volumiogaraz volumio[856]: info: Loading plugin "last_100"... May 05 14:51:31 volumiogaraz volumio[856]: info: Loading plugin "webradio"... May 05 14:51:31 volumiogaraz volumio[856]: info: Loading plugin "i2s_dacs"... May 05 14:51:31 volumiogaraz volumio[856]: info: Loading plugin "volumiodiscovery"... May 05 14:51:32 volumiogaraz volumio[856]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 14:51:32 volumiogaraz volumio[856]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 14:51:32 volumiogaraz volumio[856]: *** WARNING *** For more information see May 05 14:51:32 volumiogaraz node[856]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 14:51:32 volumiogaraz volumio[856]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 14:51:32 volumiogaraz volumio[856]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 14:51:32 volumiogaraz volumio[856]: *** WARNING *** For more information see May 05 14:51:32 volumiogaraz node[856]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 14:51:32 volumiogaraz node[856]: *** WARNING *** For more information see May 05 14:51:32 volumiogaraz node[856]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 14:51:32 volumiogaraz node[856]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 14:51:32 volumiogaraz node[856]: *** WARNING *** For more information see May 05 14:51:32 volumiogaraz volumio[856]: info: Applying required configuration parameters for plugin volumiodiscovery May 05 14:51:32 volumiogaraz volumio[856]: info: Discovery: Started advertising with name: VolumioGaraz May 05 14:51:32 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 14:51:32 volumiogaraz volumio[856]: info: Loading plugin "outputs"... May 05 14:51:32 volumiogaraz volumio[856]: info: Loading plugin "albumart"... May 05 14:51:32 volumiogaraz volumio[856]: info: Plugin example_plugin is not enabled May 05 14:51:32 volumiogaraz volumio[856]: info: Loading plugin "inputs"... May 05 14:51:32 volumiogaraz volumio[856]: info: Loading plugin "updater_comm"... May 05 14:51:32 volumiogaraz volumio[856]: info: Plugin mpdemulation is not enabled May 05 14:51:32 volumiogaraz volumio[856]: info: Loading plugin "rest_api"... May 05 14:51:32 volumiogaraz volumio[856]: info: Loading plugin "websocket"... May 05 14:51:32 volumiogaraz volumio[856]: info: Loading plugin "mpd_oled"... May 05 14:51:32 volumiogaraz sudo[906]: pam_unix(sudo:session): session closed for user root May 05 14:51:32 volumiogaraz volumio[856]: Forking 3 albumart workers May 05 14:51:33 volumiogaraz volumio[856]: info: Applying required configuration parameters for plugin mpd_oled May 05 14:51:33 volumiogaraz volumio[856]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json May 05 14:51:33 volumiogaraz volumio[856]: info: Loading i18n strings for locale pl May 05 14:51:33 volumiogaraz volumio[856]: Updating browse sources language May 05 14:51:33 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::initPlayerControls May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 14:51:34 volumiogaraz volumio[856]: Express server listening on port 3000 May 05 14:51:34 volumiogaraz volumio[856]: [Metrics] WebUI: 12s 945.73ms May 05 14:51:34 volumiogaraz volumio[856]: info: CoreStateMachine::resetVolumioState May 05 14:51:34 volumiogaraz volumio[856]: info: CoreStateMachine::getcurrentVolume May 05 14:51:34 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioRetrievevolume May 05 14:51:34 volumiogaraz volumio[856]: info: Cannot read play queue from file May 05 14:51:34 volumiogaraz volumio[856]: Starting albumart workers May 05 14:51:34 volumiogaraz volumio[856]: Starting albumart workers May 05 14:51:34 volumiogaraz volumio[856]: Starting albumart workers May 05 14:51:34 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56 from 192.168.1.172 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 1 May 05 14:51:34 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56 from 192.168.1.107 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 2 May 05 14:51:34 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56 from 192.168.1.172 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3 May 05 14:51:34 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56 from 192.168.1.107 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 4 May 05 14:51:34 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56 from 192.168.1.107 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 5 May 05 14:51:34 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56:3000 from 192.168.15.54 UA: node-XMLHttpRequest Total Clients: 6 May 05 14:51:34 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56:3000 from 192.168.15.55 UA: node-XMLHttpRequest Total Clients: 7 May 05 14:51:34 volumiogaraz volumio[856]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:51:35 volumiogaraz volumio[856]: info: CoreStateMachine::setRepeat null single undefined May 05 14:51:35 volumiogaraz volumio[856]: info: CoreStateMachine::pushState May 05 14:51:35 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioPushState May 05 14:51:35 volumiogaraz volumio[856]: info: CoreStateMachine::setRandom null May 05 14:51:35 volumiogaraz volumio[856]: info: CoreStateMachine::pushState May 05 14:51:35 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioPushState May 05 14:51:35 volumiogaraz volumio[856]: info: Setting Device type: Raspberry PI May 05 14:51:35 volumiogaraz volumio[856]: info: Completed loading Core Plugins May 05 14:51:35 volumiogaraz volumio[856]: info: Preparing to generate the ALSA configuration file May 05 14:51:35 volumiogaraz volumio[856]: info: VolumeController:: Volume=undefined Mute =false May 05 14:51:35 volumiogaraz volumio[856]: info: CoreStateMachine::pushState May 05 14:51:35 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioPushState May 05 14:51:35 volumiogaraz volumio[856]: info: CoreStateMachine::updateTrackBlock May 05 14:51:35 volumiogaraz volumio[856]: info: CorePlayQueue::getTrackBlock May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioRetrievevolume May 05 14:51:35 volumiogaraz volumio[856]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 05 14:51:35 volumiogaraz volumio[856]: info: Reading ALSA contributions from plugins. May 05 14:51:35 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56 from 192.168.1.107 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 8 May 05 14:51:35 volumiogaraz volumio[856]: info: Discovery: adding c2778d16-4824-4505-b0dc-0d46c9527a43 May 05 14:51:35 volumiogaraz volumio[856]: info: Discovery: Found device VolumioGaraz May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioGetState May 05 14:51:35 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioGetState May 05 14:51:35 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:35 volumiogaraz volumio[856]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 05 14:51:35 volumiogaraz volumio[856]: info: Discovery: adding 5efda425-4727-4719-9c0b-cadec7db9f27 May 05 14:51:35 volumiogaraz volumio[856]: info: Discovery: Found device Volumio May 05 14:51:35 volumiogaraz volumio[856]: info: Discovery: Connecting to remote: 192.168.15.54 May 05 14:51:35 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioGetState May 05 14:51:35 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:35 volumiogaraz volumio[856]: info: Discovery: adding 9699ed27-a5fd-47a4-ba4c-c72954b613df May 05 14:51:35 volumiogaraz volumio[856]: info: Discovery: Found device Volumio3b May 05 14:51:36 volumiogaraz volumio[856]: info: Discovery: Connecting to remote: 192.168.15.55 May 05 14:51:36 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 192.168.15.56 from 192.168.1.172 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 9 May 05 14:51:36 volumiogaraz volumio[856]: info: VolumeController:: Volume=undefined Mute =false May 05 14:51:36 volumiogaraz volumio[856]: info: CoreStateMachine::pushState May 05 14:51:36 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioPushState May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioGetState May 05 14:51:36 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioGetVisibleSources May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioGetState May 05 14:51:36 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioGetVisibleSources May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:51:36 volumiogaraz volumio[856]: info: Asound.conf file unchanged, so no further update is needed May 05 14:51:36 volumiogaraz volumio[856]: info: Output device has changed, restarting MPD May 05 14:51:36 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:36] [connect] Successful connection May 05 14:51:36 volumiogaraz volumio[856]: info: Output device has changed, restarting Shairport Sync May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:51:36 volumiogaraz sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 14:51:36 volumiogaraz sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:36 volumiogaraz sudo[1007]: pam_unix(sudo:session): session closed for user root May 05 14:51:36 volumiogaraz sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 14:51:36 volumiogaraz sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:36 volumiogaraz volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 14:51:36 volumiogaraz volumio[856]: info: ___________ START PLUGINS ___________ May 05 14:51:36 volumiogaraz volumio[856]: info: ControllerMpd::onStart: Initializing MPD May 05 14:51:36 volumiogaraz volumio[856]: info: Creating MPD Configuration file May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 14:51:36 volumiogaraz volumio[856]: info: [1714913496672] CoreMusicLibrary::Adding element Serwery Mediów May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:51:36 volumiogaraz systemd[1]: Listening on mpd.socket. May 05 14:51:36 volumiogaraz sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 14:51:36 volumiogaraz sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:36 volumiogaraz sudo[1017]: pam_unix(sudo:session): session closed for user root May 05 14:51:36 volumiogaraz systemd[1]: Starting Music Player Daemon... May 05 14:51:36 volumiogaraz sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 14:51:36 volumiogaraz sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:51:36 volumiogaraz systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 05 14:51:36 volumiogaraz systemd[1]: mpd.service: Succeeded. May 05 14:51:36 volumiogaraz systemd[1]: Stopped Music Player Daemon. May 05 14:51:36 volumiogaraz systemd[1]: Starting Music Player Daemon... May 05 14:51:36 volumiogaraz volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:51:36 volumiogaraz volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 14:51:36 volumiogaraz volumio[856]: info: [1714913496942] CoreMusicLibrary::Adding element Last_100 May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 14:51:36 volumiogaraz volumio[856]: info: [1714913496950] CoreMusicLibrary::Adding element Webradio May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:51:36 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 14:51:37 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 05 14:51:37 volumiogaraz volumio[856]: info: Volumio Calling Home May 05 14:51:37 volumiogaraz sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh May 05 14:51:37 volumiogaraz sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:37 volumiogaraz sudo[1033]: pam_unix(sudo:session): session closed for user root May 05 14:51:37 volumiogaraz sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service May 05 14:51:37 volumiogaraz sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:37 volumiogaraz volumio[856]: info: Preparing to generate the ALSA configuration file May 05 14:51:37 volumiogaraz volumio[856]: info: Discovery: Connected to remote: 192.168.15.54 May 05 14:51:37 volumiogaraz volumio[856]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 05 14:51:37 volumiogaraz volumio[856]: info: Reading ALSA contributions from plugins. May 05 14:51:37 volumiogaraz volumio[856]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh May 05 14:51:37 volumiogaraz volumio[856]: info: MPD Permissions set May 05 14:51:37 volumiogaraz volumio[856]: info: MPD Permissions set May 05 14:51:37 volumiogaraz volumio-remote-updater[517]: [2024-05-05 14:51:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714913496 101 May 05 14:51:37 volumiogaraz volumio[856]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 10 May 05 14:51:37 volumiogaraz volumio[856]: info: Volumio called home May 05 14:51:37 volumiogaraz volumio[856]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh May 05 14:51:37 volumiogaraz volumio[856]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 May 05 14:51:37 volumiogaraz volumio[856]: info: Discovery: Connected to remote: 192.168.15.55 May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz volumio[856]: info: Starting Shairport Sync May 05 14:51:38 volumiogaraz volumio[856]: info: Starting Shairport Sync May 05 14:51:38 volumiogaraz volumio[856]: info: Starting Shairport Sync May 05 14:51:38 volumiogaraz sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 14:51:38 volumiogaraz sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:38 volumiogaraz sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 14:51:38 volumiogaraz sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:38 volumiogaraz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 14:51:38 volumiogaraz systemd[1]: shairport-sync.service: Succeeded. May 05 14:51:38 volumiogaraz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 14:51:38 volumiogaraz sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 14:51:38 volumiogaraz sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:38 volumiogaraz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 14:51:38 volumiogaraz volumio[856]: info: Asound.conf file unchanged, so no further update is needed May 05 14:51:38 volumiogaraz volumio[856]: info: Output device has changed, restarting MPD May 05 14:51:38 volumiogaraz sudo[1050]: pam_unix(sudo:session): session closed for user root May 05 14:51:38 volumiogaraz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 14:51:38 volumiogaraz systemd[1]: shairport-sync.service: Succeeded. May 05 14:51:38 volumiogaraz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 14:51:38 volumiogaraz volumio[856]: info: Output device has changed, restarting Shairport Sync May 05 14:51:38 volumiogaraz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:38 volumiogaraz sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 14:51:38 volumiogaraz sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:38 volumiogaraz sudo[1052]: pam_unix(sudo:session): session closed for user root May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:51:38 volumiogaraz sudo[1056]: pam_unix(sudo:session): session closed for user root May 05 14:51:38 volumiogaraz sudo[1061]: pam_unix(sudo:session): session closed for user root May 05 14:51:38 volumiogaraz sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 14:51:38 volumiogaraz sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:38 volumiogaraz volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 14:51:38 volumiogaraz systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 05 14:51:38 volumiogaraz systemd[1]: mpd.service: Succeeded. May 05 14:51:38 volumiogaraz systemd[1]: Stopped Music Player Daemon. May 05 14:51:38 volumiogaraz volumio[856]: info: MPD Permissions set May 05 14:51:38 volumiogaraz volumio[856]: info: Shairport-Sync Started May 05 14:51:38 volumiogaraz volumio[856]: Error adding Membership: Error: addMembership EINVAL May 05 14:51:38 volumiogaraz volumio[856]: info: Shairport-Sync Started May 05 14:51:38 volumiogaraz systemd[1]: Starting Music Player Daemon... May 05 14:51:38 volumiogaraz volumio[856]: info: CoreCommandRouter::volumioGetState May 05 14:51:38 volumiogaraz volumio[856]: info: CorePlayQueue::getTrack 0 May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:51:39 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 14:51:39 volumiogaraz volumio[856]: info: Starting Shairport Sync May 05 14:51:39 volumiogaraz sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 14:51:39 volumiogaraz sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:39 volumiogaraz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 14:51:39 volumiogaraz systemd[1]: shairport-sync.service: Succeeded. May 05 14:51:39 volumiogaraz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 14:51:39 volumiogaraz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 14:51:39 volumiogaraz sudo[1078]: pam_unix(sudo:session): session closed for user root May 05 14:51:39 volumiogaraz sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 14:51:39 volumiogaraz sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:39 volumiogaraz volumio[856]: info: Shairport-Sync Started May 05 14:51:39 volumiogaraz sudo[1083]: pam_unix(sudo:session): session closed for user root May 05 14:51:39 volumiogaraz sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 14:51:39 volumiogaraz sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:39 volumiogaraz sudo[1085]: pam_unix(sudo:session): session closed for user root May 05 14:51:39 volumiogaraz sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 05 14:51:39 volumiogaraz sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:51:39 volumiogaraz systemd[1]: Started UPnP Renderer front-end to MPD. May 05 14:51:39 volumiogaraz sudo[1090]: pam_unix(sudo:session): session closed for user root May 05 14:51:39 volumiogaraz volumio[856]: info: Upmpdcli Daemon Started May 05 14:51:41 volumiogaraz volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 05 14:51:41 volumiogaraz volumio[856]: verbose: ControllerMpd::sendMpdCommand update May 05 14:51:41 volumiogaraz volumio[856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 14:51:41 volumiogaraz volumio[856]: TypeError: Cannot read property 'then' of undefined May 05 14:51:41 volumiogaraz volumio[856]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) May 05 14:51:41 volumiogaraz volumio[856]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) May 05 14:51:41 volumiogaraz volumio[856]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) May 05 14:51:41 volumiogaraz volumio[856]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26) May 05 14:51:41 volumiogaraz volumio[856]: at Socket.emit (events.js:315:20) May 05 14:51:41 volumiogaraz volumio[856]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 05 14:51:41 volumiogaraz volumio[856]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 05 14:51:41 volumiogaraz volumio[856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 14:51:42 volumiogaraz mpd[1072]: May 05 14:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 14:51:42 volumiogaraz systemd[1]: Started Music Player Daemon. May 05 14:51:42 volumiogaraz sudo[1065]: pam_unix(sudo:session): session closed for user root May 05 14:51:42 volumiogaraz sudo[1009]: pam_unix(sudo:session): session closed for user root May 05 14:51:42 volumiogaraz sudo[1019]: pam_unix(sudo:session): session closed for user root May 05 14:51:42 volumiogaraz systemd[1]: Started MPD OLED Plugin. May 05 14:51:42 volumiogaraz sudo[1035]: pam_unix(sudo:session): session closed for user root May 05 14:51:43 volumiogaraz sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-05 14:50 May 05 14:51:43 volumiogaraz sudo[1107]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"