-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Fri 2025-01-31 16:10:59 EST. -- Jan 31 16:09:32 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 31 16:09:32 volumio kernel: hub 1-1:1.0: USB hub found Jan 31 16:09:32 volumio kernel: hub 1-1:1.0: 4 ports detected Jan 31 16:09:32 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 31 16:09:32 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Jan 31 16:09:32 volumio initramfs: Doing a 5 second delay here to give kernel load a headstart Jan 31 16:09:32 volumio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Jan 31 16:09:32 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Jan 31 16:09:32 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 31 16:09:32 volumio kernel: hub 1-1.1:1.0: USB hub found Jan 31 16:09:32 volumio kernel: hub 1-1.1:1.0: 3 ports detected Jan 31 16:09:32 volumio kernel: dwc_otg_handle_wakeup_detected_intr lxstate = 2 Jan 31 16:09:32 volumio kernel: usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg Jan 31 16:09:32 volumio kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00 Jan 31 16:09:32 volumio kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 31 16:09:32 volumio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed Jan 31 16:09:32 volumio kernel: libphy: lan78xx-mdiobus: probed Jan 31 16:09:32 volumio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64 Jan 31 16:09:32 volumio initramfs: Checking for USB updates Jan 31 16:09:32 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jan 31 16:09:32 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 31 16:09:32 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 31 16:09:32 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 31 16:09:32 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Jan 31 16:09:32 volumio initramfs: With Option: Jan 31 16:09:32 volumio initramfs: VOLUMIO_VERSION="3.449" Jan 31 16:09:32 volumio initramfs: Finish initramfs, continue booting Volumio Jan 31 16:09:32 volumio systemd[1]: System time before build time, advancing clock. Jan 31 16:09:32 volumio kernel: NET: Registered protocol family 10 Jan 31 16:09:32 volumio kernel: Segment Routing with IPv6 Jan 31 16:09:32 volumio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Jan 31 16:09:32 volumio systemd[1]: Detected architecture arm. Jan 31 16:09:32 volumio systemd[1]: Set hostname to . Jan 31 16:09:32 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 31 16:09:32 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 31 16:09:32 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 31 16:09:32 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 31 16:09:32 volumio systemd[1]: Listening on Journal Socket. Jan 31 16:09:32 volumio systemd[1]: Started dynamicswap service. Jan 31 16:09:32 volumio systemd[1]: Starting Remount Root and Kernel File Systems... Jan 31 16:09:32 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 31 16:09:32 volumio systemd[1]: Listening on fsck to fsckd communication Socket. Jan 31 16:09:32 volumio systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Jan 31 16:09:32 volumio kernel: i2c /dev entries driver Jan 31 16:09:32 volumio systemd-journald[189]: Journal started Jan 31 16:09:32 volumio systemd-journald[189]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 7.5M, max 30.0M, 22.5M free. Jan 31 16:09:32 volumio systemd-modules-load[196]: Inserted module 'i2c_dev' Jan 31 16:09:32 volumio fake-hwclock[191]: Fri Jan 31 21:09:32 UTC 2025 Jan 31 16:09:32 volumio systemd[1]: Mounted Kernel Configuration File System. Jan 31 16:09:32 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 31 16:09:32 volumio systemd[1]: Started Apply Kernel Variables. Jan 31 16:09:32 volumio systemd[1]: Started Create System Users. Jan 31 16:09:32 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Jan 31 16:09:32 volumio systemd[1]: Started Create Static Device Nodes in /dev. Jan 31 16:09:32 volumio systemd[1]: Starting udev Kernel Device Manager... Jan 31 16:09:32 volumio systemd[1]: Reached target Local File Systems (Pre). Jan 31 16:09:32 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 31 16:09:32 volumio systemd[1]: Mounting /var/log... Jan 31 16:09:32 volumio systemd[1]: Mounting /var/spool/cups... Jan 31 16:09:32 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 31 16:09:32 volumio systemd[1]: Mounting /tmp... Jan 31 16:09:32 volumio systemd[1]: Mounted /var/log. Jan 31 16:09:32 volumio systemd[1]: Mounted /var/spool/cups. Jan 31 16:09:32 volumio systemd[1]: Mounting /var/spool/cups/tmp... Jan 31 16:09:32 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Jan 31 16:09:32 volumio systemd-udevd[213]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 31 16:09:32 volumio systemd[1]: Mounted /tmp. Jan 31 16:09:32 volumio systemd[1]: Mounted /var/spool/cups/tmp. Jan 31 16:09:32 volumio systemd[1]: Started udev Coldplug all Devices. Jan 31 16:09:32 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 31 16:09:32 volumio systemd[1]: Started udev Kernel Device Manager. Jan 31 16:09:32 volumio systemd[1]: Starting Show Plymouth Boot Screen... Jan 31 16:09:32 volumio systemd-journald[189]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 7.5M, max 30.0M, 22.5M free. Jan 31 16:09:32 volumio systemd[1]: Started Flush Journal to Persistent Storage. Jan 31 16:09:32 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 31 16:09:32 volumio systemd[1]: Received SIGRTMIN+20 from PID 233 (plymouthd). Jan 31 16:09:32 volumio systemd[1]: Started Show Plymouth Boot Screen. Jan 31 16:09:32 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 31 16:09:32 volumio systemd[1]: Reached target Local Encrypted Volumes. Jan 31 16:09:32 volumio systemd[1]: Reached target Paths. Jan 31 16:09:32 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 31 16:09:32 volumio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 16:09:32 volumio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 31 16:09:32 volumio kernel: [vc_sm_connected_init]: start Jan 31 16:09:32 volumio kernel: [vc_sm_connected_init]: installed successfully Jan 31 16:09:32 volumio kernel: mc: Linux media interface: v0.10 Jan 31 16:09:33 volumio kernel: videodev: Linux video capture interface: v2.00 Jan 31 16:09:33 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 16:09:33 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 16:09:33 volumio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 16:09:33 volumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 16:09:33 volumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 16:09:33 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 31 16:09:33 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 31 16:09:33 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 31 16:09:33 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 31 16:09:33 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 31 16:09:33 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 31 16:09:33 volumio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 31 16:09:33 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 31 16:09:33 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 31 16:09:33 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 16:09:33 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 31 16:09:33 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 31 16:09:33 volumio systemd[1]: Found device /dev/mmcblk0p1. Jan 31 16:09:33 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 31 16:09:34 volumio systemd-udevd[222]: Using default interface naming scheme 'v240'. Jan 31 16:09:34 volumio systemd-udevd[222]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 31 16:09:34 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 31 16:09:34 volumio systemd-udevd[223]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 31 16:09:34 volumio kernel: cfg80211: loaded regulatory.db is malformed or signature is missing/invalid Jan 31 16:09:34 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jan 31 16:09:34 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 31 16:09:34 volumio kernel: usbcore: registered new interface driver brcmfmac Jan 31 16:09:34 volumio kernel: random: crng init done Jan 31 16:09:34 volumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Jan 31 16:09:34 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 31 16:09:34 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 31 16:09:34 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e Jan 31 16:09:35 volumio systemd-udevd[225]: Using default interface naming scheme 'v240'. Jan 31 16:09:35 volumio systemd-udevd[225]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 31 16:09:36 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Jan 31 16:09:36 volumio systemd[1]: Condition check resulted in 7800 being skipped. Jan 31 16:09:36 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 31 16:09:36 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 31 16:09:36 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 31 16:09:36 volumio systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jan 31 16:09:36 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 31 16:09:36 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 31 16:09:36 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jan 31 16:09:36 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 31 16:09:37 volumio systemd[1]: Started File System Check Daemon to report status. Jan 31 16:09:37 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Jan 31 16:09:37 volumio systemd-fsck[366]: fsck.fat 4.1 (2017-01-24) Jan 31 16:09:37 volumio systemd-fsck[366]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 31 16:09:37 volumio systemd-fsck[366]: Automatically removing dirty bit. Jan 31 16:09:37 volumio systemd-fsck[366]: Performing changes. Jan 31 16:09:37 volumio systemd-fsck[366]: /dev/mmcblk0p1: 305 files, 109534/123044 clusters Jan 31 16:09:37 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1. Jan 31 16:09:37 volumio systemd[1]: Mounting /boot... Jan 31 16:09:37 volumio systemd[1]: Mounted /boot. Jan 31 16:09:37 volumio systemd[1]: Reached target Local File Systems. Jan 31 16:09:37 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 31 16:09:37 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 31 16:09:37 volumio systemd[1]: Started ifup for eth0. Jan 31 16:09:37 volumio systemd[1]: Starting Preprocess NFS configuration... Jan 31 16:09:37 volumio systemd[1]: Starting Create Volatile Files and Directories... Jan 31 16:09:37 volumio systemd[1]: Starting Raise network interfaces... Jan 31 16:09:37 volumio systemd[1]: Started ifup for wlan0. Jan 31 16:09:37 volumio systemd[1]: plymouth-read-write.service: Succeeded. Jan 31 16:09:37 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 31 16:09:37 volumio systemd[1]: Received SIGRTMIN+20 from PID 233 (plymouthd). Jan 31 16:09:37 volumio systemd[1]: nfs-config.service: Succeeded. Jan 31 16:09:37 volumio systemd[1]: Started Preprocess NFS configuration. Jan 31 16:09:37 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 31 16:09:37 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 31 16:09:37 volumio systemd[1]: Reached target NFS client services. Jan 31 16:09:37 volumio systemd[1]: Started Create Volatile Files and Directories. Jan 31 16:09:37 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 31 16:09:37 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 31 16:09:37 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 31 16:09:37 volumio systemd[1]: Starting RPC bind portmap service... Jan 31 16:09:37 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 31 16:09:37 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 31 16:09:37 volumio systemd[1]: Reached target System Initialization. Jan 31 16:09:37 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 31 16:09:37 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 31 16:09:37 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 31 16:09:37 volumio systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2025-02-25 06:32:00 EST as it is in the future. Jan 31 16:09:37 volumio systemd[1]: Started Daily apt download activities. Jan 31 16:09:37 volumio systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2025-02-25 06:19:41 EST as it is in the future. Jan 31 16:09:37 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jan 31 16:09:37 volumio systemd[1]: Reached target Timers. Jan 31 16:09:37 volumio systemd[1]: Listening on mpd.socket. Jan 31 16:09:37 volumio systemd[1]: Listening on triggerhappy.socket. Jan 31 16:09:37 volumio systemd[1]: Reached target Sockets. Jan 31 16:09:37 volumio systemd[1]: Reached target Basic System. Jan 31 16:09:37 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 31 16:09:37 volumio systemd[1]: Starting Login Service... Jan 31 16:09:37 volumio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 31 16:09:37 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 31 16:09:37 volumio systemd[1]: Starting dhcpcd on all interfaces... Jan 31 16:09:37 volumio systemd[1]: Started Manage Sound Card State (restore and store). Jan 31 16:09:37 volumio systemd[1]: Starting Save/Restore Sound Card State... Jan 31 16:09:37 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 31 16:09:37 volumio systemd[1]: Started D-Bus System Message Bus. Jan 31 16:09:37 volumio alsactl[456]: alsactl 1.1.8 daemon started Jan 31 16:09:38 volumio dhcpcd[455]: Not running dhcpcd because /etc/network/interfaces Jan 31 16:09:38 volumio dhcpcd[455]: defines some interfaces that will use a Jan 31 16:09:38 volumio dhcpcd[455]: DHCP client or static address Jan 31 16:09:38 volumio systemd[1]: Starting WPA supplicant... Jan 31 16:09:38 volumio systemd[1]: Starting Wireless Services... Jan 31 16:09:38 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 31 16:09:38 volumio systemd[1]: Started Volumio Iptables Module. Jan 31 16:09:38 volumio sh[373]: eth0: waiting for carrier Jan 31 16:09:38 volumio dhcpcd[420]: eth0: waiting for carrier Jan 31 16:09:38 volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Jan 31 16:09:38 volumio systemd[1]: Started volumio-remote-updater.service. Jan 31 16:09:38 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Jan 31 16:09:38 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 31 16:09:38 volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 31 16:09:38 volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 31 16:09:38 volumio systemd[1]: Failed to start dhcpcd on all interfaces. Jan 31 16:09:38 volumio systemd[1]: Started Save/Restore Sound Card State. Jan 31 16:09:38 volumio systemd[1]: Reached target Sound Card. Jan 31 16:09:38 volumio thd[486]: Unable to parse trigger line: Jan 31 16:09:38 volumio thd[486]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 31 16:09:38 volumio thd[486]: Unable to parse trigger line: Jan 31 16:09:38 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 31 16:09:38 volumio avahi-daemon[459]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 31 16:09:38 volumio avahi-daemon[459]: Successfully dropped root privileges. Jan 31 16:09:38 volumio avahi-daemon[459]: avahi-daemon 0.7 starting up. Jan 31 16:09:38 volumio systemd-logind[451]: New seat seat0. Jan 31 16:09:39 volumio kernel: uart-pl011 3f201000.serial: no DMA platform data Jan 31 16:09:39 volumio systemd[1]: Started Login Service. Jan 31 16:09:39 volumio avahi-daemon[459]: Successfully called chroot(). Jan 31 16:09:39 volumio avahi-daemon[459]: Successfully dropped remaining capabilities. Jan 31 16:09:39 volumio avahi-daemon[459]: No service file found in /etc/avahi/services. Jan 31 16:09:39 volumio avahi-daemon[459]: Network interface enumeration completed. Jan 31 16:09:39 volumio avahi-daemon[459]: Server startup complete. Host name is volumio.local. Local service cookie is 1869692880. Jan 31 16:09:39 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 31 16:09:39 volumio sh[379]: wlan0=wlan0 Jan 31 16:09:39 volumio systemd[1]: Started Raise network interfaces. Jan 31 16:09:39 volumio thd[486]: Found socket passed from systemd Jan 31 16:09:39 volumio systemd[1]: Started triggerhappy global hotkey daemon. Jan 31 16:09:40 volumio systemd[1]: iptables.service: Succeeded. Jan 31 16:09:40 volumio haveged[428]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 31 16:09:40 volumio haveged[428]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 31 16:09:40 volumio haveged[428]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99999 Jan 31 16:09:40 volumio haveged[428]: haveged: fills: 0, generated: 0 Jan 31 16:09:40 volumio systemd[1]: Started RPC bind portmap service. Jan 31 16:09:40 volumio systemd[1]: Reached target RPC Port Mapper. Jan 31 16:09:40 volumio systemd[1]: Reached target Remote File Systems (Pre). Jan 31 16:09:40 volumio systemd[1]: Reached target Remote File Systems. Jan 31 16:09:40 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 31 16:09:41 volumio loadcpufreq[557]: Loading cpufreq kernel modules...done (none). Jan 31 16:09:41 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 31 16:09:41 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 31 16:09:41 volumio wpa_supplicant[469]: Successfully initialized wpa_supplicant Jan 31 16:09:41 volumio systemd[1]: Started WPA supplicant. Jan 31 16:09:41 volumio systemd[1]: Reached target Network. Jan 31 16:09:41 volumio systemd[1]: Starting OpenBSD Secure Shell server... Jan 31 16:09:41 volumio systemd[1]: Starting Music Player Daemon... Jan 31 16:09:41 volumio systemd[1]: Starting Network Time Service... Jan 31 16:09:41 volumio systemd[1]: Reached target Network is Online. Jan 31 16:09:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 31 16:09:41 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jan 31 16:09:41 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jan 31 16:09:41 volumio systemd[1]: Starting Samba NMB Daemon... Jan 31 16:09:41 volumio systemd[1]: Starting Permit User Sessions... Jan 31 16:09:41 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 31 16:09:41 volumio systemd[1]: Started /etc/rc.local Compatibility. Jan 31 16:09:41 volumio cpufrequtils[586]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 31 16:09:41 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 31 16:09:41 volumio systemd[1]: Started Permit User Sessions. Jan 31 16:09:41 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 31 16:09:41 volumio systemd[1]: Starting Hold until boot process finishes up... Jan 31 16:09:41 volumio systemd[1]: Received SIGRTMIN+21 from PID 233 (plymouthd). Jan 31 16:09:41 volumio systemd[1]: Received SIGRTMIN+21 from PID 233 (plymouthd). Jan 31 16:09:41 volumio systemd[1]: plymouth-quit.service: Succeeded. Jan 31 16:09:41 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Jan 31 16:09:41 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 31 16:09:41 volumio systemd[1]: Started Hold until boot process finishes up. Jan 31 16:09:41 volumio systemd[1]: plymouth-start.service: Succeeded. Jan 31 16:09:41 volumio ifplugd(eth0)[627]: ifplugd 0.28 initializing. Jan 31 16:09:41 volumio ifplugd(eth0)[627]: Using interface eth0/B8:27:EB:6D:6F:39 with driver (version: 5.10.92-v7+) Jan 31 16:09:41 volumio ifplugd(eth0)[627]: Using detection mode: SIOCETHTOOL Jan 31 16:09:41 volumio systemd[1]: Started Getty on tty1. Jan 31 16:09:41 volumio ifplugd(eth0)[627]: Initialization complete, link beat not detected. Jan 31 16:09:41 volumio systemd[1]: Reached target Login Prompts. Jan 31 16:09:41 volumio ifplugd[599]: Network Interface Plugging Daemon...start eth0...done. Jan 31 16:09:41 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jan 31 16:09:42 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jan 31 16:09:42 volumio ntpd[596]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 31 16:09:42 volumio ntpd[596]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 31 16:09:42 volumio systemd[1]: Started Network Time Service. Jan 31 16:09:42 volumio ntpd[633]: proto: precision = 0.573 usec (-21) Jan 31 16:09:42 volumio volumio-remote-updater[480]: Error: No active session Jan 31 16:09:42 volumio volumio-remote-updater[480]: [2025-01-31 16:09:42] [info] asio async_connect error: system:111 (Connection refused) Jan 31 16:09:42 volumio volumio-remote-updater[480]: [2025-01-31 16:09:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 31 16:09:42 volumio volumio-remote-updater[480]: [2025-01-31 16:09:42] [error] handle_connect error: Underlying Transport Error Jan 31 16:09:42 volumio ntpd[633]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 31 16:09:42 volumio ntpd[633]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 31 16:09:42 volumio ntpd[633]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 584 days ago Jan 31 16:09:42 volumio ntpd[633]: Listen and drop on 0 v6wildcard [::]:123 Jan 31 16:09:42 volumio ntpd[633]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 31 16:09:42 volumio ntpd[633]: Listen normally on 2 lo 127.0.0.1:123 Jan 31 16:09:42 volumio ntpd[633]: Listening on routing socket on fd #19 for interface updates Jan 31 16:09:42 volumio ntpd[633]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 31 16:09:42 volumio ntpd[633]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 31 16:09:42 volumio sshd[637]: Server listening on 0.0.0.0 port 22. Jan 31 16:09:42 volumio sshd[637]: Server listening on :: port 22. Jan 31 16:09:42 volumio systemd[1]: Started OpenBSD Secure Shell server. Jan 31 16:09:42 volumio volumio[454]: Could not open config: /tmp/upmpdcli.conf Jan 31 16:09:42 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 31 16:09:42 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 31 16:09:43 volumio ntpd[633]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 31 16:09:43 volumio nmbd[613]: [2025/01/31 16:09:43.898149, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 31 16:09:43 volumio nmbd[613]: started asyncdns process 643 Jan 31 16:09:43 volumio nmbd[613]: [2025/01/31 16:09:43.901835, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 31 16:09:43 volumio nmbd[613]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 31 16:09:43 volumio nmbd[613]: [2025/01/31 16:09:43.902030, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 31 16:09:43 volumio nmbd[613]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 31 16:09:44 volumio ntpd[633]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 31 16:09:44 volumio wireless.js[473]: Cleaning previous... Jan 31 16:09:44 volumio sudo[652]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 31 16:09:44 volumio sudo[652]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:44 volumio sudo[652]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:45 volumio sudo[654]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 31 16:09:45 volumio sudo[654]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:45 volumio ntpd[633]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 31 16:09:45 volumio sudo[654]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:45 volumio wireless.js[473]: Stopped aP Jan 31 16:09:45 volumio mpd[629]: Jan 31 16:09 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 31 16:09:45 volumio sudo[661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 31 16:09:45 volumio sudo[661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:45 volumio sudo[661]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:45 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 31 16:09:45 volumio sudo[663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 31 16:09:45 volumio sudo[663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:45 volumio sudo[663]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:45 volumio sudo[670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 31 16:09:45 volumio sudo[670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:45 volumio sudo[670]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:45 volumio sudo[672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 31 16:09:45 volumio sudo[672]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:46 volumio ntpd[633]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 31 16:09:46 volumio kernel: Bluetooth: Core ver 2.22 Jan 31 16:09:46 volumio kernel: NET: Registered protocol family 31 Jan 31 16:09:46 volumio kernel: Bluetooth: HCI device and connection manager initialized Jan 31 16:09:46 volumio kernel: Bluetooth: HCI socket layer initialized Jan 31 16:09:46 volumio kernel: Bluetooth: L2CAP socket layer initialized Jan 31 16:09:46 volumio kernel: Bluetooth: SCO socket layer initialized Jan 31 16:09:46 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Jan 31 16:09:46 volumio kernel: Bluetooth: HCI UART protocol H4 registered Jan 31 16:09:46 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 31 16:09:46 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 31 16:09:46 volumio btuart[453]: bcm43xx_init Jan 31 16:09:46 volumio btuart[453]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Jan 31 16:09:46 volumio btuart[453]: Set BDADDR UART: b8:27:eb:c7:c5:93 Jan 31 16:09:46 volumio btuart[453]: Set Controller UART speed to 3000000 bit/s Jan 31 16:09:46 volumio btuart[453]: Device setup complete Jan 31 16:09:46 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 31 16:09:46 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 31 16:09:46 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Jan 31 16:09:46 volumio systemd[1]: Created slice system-bthelper.slice. Jan 31 16:09:46 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 31 16:09:46 volumio bthelper[685]: Raspberry Pi BDADDR already set Jan 31 16:09:46 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Jan 31 16:09:46 volumio systemd[1]: Starting Bluetooth service... Jan 31 16:09:46 volumio bluetoothd[695]: Bluetooth daemon 5.50 Jan 31 16:09:46 volumio bluetoothd[695]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 31 16:09:46 volumio systemd[1]: Started Bluetooth service. Jan 31 16:09:46 volumio bluetoothd[695]: Starting SDP server Jan 31 16:09:46 volumio bluetoothd[695]: Excluding (cli) sap Jan 31 16:09:46 volumio systemd[1]: Reached target Bluetooth. Jan 31 16:09:46 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 31 16:09:46 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jan 31 16:09:46 volumio kernel: Bluetooth: BNEP socket layer initialized Jan 31 16:09:46 volumio bluetoothd[695]: Bluetooth management interface 1.18 initialized Jan 31 16:09:46 volumio dbus-daemon[463]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=695 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 31 16:09:46 volumio systemd[1]: Starting Hostname Service... Jan 31 16:09:46 volumio bluetoothd[695]: Failed to set privacy: Rejected (0x0b) Jan 31 16:09:46 volumio dbus-daemon[463]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 31 16:09:46 volumio systemd[1]: Started Hostname Service. Jan 31 16:09:47 volumio volumio-remote-updater[480]: [2025-01-31 16:09:47] [info] asio async_connect error: system:111 (Connection refused) Jan 31 16:09:47 volumio volumio-remote-updater[480]: [2025-01-31 16:09:47] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 31 16:09:47 volumio volumio-remote-updater[480]: [2025-01-31 16:09:47] [error] handle_connect error: Underlying Transport Error Jan 31 16:09:47 volumio mpd[629]: Jan 31 16:09 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 31 16:09:47 volumio mpd[629]: Jan 31 16:09 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 31 16:09:47 volumio systemd[1]: Started Music Player Daemon. Jan 31 16:09:48 volumio sudo[672]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:48 volumio wireless.js[473]: Start wireless flow Jan 31 16:09:48 volumio wireless.js[473]: Stopped hotspot (if there).. Jan 31 16:09:48 volumio sudo[701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 31 16:09:48 volumio sudo[701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:48 volumio sudo[701]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:48 volumio sudo[703]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 31 16:09:48 volumio sudo[703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:49 volumio sudo[703]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:49 volumio wireless.js[473]: DHCP IP Jan 31 16:09:49 volumio wireless.js[473]: Start ap Jan 31 16:09:49 volumio wpa_supplicant[705]: Successfully initialized wpa_supplicant Jan 31 16:09:49 volumio sudo[706]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 31 16:09:49 volumio sudo[706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:49 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 31 16:09:49 volumio dhcpcd[707]: dev: loaded udev Jan 31 16:09:49 volumio dhcpcd-run-hooks[725]: wlan0: starting wpa_supplicant Jan 31 16:09:49 volumio dhcpcd-run-hooks[730]: wlan0: failed to start wpa_supplicant Jan 31 16:09:49 volumio dhcpcd-run-hooks[731]: 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 Jan 31 16:09:49 volumio dhcpcd[707]: wlan0: connected to Access Point `' Jan 31 16:09:49 volumio dhcpcd[707]: no interfaces have a carrier Jan 31 16:09:49 volumio dhcpcd[707]: forked to background, child pid 741 Jan 31 16:09:49 volumio dhcpcd[741]: eth0: waiting for carrier Jan 31 16:09:49 volumio dhcpcd[741]: wlan0: waiting for carrier Jan 31 16:09:49 volumio sudo[706]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:50 volumio wireless.js[473]: trying... Jan 31 16:09:50 volumio sudo[743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:50 volumio sudo[743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:50 volumio sudo[743]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:51 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jan 31 16:09:51 volumio wireless.js[473]: trying... Jan 31 16:09:51 volumio sudo[746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:51 volumio sudo[746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:51 volumio sudo[746]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:51 volumio bthelper[685]: Changing power off succeeded Jan 31 16:09:51 volumio bthelper[685]: [CHG] Controller B8:27:EB:C7:C5:93 Class: 0x0000041c Jan 31 16:09:51 volumio bthelper[685]: Changing power on succeeded Jan 31 16:09:51 volumio kernel: cam1-reg: disabling Jan 31 16:09:51 volumio kernel: cam-dummy-reg: disabling Jan 31 16:09:52 volumio wireless.js[473]: trying... Jan 31 16:09:52 volumio wpa_supplicant[729]: wlan0: Trying to associate with SSID 'xhopheadx' Jan 31 16:09:52 volumio sudo[756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:52 volumio sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:52 volumio sudo[756]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:53 volumio wireless.js[473]: trying... Jan 31 16:09:53 volumio sudo[759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:53 volumio sudo[759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:53 volumio sudo[759]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:54 volumio wireless.js[473]: trying... Jan 31 16:09:54 volumio sudo[762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:54 volumio sudo[762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:54 volumio sudo[762]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:54 volumio volumio-remote-updater[480]: [2025-01-31 16:09:54] [info] asio async_connect error: system:111 (Connection refused) Jan 31 16:09:54 volumio volumio-remote-updater[480]: [2025-01-31 16:09:54] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 31 16:09:54 volumio volumio-remote-updater[480]: [2025-01-31 16:09:54] [error] handle_connect error: Underlying Transport Error Jan 31 16:09:55 volumio wireless.js[473]: trying... Jan 31 16:09:55 volumio sudo[765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:55 volumio sudo[765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:55 volumio sudo[765]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:55 volumio wpa_supplicant[729]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jan 31 16:09:56 volumio wireless.js[473]: trying... Jan 31 16:09:56 volumio sudo[768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:56 volumio sudo[768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:56 volumio sudo[768]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:57 volumio wireless.js[473]: trying... Jan 31 16:09:57 volumio sudo[771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:57 volumio sudo[771]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:57 volumio sudo[771]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:58 volumio wireless.js[473]: trying... Jan 31 16:09:58 volumio sudo[774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:58 volumio sudo[774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:58 volumio sudo[774]: pam_unix(sudo:session): session closed for user root Jan 31 16:09:58 volumio wpa_supplicant[729]: wlan0: Trying to associate with SSID 'xhopheadx' Jan 31 16:09:59 volumio wireless.js[473]: trying... Jan 31 16:09:59 volumio sudo[777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:09:59 volumio sudo[777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:09:59 volumio sudo[777]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:00 volumio wireless.js[473]: trying... Jan 31 16:10:00 volumio sudo[780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:00 volumio sudo[780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:00 volumio sudo[780]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:01 volumio wireless.js[473]: trying... Jan 31 16:10:01 volumio sudo[783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:01 volumio sudo[783]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:01 volumio sudo[783]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:02 volumio wireless.js[473]: trying... Jan 31 16:10:02 volumio sudo[786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:02 volumio sudo[786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:02 volumio sudo[786]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: carrier acquired Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: connected to Access Point `xhopheadx' Jan 31 16:10:02 volumio wpa_supplicant[729]: wlan0: Associated with 00:1e:2a:e3:f1:5e Jan 31 16:10:02 volumio wpa_supplicant[729]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:1e:2a:e3:f1:5e completed [id=0 id_str=] Jan 31 16:10:02 volumio wpa_supplicant[729]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 31 16:10:02 volumio dhcpcd[741]: DUID 00:01:00:01:2b:b8:6c:61:b8:27:eb:38:3a:6c Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: IAID eb:38:3a:6c Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: adding address fe80::75eb:1437:f6eb:3d7d Jan 31 16:10:02 volumio dhcpcd[741]: ipv6_addaddr1: Permission denied Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: carrier lost Jan 31 16:10:02 volumio wpa_supplicant[729]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: carrier acquired Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: IAID eb:38:3a:6c Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: soliciting an IPv6 router Jan 31 16:10:02 volumio dhcpcd[741]: wlan0: soliciting a DHCP lease Jan 31 16:10:03 volumio wireless.js[473]: trying... Jan 31 16:10:03 volumio sudo[803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:03 volumio sudo[803]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:03 volumio sudo[803]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:04 volumio wireless.js[473]: trying... Jan 31 16:10:04 volumio sudo[806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:04 volumio sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:04 volumio sudo[806]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:04 volumio volumio-remote-updater[480]: [2025-01-31 16:10:04] [info] asio async_connect error: system:111 (Connection refused) Jan 31 16:10:04 volumio volumio-remote-updater[480]: [2025-01-31 16:10:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 31 16:10:04 volumio volumio-remote-updater[480]: [2025-01-31 16:10:04] [error] handle_connect error: Underlying Transport Error Jan 31 16:10:05 volumio wireless.js[473]: trying... Jan 31 16:10:05 volumio sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:05 volumio sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:05 volumio sudo[809]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:06 volumio wireless.js[473]: trying... Jan 31 16:10:06 volumio sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:06 volumio sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:06 volumio sudo[812]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:07 volumio wireless.js[473]: trying... Jan 31 16:10:07 volumio sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:07 volumio sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:07 volumio sudo[815]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:07 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 31 16:10:07 volumio dhcpcd[741]: wlan0: probing for an IPv4LL address Jan 31 16:10:08 volumio sh[373]: timed out Jan 31 16:10:08 volumio dhcpcd[420]: timed out Jan 31 16:10:08 volumio sh[373]: dhcpcd exited Jan 31 16:10:08 volumio dhcpcd[420]: dhcpcd exited Jan 31 16:10:08 volumio sh[373]: ifup: failed to bring up eth0 Jan 31 16:10:08 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 31 16:10:08 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 31 16:10:08 volumio wireless.js[473]: trying... Jan 31 16:10:08 volumio sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:08 volumio sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:08 volumio sudo[819]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:09 volumio wireless.js[473]: trying... Jan 31 16:10:09 volumio sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 31 16:10:09 volumio sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:09 volumio sudo[822]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:10 volumio wireless.js[473]: Overtime, starting plan B Jan 31 16:10:10 volumio systemd[1]: Started Wireless Services. Jan 31 16:10:10 volumio systemd[1]: Started Volumio Backend Module. Jan 31 16:10:12 volumio dhcpcd[741]: wlan0: using IPv4LL address 169.254.13.228 Jan 31 16:10:12 volumio avahi-daemon[459]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.13.228. Jan 31 16:10:12 volumio dhcpcd[741]: wlan0: adding route to 169.254.0.0/16 Jan 31 16:10:12 volumio dhcpcd[741]: wlan0: adding default route Jan 31 16:10:12 volumio avahi-daemon[459]: New relevant interface wlan0.IPv4 for mDNS. Jan 31 16:10:12 volumio avahi-daemon[459]: Registering new address record for 169.254.13.228 on wlan0.IPv4. Jan 31 16:10:12 volumio volumio[826]: info: ------------------------------------------- Jan 31 16:10:12 volumio volumio[826]: info: ----- Volumio3 ---- Jan 31 16:10:12 volumio volumio[826]: info: ------------------------------------------- Jan 31 16:10:12 volumio volumio[826]: info: ----- System startup ---- Jan 31 16:10:12 volumio volumio[826]: info: ------------------------------------------- Jan 31 16:10:13 volumio volumio[826]: info: MYVOLUMIO Environment detected Jan 31 16:10:14 volumio ntpd[633]: Listen normally on 3 wlan0 169.254.13.228:123 Jan 31 16:10:14 volumio ntpd[633]: new interface(s) found: waking up resolver Jan 31 16:10:14 volumio volumio[826]: info: Plugin folders cleanup Jan 31 16:10:14 volumio volumio[826]: info: Scanning into folder /volumio/app/plugins/ Jan 31 16:10:14 volumio volumio[826]: info: Scanning category audio_interface Jan 31 16:10:14 volumio volumio[826]: info: Scanning category miscellanea Jan 31 16:10:14 volumio volumio[826]: info: Scanning category music_service Jan 31 16:10:14 volumio volumio[826]: info: Scanning category plugins.json Jan 31 16:10:14 volumio volumio[826]: info: Scanning category system_controller Jan 31 16:10:14 volumio volumio[826]: info: Scanning category user_interface Jan 31 16:10:14 volumio volumio[826]: info: Scanning into folder /data/plugins/ Jan 31 16:10:14 volumio volumio[826]: info: Scanning category system_controller Jan 31 16:10:14 volumio volumio[826]: info: Scanning category system_hardware Jan 31 16:10:14 volumio volumio[826]: info: Scanning category user_interface Jan 31 16:10:14 volumio volumio[826]: info: Plugin folders cleanup completed Jan 31 16:10:14 volumio volumio[826]: info: ------------------------------------------- Jan 31 16:10:14 volumio volumio[826]: info: ----- Core plugins startup ---- Jan 31 16:10:14 volumio volumio[826]: info: ------------------------------------------- Jan 31 16:10:14 volumio volumio[826]: info: Loading plugins from folder /volumio/app/plugins/ Jan 31 16:10:14 volumio volumio[826]: info: Adding plugin upnp to MyMusic Plugins Jan 31 16:10:14 volumio volumio[826]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 31 16:10:14 volumio volumio[826]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 31 16:10:14 volumio volumio[826]: info: Loading plugins from folder /data/plugins/ Jan 31 16:10:14 volumio volumio[826]: info: Loading plugin "system"... Jan 31 16:10:14 volumio volumio[826]: info: Loading plugin "appearance"... Jan 31 16:10:14 volumio nmbd[613]: [2025/01/31 16:10:14.196164, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 31 16:10:14 volumio systemd[1]: Started Samba NMB Daemon. Jan 31 16:10:14 volumio nmbd[613]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 31 16:10:14 volumio systemd[1]: Starting Samba Winbind Daemon... Jan 31 16:10:14 volumio winbindd[854]: [2025/01/31 16:10:14.507112, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 31 16:10:14 volumio winbindd[854]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 31 16:10:14 volumio winbindd[854]: [2025/01/31 16:10:14.529963, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 31 16:10:14 volumio winbindd[854]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 31 16:10:14 volumio systemd[1]: Started Samba Winbind Daemon. Jan 31 16:10:14 volumio systemd[1]: Starting Samba SMB Daemon... Jan 31 16:10:14 volumio volumio-remote-updater[480]: [2025-01-31 16:10:14] [connect] Successful connection Jan 31 16:10:15 volumio winbindd[854]: [2025/01/31 16:10:15.177699, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 31 16:10:15 volumio winbindd[854]: res_names->count = 3, expected 4 Jan 31 16:10:15 volumio smbd[859]: [2025/01/31 16:10:15.205829, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 31 16:10:15 volumio systemd[1]: Started Samba SMB Daemon. Jan 31 16:10:15 volumio smbd[859]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 31 16:10:15 volumio systemd[1]: Reached target Multi-User System. Jan 31 16:10:15 volumio systemd[1]: Reached target Graphical Interface. Jan 31 16:10:15 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 31 16:10:15 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 31 16:10:15 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 31 16:10:15 volumio systemd[1]: Startup finished in 12.594s (kernel) + 45.131s (userspace) = 57.725s. Jan 31 16:10:15 volumio volumio[826]: info: Loading plugin "network"... Jan 31 16:10:15 volumio volumio[826]: info: Refreshing Cached IP Addresses Jan 31 16:10:15 volumio sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 31 16:10:15 volumio sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:15 volumio sudo[869]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:15 volumio volumio[826]: info: Loading plugin "services"... Jan 31 16:10:15 volumio sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 31 16:10:15 volumio sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:15 volumio volumio[826]: info: Loading plugin "alsa_controller"... Jan 31 16:10:15 volumio sudo[871]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:15 volumio sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 31 16:10:15 volumio sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:16 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 31 16:10:16 volumio volumio[826]: info: Loading plugin "wizard"... Jan 31 16:10:16 volumio volumio[826]: info: Loading plugin "volumio_command_line_client"... Jan 31 16:10:16 volumio volumio[826]: info: Loading plugin "upnp"... Jan 31 16:10:16 volumio volumio[826]: info: [1738357816074] Starting Upmpd Daemon Jan 31 16:10:16 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 31 16:10:16 volumio volumio[826]: info: Loading plugin "my_music"... Jan 31 16:10:16 volumio volumio[826]: info: Loading plugin "mpd"... Jan 31 16:10:16 volumio volumio[826]: info: Creating MPD Configuration file Jan 31 16:10:16 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 31 16:10:16 volumio sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 31 16:10:16 volumio sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:16 volumio sudo[882]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:16 volumio volumio[826]: info: Loading plugin "upnp_browser"... Jan 31 16:10:16 volumio sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 31 16:10:16 volumio sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:16 volumio systemd[1]: Stopping Music Player Daemon... Jan 31 16:10:16 volumio systemd[1]: mpd.service: Succeeded. Jan 31 16:10:16 volumio systemd[1]: Stopped Music Player Daemon. Jan 31 16:10:16 volumio systemd[1]: Starting Music Player Daemon... Jan 31 16:10:17 volumio volumio[826]: info: Loading plugin "networkfs"... Jan 31 16:10:17 volumio volumio[826]: info: Starting Udev Watcher for removable devices Jan 31 16:10:17 volumio sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.16:/volume1/music /mnt/NAS/NAS Jan 31 16:10:17 volumio sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:17 volumio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.200:/svr/nfs/music /mnt/NAS/chum Jan 31 16:10:17 volumio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:17 volumio volumio[826]: info: Ignoring mount for partition: boot Jan 31 16:10:17 volumio volumio[826]: info: Ignoring mount for partition: volumio Jan 31 16:10:17 volumio volumio[826]: info: Ignoring mount for partition: volumio_data Jan 31 16:10:17 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 31 16:10:17 volumio volumio[826]: info: Loading plugin "alarm-clock"... Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "airplay_emulation"... Jan 31 16:10:18 volumio volumio[826]: info: Starting Shairport Sync Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "last_100"... Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "webradio"... Jan 31 16:10:18 volumio mpd[891]: Jan 31 16:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "i2s_dacs"... Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "volumiodiscovery"... Jan 31 16:10:18 volumio volumio[826]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 31 16:10:18 volumio volumio[826]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 31 16:10:18 volumio volumio[826]: *** WARNING *** For more information see Jan 31 16:10:18 volumio volumio[826]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 31 16:10:18 volumio node[826]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 31 16:10:18 volumio volumio[826]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 31 16:10:18 volumio volumio[826]: *** WARNING *** For more information see Jan 31 16:10:18 volumio node[826]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 31 16:10:18 volumio node[826]: *** WARNING *** For more information see Jan 31 16:10:18 volumio node[826]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 31 16:10:18 volumio node[826]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 31 16:10:18 volumio node[826]: *** WARNING *** For more information see Jan 31 16:10:18 volumio volumio[826]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 31 16:10:18 volumio volumio[826]: info: Discovery: Started advertising with name: Volumio Jan 31 16:10:18 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 31 16:10:18 volumio volumio[826]: info: Plugin gpio-buttons is not enabled Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "outputs"... Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "albumart"... Jan 31 16:10:18 volumio volumio[826]: info: Plugin example_plugin is not enabled Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "inputs"... Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "updater_comm"... Jan 31 16:10:18 volumio volumio[826]: info: Plugin mpdemulation is not enabled Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "rest_api"... Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "websocket"... Jan 31 16:10:18 volumio volumio[826]: info: Loading plugin "backup_restore"... Jan 31 16:10:18 volumio volumio[826]: Forking 3 albumart workers Jan 31 16:10:19 volumio volumio[826]: info: Applying required configuration parameters for plugin backup_restore Jan 31 16:10:19 volumio volumio[826]: info: Loading plugin "rotaryencoder2"... Jan 31 16:10:19 volumio mpd[891]: Jan 31 16:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 31 16:10:19 volumio mpd[891]: Jan 31 16:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 31 16:10:19 volumio systemd[1]: Started Music Player Daemon. Jan 31 16:10:19 volumio sudo[886]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:20 volumio volumio[826]: Starting albumart workers Jan 31 16:10:20 volumio volumio[826]: Starting albumart workers Jan 31 16:10:20 volumio sudo[874]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:20 volumio volumio[826]: info: Loading plugin "randomizer"... Jan 31 16:10:20 volumio volumio[826]: Starting albumart workers Jan 31 16:10:21 volumio volumio[826]: info: Applying required configuration parameters for plugin randomizer Jan 31 16:10:21 volumio volumio[826]: info: Loading i18n strings for locale en Jan 31 16:10:21 volumio volumio[826]: Updating browse sources language Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::initPlayerControls Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 31 16:10:21 volumio volumio[826]: Express server listening on port 3000 Jan 31 16:10:21 volumio volumio[826]: [Metrics] WebUI: 9s 449.57ms Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::resetVolumioState Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::getcurrentVolume Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::volumioRetrievevolume Jan 31 16:10:21 volumio volumio[826]: info: MPD Permissions set Jan 31 16:10:21 volumio volumio[826]: info: MPD running with PID891 Jan 31 16:10:21 volumio volumio[826]: ,establishing connection Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 31 16:10:21 volumio volumio[826]: info: Reloading queue from file Jan 31 16:10:21 volumio volumio[826]: error: updateQueue error: null Jan 31 16:10:21 volumio volumio[826]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::setRepeat true single undefined Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::pushState Jan 31 16:10:21 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::setRandom null Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::pushState Jan 31 16:10:21 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Jan 31 16:10:21 volumio volumio[826]: info: Setting Device type: Raspberry PI Jan 31 16:10:21 volumio volumio[826]: info: VolumeController:: Volume=40 Mute =false Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::pushState Jan 31 16:10:21 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Jan 31 16:10:21 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::volumioRetrievevolume Jan 31 16:10:21 volumio volumio[826]: info: VolumeController:: Volume=40 Mute =false Jan 31 16:10:21 volumio volumio[826]: info: CoreStateMachine::pushState Jan 31 16:10:21 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Jan 31 16:10:21 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Jan 31 16:10:25 volumio volumio[826]: error: updateQueue error: null Jan 31 16:10:27 volumio sudo[980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 31 16:10:27 volumio sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:27 volumio sudo[980]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:27 volumio sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 31 16:10:27 volumio sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:27 volumio sudo[983]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:29 volumio volumio-remote-updater[480]: [2025-01-31 16:10:29] [connect] Successful connection Jan 31 16:10:30 volumio dhcpcd[741]: wlan0: carrier lost Jan 31 16:10:30 volumio wpa_supplicant[729]: wlan0: Associated with cc:40:d0:fa:f5:27 Jan 31 16:10:30 volumio wpa_supplicant[729]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:40:d0:fa:f5:27 completed [id=0 id_str=] Jan 31 16:10:30 volumio wpa_supplicant[729]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 31 16:10:30 volumio avahi-daemon[459]: Withdrawing address record for 169.254.13.228 on wlan0. Jan 31 16:10:30 volumio avahi-daemon[459]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.13.228. Jan 31 16:10:30 volumio avahi-daemon[459]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 31 16:10:30 volumio dhcpcd[741]: wlan0: deleting route to 169.254.0.0/16 Jan 31 16:10:30 volumio dhcpcd[741]: wlan0: deleting default route Jan 31 16:10:30 volumio dhcpcd[741]: wlan0: carrier acquired Jan 31 16:10:30 volumio dhcpcd[741]: wlan0: IAID eb:38:3a:6c Jan 31 16:10:30 volumio dhcpcd[741]: wlan0: soliciting a DHCP lease Jan 31 16:10:30 volumio volumio-remote-updater[480]: [2025-01-31 16:10:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1738357829 101 Jan 31 16:10:30 volumio volumio[826]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jan 31 16:10:30 volumio dhcpcd[741]: wlan0: soliciting an IPv6 router Jan 31 16:10:30 volumio volumio[826]: info: Discovery: A device disappeared from network Jan 31 16:10:30 volumio volumio[826]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 31 16:10:31 volumio sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 31 16:10:31 volumio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:31 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 31 16:10:31 volumio sudo[1016]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:31 volumio volumio[826]: info: Upmpdcli Daemon Started Jan 31 16:10:32 volumio ntpd[633]: Deleting interface #3 wlan0, 169.254.13.228#123, interface stats: received=0, sent=0, dropped=0, active_time=18 secs Jan 31 16:10:35 volumio dhcpcd[741]: wlan0: probing for an IPv4LL address Jan 31 16:10:36 volumio volumio[826]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Jan 31 16:10:39 volumio dhcpcd[741]: wlan0: using IPv4LL address 169.254.13.228 Jan 31 16:10:39 volumio avahi-daemon[459]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.13.228. Jan 31 16:10:39 volumio dhcpcd[741]: wlan0: adding route to 169.254.0.0/16 Jan 31 16:10:39 volumio dhcpcd[741]: wlan0: adding default route Jan 31 16:10:39 volumio avahi-daemon[459]: New relevant interface wlan0.IPv4 for mDNS. Jan 31 16:10:39 volumio avahi-daemon[459]: Registering new address record for 169.254.13.228 on wlan0.IPv4. Jan 31 16:10:41 volumio ntpd[633]: Listen normally on 4 wlan0 169.254.13.228:123 Jan 31 16:10:41 volumio ntpd[633]: new interface(s) found: waking up resolver Jan 31 16:10:42 volumio volumio[1018]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 31 16:10:45 volumio dhcpcd[741]: wlan0: offered 192.168.1.32 from 192.168.1.1 Jan 31 16:10:47 volumio dhcpcd[741]: wlan0: ignoring offer of 192.168.1.32 from 192.168.1.1 Jan 31 16:10:50 volumio dhcpcd[741]: wlan0: ignoring offer of 192.168.1.32 from 192.168.1.1 Jan 31 16:10:51 volumio volumio[826]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion Jan 31 16:10:51 volumio volumio[826]: info: Preparing to generate the ALSA configuration file Jan 31 16:10:53 volumio dhcpcd[741]: wlan0: probing address 192.168.1.32/24 Jan 31 16:10:53 volumio volumio[826]: info: Asound.conf file unchanged, so no further update is needed Jan 31 16:10:53 volumio volumio[826]: info: Output device has changed, restarting MPD Jan 31 16:10:53 volumio sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 31 16:10:53 volumio sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:53 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:53 volumio volumio[826]: info: Output device has changed, restarting Shairport Sync Jan 31 16:10:53 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:53 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 31 16:10:53 volumio sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 31 16:10:53 volumio sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:53 volumio systemd[1]: Stopping Music Player Daemon... Jan 31 16:10:54 volumio volumio[826]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 31 16:10:54 volumio volumio[826]: info: ___________ START PLUGINS ___________ Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 31 16:10:54 volumio volumio[826]: info: [1738357854041] CoreMusicLibrary::Adding element Media Servers Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 31 16:10:54 volumio volumio[826]: Cannot find translation for sourceMedia Servers Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 31 16:10:54 volumio systemd[1]: mpd.service: Succeeded. Jan 31 16:10:54 volumio systemd[1]: Stopped Music Player Daemon. Jan 31 16:10:54 volumio systemd[1]: Starting Music Player Daemon... Jan 31 16:10:54 volumio volumio[826]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 31 16:10:54 volumio volumio[826]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 31 16:10:54 volumio volumio[826]: info: [1738357854278] CoreMusicLibrary::Adding element Last_100 Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 31 16:10:54 volumio volumio[826]: Cannot find translation for sourceMedia Servers Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 31 16:10:54 volumio volumio[826]: info: [1738357854352] CoreMusicLibrary::Adding element Webradio Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 31 16:10:54 volumio volumio[826]: Cannot find translation for sourceMedia Servers Jan 31 16:10:54 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] loadI18nStrings: /data/plugins/system_hardware/rotaryencoder2/i18n/strings_en.json Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] loadI18nStrings: loaded: {"ROTARYENCODER2":{"CONFIG":"Rotary Encoder II Plugin Configuration","SAVE":"Save","ENCODER0":"Rotary Encoder 1","D_ENCODER0":"Settings for the first rotary encoder.","ENCODER1":"Rotary Encoder 2","D_ENCODER1":"Settings for the second rotary encoder.","ENCODER2":"Rotary Encoder 3","D_ENCODER2":"Settings for the third rotary encoder.","ROTARYTYPE":"Periods per tick","D_ROTARYTYPE":"Periods per tick (Full: A and B full period, half: A and B half period, quarter: A or B half period","FULL":"Full-period mode","HALF":"Half-period mode","QUARTER":"Quarter-period mode","PINA":"Pin A GPIO","D_PINA":"GPIO pin that is connected to the first pin of the rotary.","PINB":"Pin B GPIO","D_PINB":"GPIO pin that is connected to the second pin of the rotary.","DIALACTION":"Dial Action","D_DIALACTION":"Action to be triggered by turning the rotary encocer. Option 'Emit Websocket Message' can be used to control other plugins using the Volumio Websock calls between Plugins. (see https://volumio.github.io/docs/API/WebSocket_APIs.html section 'CallMethod on Plugin' for details)","DOTS":"...","VOLUME":"Volume","SKIP":"Prev/Next title","SEEK":"Seek in title","SCROLL":"Scroll","EMIT":"Emit Websocket Message","SOCKCMDCCW":"Command CCW","SOCKDATACCW":"Data CCW","SOCKCMDCW":"Command CW","SOCKDATACW":"Data CW","SOCKCMD":"Command","SOCKDATA":"Data","D_SOCKCMD":"Command to send via Websocket","D_SOCKDATA":"Data to include with the Websocket Command","PINPUSH":"Pushbutton GPIO","D_PINPUSH":"GPIO pin that is connected to the pushbutton pin of the rotary. 0 or empty to disable.","PINPUSHDEBOUNCE":"Debounce-time (ms)","D_PINPUSHDEBOUNCE":"Debounce time for the button. If the button has hardware-debouncing, set to 0.","PUSHSTATE":"Button logic-level active low","D_PUSHSTATE":"Activate this, if pressing the button pulls the logic level on the GPIO low.","PINPUSHDELAY":"Delay for a long button press (ms)","D_PINPUSHDEDELAY":"Minimum duration of a button press, that will trigger the long push action in ms","PINDOUBLEPUSHDELAY":"Maximum interval for double press (ms)","D_PINDOUBLEPUSHDEDELAY":"Time interval in ms, within which a double press has to be detected.","PUSHACTION":"Short Press Action","D_PUSHACTION":"Action that gets triggered, when pushbutton is pressed briefly.","LONGPUSHACTION":"Long Press Action","D_LONGPUSHACTION":"Action that gets triggered, when pushbutton is pressed longer.","DOUBLEPUSHACTION":"Double Press Action","D_DOUBLEPUSHACTION":"Action that gets triggered, when pushbutton is pressed twice quickly.","SET_DEBUG":"Debug Settings","D_SET_DEBUG":"Settings for functional debugging.","PLAY":"Play","PAUSE":"Pause","PLAYPAUSE":"Play/Pause toggle","STOP":"Stop","REPEAT":"Repeat","RANDOM":"Random","CLEARQUEUE":"Clear Queue","MUTE":"Mute","UNMUTE":"Unmute","TOGGLEMUTE":"Toggle Mute","SHUTDOWN":"System Shutdown","REBOOT":"System Reboot","RESTARTAPP":"Restart Application","DUMPLOG":"Dump logfile","LOGGING":"Logging","D_LOGGING":"Switch the output of log messages on or off. Error messages are always logged.","TOAST_START_SUCCESS":"Plugin successfully started.","TOAST_STOP_SUCCESS":"Plugin successfully stopped.","TOAST_START_FAIL":"Plugin failed to start.","TOAST_STOP_FAIL":"Plugin failed to stop.","TOAST_GPIO_BLOCKED":"GPIO Pin unavailable","TOAST_MSG_OVERLAY_BLOCKING":"Blocked by another overlay.","TOAST_SAVE_SUCCESS":"Successfully saved","TOAST_MSG_SAVE":"Settings for Encoder ","TOAST_DEBUG_SAVE":"Debug Settings","TOAST_SAVE_FAIL":"Save failed","TOAST_WRONG_PARAMETER":"Error in parameters","TOAST_NEEDS_INTEGER":"GPIO Pins must be Integers.","TOAST_PINS_DIFFERENT":"GPIO Pins for A/B/Button must be different.","TOAST_PINS_BLOCKED":"Pins already used in another Encoder.","TOAST_NO_TYPE":"Please select 'Periods per tick'!"}} Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] onStart: Config loaded: {"syncSave":true,"autosave":true,"autosaveDelay":1000,"saved":true,"atomicSave":false,"data":{"enabled0":{"type":"boolean","value":true},"rotaryType0":{"type":"number","value":1},"pinA0":{"type":"string","value":"22"},"pinB0":{"type":"string","value":"27"},"dialAction0":{"type":"number","value":1},"socketCmdCW0":{"type":"string","value":""},"socketDataCW0":{"type":"string","value":""},"socketCmdCCW0":{"type":"string","value":""},"socketDataCCW0":{"type":"string","value":""},"pinPush0":{"type":"number","value":17},"pinPushDebounce0":{"type":"number","value":10},"pushState0":{"type":"boolean","value":true},"pushAction0":{"type":"number","value":3},"socketCmdPush0":{"type":"string","value":""},"socketDataPush0":{"type":"string","value":""},"longPushAction0":{"type":"number","value":0},"socketCmdLongPush0":{"type":"string","value":""},"socketDataLongPush0":{"type":"string","value":""},"delayLongPush0":{"type":"string","value":"1500"},"doublePushAction0":{"type":"number","value":0},"socketCmdDoublePush0":{"type":"string","value":""},"socketDataDoublePush0":{"type":"string","value":""},"delayDoublePush0":{"type":"string","value":"700"},"enabled1":{"type":"boolean","value":false},"rotaryType1":{"type":"number","value":0},"pinA1":{"type":"string","value":""},"pinB1":{"type":"string","value":""},"dialAction1":{"type":"number","value":0},"socketCmdCW1":{"type":"string","value":""},"socketDataCW1":{"type":"string","value":""},"socketCmdCCW1":{"type":"string","value":""},"socketDataCCW1":{"type":"string","value":""},"pinPush1":{"type":"number","value":0},"pinPushDebounce1":{"type":"number","value":0},"pushState1":{"type":"boolean","value":false},"pushAction1":{"type":"number","value":0},"socketCmdPush1":{"type":"string","value":""},"socketDataPush1":{"type":"string","value":""},"longPushAction1":{"type":"number","value":0},"socketCmdLongPush1":{"type":"string","value":""},"socketDataLongPush1":{"type":"string","value":""},"delayLongPush1":{"type":"string","value":"1500"},"doublePushAction1":{"type":"number","value":0},"socketCmdDoublePush1":{"type":"string","value":""},"socketDataDoublePush1":{"type":"string","value":""},"delayDoublePush1":{"type":"string","value":"700"},"enabled2":{"type":"boolean","value":false},"rotaryType2":{"type":"number","value":0},"pinA2":{"type":"string","value":""},"pinB2":{"type":"string","value":""},"dialAction2":{"type":"number","value":0},"socketCmdCW2":{"type":"string","value":""},"socketDataCW2":{"type":"string","value":""},"socketCmdCCW2":{"type":"string","value":""},"socketDataCCW2":{"type":"string","value":""},"pinPush2":{"type":"number","value":0},"pinPushDebounce2":{"type":"number","value":0},"pushState2":{"type":"boolean","value":false},"pushAction2":{"type":"number","value":0},"socketCmdPush2":{"type":"string","value":""},"socketDataPush2":{"type":"string","value":""},"longPushAction2":{"type":"number","value":0},"socketCmdLongPush2":{"type":"string","value":""},"socketDataLongPush2":{"type":"string","value":""},"delayLongPush2":{"type":"string","value":"1500"},"doublePushAction2":{"type":"number","value":0},"socketCmdDoublePush2":{"type":"string","value":""},"socketDataDoublePush2":{"type":"string","value":""},"delayDoublePush2":{"type":"string","value":"700"},"logging":{"type":"boolean","value":true}},"callbacks":{"_":{}},"filePath":"/data/configuration/system_hardware/rotaryencoder2/config.json"} Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] activateRotaries: 1,2,3 Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] activateRotaries: 1,2 Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] activateRotaries: 1 Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] activateRotaries: Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] activateRotaries: end of recursion. Jan 31 16:10:54 volumio volumio[826]: info: Volumio Calling Home Jan 31 16:10:54 volumio volumio[826]: info: [ROTARYENCODER2] addOverlay: 22 27 1 Jan 31 16:10:54 volumio sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=27 relative_axis=true steps-per-period=1 Jan 31 16:10:54 volumio sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:54 volumio kernel: rotary-encoder rotary@16: gray Jan 31 16:10:54 volumio kernel: input: rotary@16 as /devices/platform/rotary@16/input/input0 Jan 31 16:10:54 volumio sudo[1069]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:55 volumio systemd-udevd[1074]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 31 16:10:55 volumio volumio[826]: info: MPD Permissions set Jan 31 16:10:55 volumio mpd[1064]: Jan 31 16:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 31 16:10:55 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jan 31 16:10:55 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jan 31 16:10:55 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 31 16:10:55 volumio volumio[826]: info: /dev/input: Jan 31 16:10:55 volumio volumio[826]: by-path Jan 31 16:10:55 volumio volumio[826]: event0 Jan 31 16:10:55 volumio volumio[826]: mice Jan 31 16:10:55 volumio volumio[826]: /dev/input/by-path: Jan 31 16:10:55 volumio volumio[826]: platform-rotary@16-event Jan 31 16:10:55 volumio volumio[826]: info: Starting Shairport Sync Jan 31 16:10:55 volumio volumio[826]: info: Starting Shairport Sync Jan 31 16:10:55 volumio sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 31 16:10:55 volumio sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:55 volumio volumio[826]: info: Starting Shairport Sync Jan 31 16:10:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 31 16:10:55 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 31 16:10:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 31 16:10:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 31 16:10:55 volumio sudo[1091]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:55 volumio sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 31 16:10:55 volumio volumio[826]: info: Shairport-Sync Started Jan 31 16:10:55 volumio volumio[826]: Error adding Membership: Error: addMembership EINVAL Jan 31 16:10:55 volumio sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:55 volumio sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 31 16:10:55 volumio sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 31 16:10:56 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 31 16:10:56 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 31 16:10:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 31 16:10:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 31 16:10:56 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:56 volumio volumio[826]: info: Shairport-Sync Started Jan 31 16:10:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 31 16:10:56 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 31 16:10:56 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 31 16:10:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 31 16:10:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 31 16:10:56 volumio sudo[1097]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:56 volumio volumio[826]: info: Shairport-Sync Started Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] attachListener: /dev/input/by-path/platform-rotary@16-event Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] addEventHandle for rotary: 1 Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] activateButtons: 1,2,3 Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] activateButtons: 1,2 Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] activateButtons: 1 Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] activateButtons: Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] activateButtons: end of recursion. Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] activateButtons: Now assign push button: 1, debounce: 10 Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] Push Button 1 now resolving. Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] onStart: Plugin successfully started. Jan 31 16:10:56 volumio volumio[826]: info: Completed starting Core Plugins Jan 31 16:10:56 volumio volumio[826]: info: ------------------------------------------- Jan 31 16:10:56 volumio volumio[826]: info: ----- MyVolumio plugins startup ---- Jan 31 16:10:56 volumio volumio[826]: info: ------------------------------------------- Jan 31 16:10:56 volumio volumio[826]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: 1 Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] emitDialCommand: 1 with value 1for Rotary: 1 Jan 31 16:10:56 volumio volumio[826]: info: [ROTARYENCODER2] emitDialCommand: VOLUME UP Jan 31 16:10:57 volumio mpd[1064]: Jan 31 16:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 31 16:10:57 volumio mpd[1064]: Jan 31 16:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 31 16:10:57 volumio systemd[1]: Started Music Player Daemon. Jan 31 16:10:57 volumio sudo[1051]: pam_unix(sudo:session): session closed for user root Jan 31 16:10:57 volumio volumio[826]: error: updateQueue error: null Jan 31 16:10:57 volumio volumio[826]: info: [ROTARYENCODER2] Push Button 1 pressed. Jan 31 16:10:57 volumio volumio[826]: info: [ROTARYENCODER2] Push Button 1 starting timers. Jan 31 16:10:58 volumio volumio[826]: info: [ROTARYENCODER2] Push Button 1 released after 170ms. Jan 31 16:10:58 volumio dhcpcd[741]: wlan0: leased 192.168.1.32 for 86400 seconds Jan 31 16:10:58 volumio avahi-daemon[459]: Registering new address record for 192.168.1.32 on wlan0.IPv4. Jan 31 16:10:58 volumio dhcpcd[741]: wlan0: adding route to 192.168.1.0/24 Jan 31 16:10:58 volumio dhcpcd[741]: wlan0: changing default route via 192.168.1.1 Jan 31 16:10:58 volumio avahi-daemon[459]: Withdrawing address record for 169.254.13.228 on wlan0. Jan 31 16:10:58 volumio avahi-daemon[459]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.13.228. Jan 31 16:10:58 volumio dhcpcd[741]: wlan0: deleting route to 169.254.0.0/16 Jan 31 16:10:58 volumio avahi-daemon[459]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.32. Jan 31 16:10:58 volumio dhcpcd[741]: wlan0: pid 741 deleted default route via 192.168.1.1 Jan 31 16:10:58 volumio sshd[1123]: Accepted publickey for volumio from 192.168.1.43 port 60196 ssh2: RSA SHA256:hs2r+/QQ3wuucGwazd/s4CVL+V4nFxQN6aJZX31e+fQ Jan 31 16:10:58 volumio sshd[1123]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jan 31 16:10:58 volumio systemd-logind[451]: New session 1 of user volumio. Jan 31 16:10:58 volumio systemd[1]: Created slice User Slice of UID 1000. Jan 31 16:10:58 volumio volumio[826]: info: [ROTARYENCODER2] Push Button 1 doublepush timer elapsed. (false, 1) Jan 31 16:10:58 volumio volumio[826]: info: [ROTARYENCODER2] Push Button 1 sending single push command. Jan 31 16:10:58 volumio volumio[826]: info: [ROTARYENCODER2] emitPushCommand: 3for Rotary: 1 Jan 31 16:10:58 volumio volumio[826]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 31 16:10:58 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Jan 31 16:10:58 volumio volumio[826]: TypeError: Cannot read property 'status' of null Jan 31 16:10:58 volumio volumio[826]: at rotaryencoder2.emitPushCommand (/data/plugins/system_hardware/rotaryencoder2/index.js:761:24) Jan 31 16:10:58 volumio volumio[826]: at Timeout._onTimeout (/data/plugins/system_hardware/rotaryencoder2/index.js:532:18) Jan 31 16:10:58 volumio volumio[826]: at listOnTimeout (internal/timers.js:554:17) Jan 31 16:10:58 volumio volumio[826]: at processTimers (internal/timers.js:497:7) Jan 31 16:10:58 volumio volumio[826]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 31 16:10:58 volumio systemd[1]: Started User Runtime Directory /run/user/1000. Jan 31 16:10:58 volumio systemd[1]: Starting User Manager for UID 1000... Jan 31 16:10:58 volumio systemd[1161]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jan 31 16:10:58 volumio systemd[1161]: Listening on GnuPG cryptographic agent and passphrase cache. Jan 31 16:10:58 volumio systemd[1161]: Reached target Paths. Jan 31 16:10:58 volumio systemd[1161]: Listening on Sound System. Jan 31 16:10:58 volumio systemd[1161]: Starting D-Bus User Message Bus Socket. Jan 31 16:10:58 volumio systemd[1161]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use Jan 31 16:10:58 volumio systemd[1161]: mpd.socket: Failed to listen on sockets: Address already in use Jan 31 16:10:58 volumio systemd[1161]: mpd.socket: Failed with result 'resources'. Jan 31 16:10:58 volumio systemd[1161]: Failed to listen on mpd.socket. Jan 31 16:10:58 volumio systemd[1161]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jan 31 16:10:58 volumio systemd[1161]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jan 31 16:10:58 volumio systemd[1161]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jan 31 16:10:58 volumio systemd[1161]: Reached target Timers. Jan 31 16:10:58 volumio systemd[1161]: Listening on GnuPG network certificate management daemon. Jan 31 16:10:58 volumio systemd[1161]: Listening on D-Bus User Message Bus Socket. Jan 31 16:10:58 volumio systemd[1161]: Reached target Sockets. Jan 31 16:10:58 volumio systemd[1161]: Reached target Basic System. Jan 31 16:10:58 volumio systemd[1]: Started User Manager for UID 1000. Jan 31 16:10:58 volumio systemd[1161]: Starting Music Player Daemon... Jan 31 16:10:58 volumio systemd[1]: Started Session 1 of user volumio. Jan 31 16:10:59 volumio ntpd[633]: Soliciting pool server 198.60.22.240 Jan 31 16:10:59 volumio volumio[1018]: ...........................................................................................................................++++ Jan 31 16:10:59 volumio ntpd[633]: Soliciting pool server 192.189.65.187 Jan 31 16:10:59 volumio ntpd[633]: Soliciting pool server 23.150.40.242 Jan 31 16:10:59 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-31 16:09 Jan 31 16:10:59 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 31 16:10:59 volumio ntpd[633]: Soliciting pool server 148.113.194.34 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"