-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-05-19 11:14:47 CST. -- May 19 11:13:03 volumio3 fake-hwclock[315]: Mon May 19 03:13:03 UTC 2025 May 19 11:13:03 volumio3 systemd[1]: Mounting Kernel Configuration File System... May 19 11:13:03 volumio3 systemd[1]: Mounting FUSE Control File System... May 19 11:13:03 volumio3 systemd[1]: Starting Apply Kernel Variables... May 19 11:13:03 volumio3 systemd[1]: Started Restore / save the current clock. May 19 11:13:03 volumio3 systemd[1]: Mounted FUSE Control File System. May 19 11:13:03 volumio3 systemd[1]: Started Load/Save Random Seed. May 19 11:13:03 volumio3 systemd[1]: Started udev Coldplug all Devices. May 19 11:13:03 volumio3 systemd[1]: Mounted Kernel Configuration File System. May 19 11:13:03 volumio3 systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 19 11:13:03 volumio3 systemd[1]: dynamicswap.service: Succeeded. May 19 11:13:03 volumio3 systemd[1]: Started Apply Kernel Variables. May 19 11:13:03 volumio3 systemd[1]: Started Create System Users. May 19 11:13:03 volumio3 systemd[1]: Starting Create Static Device Nodes in /dev... May 19 11:13:03 volumio3 systemd[1]: Started Create Static Device Nodes in /dev. May 19 11:13:03 volumio3 systemd[1]: Starting udev Kernel Device Manager... May 19 11:13:03 volumio3 systemd[1]: Reached target Local File Systems (Pre). May 19 11:13:03 volumio3 systemd[1]: Mounting /var/spool/cups... May 19 11:13:03 volumio3 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 19 11:13:03 volumio3 systemd[1]: Mounting /tmp... May 19 11:13:03 volumio3 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 19 11:13:03 volumio3 systemd[1]: Mounting /var/log... May 19 11:13:03 volumio3 systemd[1]: Mounted /var/spool/cups. May 19 11:13:03 volumio3 systemd[1]: Mounted /tmp. May 19 11:13:03 volumio3 systemd[1]: Mounted /var/log. May 19 11:13:03 volumio3 systemd[1]: Starting Flush Journal to Persistent Storage... May 19 11:13:03 volumio3 systemd[1]: Mounting /var/spool/cups/tmp... May 19 11:13:03 volumio3 systemd-udevd[346]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 19 11:13:03 volumio3 systemd[1]: Mounted /var/spool/cups/tmp. May 19 11:13:03 volumio3 systemd-journald[317]: Runtime journal (/run/log/journal/a4d620ccb4a6a12010c39c5d649876fe) is 7.5M, max 30.0M, 22.5M free. May 19 11:13:03 volumio3 systemd[1]: Started udev Kernel Device Manager. May 19 11:13:03 volumio3 systemd[1]: Starting Show Plymouth Boot Screen... May 19 11:13:03 volumio3 systemd[1]: Started Flush Journal to Persistent Storage. May 19 11:13:03 volumio3 systemd[1]: plymouth-start.service: Succeeded. May 19 11:13:03 volumio3 systemd[1]: Started Show Plymouth Boot Screen. May 19 11:13:03 volumio3 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 19 11:13:03 volumio3 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 19 11:13:03 volumio3 systemd[1]: Reached target Local Encrypted Volumes. May 19 11:13:03 volumio3 systemd[1]: Reached target Paths. May 19 11:13:03 volumio3 kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 May 19 11:13:03 volumio3 kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem May 19 11:13:03 volumio3 kernel: mc: Linux media interface: v0.10 May 19 11:13:03 volumio3 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 19 11:13:03 volumio3 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 19 11:13:03 volumio3 kernel: [vc_sm_connected_init]: start May 19 11:13:03 volumio3 kernel: [vc_sm_connected_init]: installed successfully May 19 11:13:03 volumio3 kernel: videodev: Linux video capture interface: v2.00 May 19 11:13:03 volumio3 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 19 11:13:03 volumio3 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 19 11:13:03 volumio3 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. May 19 11:13:03 volumio3 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 19 11:13:03 volumio3 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 May 19 11:13:03 volumio3 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image May 19 11:13:04 volumio3 kernel: Bluetooth: Core ver 2.22 May 19 11:13:04 volumio3 kernel: NET: Registered PF_BLUETOOTH protocol family May 19 11:13:04 volumio3 kernel: Bluetooth: HCI device and connection manager initialized May 19 11:13:04 volumio3 kernel: Bluetooth: HCI socket layer initialized May 19 11:13:04 volumio3 kernel: Bluetooth: L2CAP socket layer initialized May 19 11:13:04 volumio3 kernel: Bluetooth: SCO socket layer initialized May 19 11:13:04 volumio3 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 19 11:13:04 volumio3 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored May 19 11:13:04 volumio3 kernel: Bluetooth: HCI UART driver ver 2.3 May 19 11:13:04 volumio3 kernel: Bluetooth: HCI UART protocol H4 registered May 19 11:13:04 volumio3 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered May 19 11:13:04 volumio3 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator May 19 11:13:04 volumio3 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 19 11:13:04 volumio3 kernel: Bluetooth: HCI UART protocol Broadcom registered May 19 11:13:04 volumio3 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator May 19 11:13:04 volumio3 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 19 11:13:04 volumio3 kernel: uart-pl011 3f201000.serial: no DMA platform data May 19 11:13:04 volumio3 systemd-udevd[359]: Using default interface naming scheme 'v240'. May 19 11:13:04 volumio3 systemd[1]: Found device /dev/disk/by-uuid/7883-9333. May 19 11:13:04 volumio3 systemd-udevd[359]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 19 11:13:04 volumio3 systemd[1]: Starting File System Check on /dev/disk/by-uuid/7883-9333... May 19 11:13:04 volumio3 systemd[1]: Started File System Check Daemon to report status. May 19 11:13:04 volumio3 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 19 11:13:04 volumio3 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' May 19 11:13:04 volumio3 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' May 19 11:13:04 volumio3 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 19 11:13:04 volumio3 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' May 19 11:13:04 volumio3 systemd-fsck[460]: fsck.fat 4.1 (2017-01-24) May 19 11:13:04 volumio3 systemd-fsck[460]: /dev/mmcblk0p1: 411 files, 41753/46774 clusters May 19 11:13:04 volumio3 kernel: snd-rpi-iqaudio-dac soc:sound: snd_rpi_iqaudio_gpio_unmute: un-muting amp using GPIO22 May 19 11:13:04 volumio3 systemd-udevd[357]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 19 11:13:04 volumio3 systemd[1]: Started File System Check on /dev/disk/by-uuid/7883-9333. May 19 11:13:04 volumio3 systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. May 19 11:13:04 volumio3 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 19 11:13:04 volumio3 systemd[1]: Mounting /boot... May 19 11:13:05 volumio3 systemd[1]: Mounted /boot. May 19 11:13:05 volumio3 kernel: usb 1-1.3: reset high-speed USB device number 4 using dwc_otg May 19 11:13:06 volumio3 kernel: mt7601u 1-1.3:1.0: ASIC revision: 76010001 MAC revision: 76010500 May 19 11:13:06 volumio3 kernel: mt7601u 1-1.3:1.0: Firmware Version: 0.1.00 Build: 7640 Build time: 201302052146____ May 19 11:13:06 volumio3 kernel: alsactl[502]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 19 11:13:06 volumio3 kernel: alsactl[501]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 19 11:13:06 volumio3 kernel: 8021q: 802.1Q VLAN Support v1.8 May 19 11:13:06 volumio3 kernel: mt7601u 1-1.3:1.0: EEPROM ver:0d fae:00 May 19 11:13:06 volumio3 kernel: ieee80211 phy0: Selected rate control algorithm 'minstrel_ht' May 19 11:13:06 volumio3 kernel: usbcore: registered new interface driver mt7601u May 19 11:13:05 volumio3 alsactl[554]: alsactl 1.1.8 daemon started May 19 11:13:05 volumio3 systemd[1]: Created slice system-bthelper.slice. May 19 11:13:05 volumio3 alsactl[554]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #1 (No such file or directory) May 19 11:13:05 volumio3 systemd[1]: Reached target Local File Systems. May 19 11:13:05 volumio3 alsactl[554]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #2 (No such file or directory) May 19 11:13:06 volumio3 sh[511]: command failed: No such device (-19) May 19 11:13:05 volumio3 systemd[1]: Started ifup for eth0. May 19 11:13:05 volumio3 alsactl[554]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #1 (No such file or directory) May 19 11:13:05 volumio3 systemd[1]: Starting Create Volatile Files and Directories... May 19 11:13:05 volumio3 alsactl[554]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #2 (No such file or directory) May 19 11:13:05 volumio3 systemd[1]: Starting Preprocess NFS configuration... May 19 11:13:05 volumio3 systemd[1]: Starting Show Plymouth Boot Screen... May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 19 11:13:05 volumio3 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 19 11:13:05 volumio3 systemd[1]: Starting Load/Save RF Kill Switch Status... May 19 11:13:05 volumio3 systemd[1]: nfs-config.service: Succeeded. May 19 11:13:05 volumio3 systemd[1]: Started Preprocess NFS configuration. May 19 11:13:05 volumio3 systemd[1]: plymouth-read-write.service: Succeeded. May 19 11:13:06 volumio3 mpd_monitor.sh[549]: MPD Monitor Service: Starting MPD Monitor Service May 19 11:13:05 volumio3 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 19 11:13:05 volumio3 systemd[1]: Received SIGRTMIN+20 from PID 196 (plymouthd). May 19 11:13:06 volumio3 dhcpcd[550]: Not running dhcpcd because /etc/network/interfaces May 19 11:13:06 volumio3 dhcpcd[550]: defines some interfaces that will use a May 19 11:13:06 volumio3 dhcpcd[550]: DHCP client or static address May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 19 11:13:06 volumio3 volumio-time-update[552]: volumio-time-update-util: Fetching time from Volumio... May 19 11:13:05 volumio3 systemd[1]: Reached target NFS client services. May 19 11:13:05 volumio3 systemd[1]: plymouth-start.service: Succeeded. May 19 11:13:06 volumio3 alsactl[554]: /usr/share/alsa/init/bcm2835:14: control element not found May 19 11:13:06 volumio3 alsactl[554]: /usr/share/alsa/init/bcm2835:20: control element not found May 19 11:13:05 volumio3 systemd[1]: Started Show Plymouth Boot Screen. May 19 11:13:06 volumio3 alsactl[555]: /usr/share/alsa/init/bcm2835:14: control element not found May 19 11:13:06 volumio3 alsactl[555]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #1 (No such file or directory) May 19 11:13:06 volumio3 alsactl[555]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #2 (No such file or directory) May 19 11:13:06 volumio3 alsactl[555]: /usr/share/alsa/init/bcm2835:20: control element not found May 19 11:13:06 volumio3 alsactl[555]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #1 (No such file or directory) May 19 11:13:06 volumio3 alsactl[555]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #2 (No such file or directory) May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 19 11:13:06 volumio3 thd[557]: Unable to parse trigger line: May 19 11:13:06 volumio3 thd[557]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 19 11:13:06 volumio3 thd[557]: Unable to parse trigger line: May 19 11:13:06 volumio3 thd[557]: Found socket passed from systemd May 19 11:13:05 volumio3 systemd[1]: Started Create Volatile Files and Directories. May 19 11:13:05 volumio3 systemd[1]: Started Load/Save RF Kill Switch Status. May 19 11:13:05 volumio3 systemd[1]: Starting RPC bind portmap service... May 19 11:13:05 volumio3 systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 19 11:13:05 volumio3 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 19 11:13:05 volumio3 systemd[1]: Started Update UTMP about System Boot/Shutdown. May 19 11:13:05 volumio3 systemd[1]: Reached target System Initialization. May 19 11:13:05 volumio3 systemd[1]: Started Daily Cleanup of Temporary Directories. May 19 11:13:05 volumio3 systemd[1]: Listening on triggerhappy.socket. May 19 11:13:05 volumio3 systemd[1]: Listening on D-Bus System Message Bus Socket. May 19 11:13:05 volumio3 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 19 11:13:05 volumio3 systemd[1]: Reached target Sockets. May 19 11:13:05 volumio3 systemd[1]: Reached target Basic System. May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 19 11:13:05 volumio3 systemd[1]: Starting Wireless Services... May 19 11:13:05 volumio3 systemd[1]: Started D-Bus System Message Bus. May 19 11:13:05 volumio3 systemd[1]: Starting WPA supplicant... May 19 11:13:05 volumio3 systemd[1]: Starting Configure Bluetooth Modems connected by UART... May 19 11:13:05 volumio3 systemd[1]: Started volumio-remote-updater.service. May 19 11:13:05 volumio3 systemd[1]: Started MPD Monitor Service. May 19 11:13:05 volumio3 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 19 11:13:05 volumio3 systemd[1]: Starting dhcpcd on all interfaces... May 19 11:13:05 volumio3 systemd[1]: Started Volumio Log Rotation Service. May 19 11:13:05 volumio3 systemd[1]: Starting Volumio Time Update Utility... May 19 11:13:05 volumio3 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 19 11:13:05 volumio3 systemd[1]: Started Daily apt download activities. May 19 11:13:05 volumio3 systemd[1]: Started Daily apt upgrade and clean activities. May 19 11:13:05 volumio3 systemd[1]: Reached target Timers. May 19 11:13:05 volumio3 systemd[1]: Started Manage Sound Card State (restore and store). May 19 11:13:05 volumio3 systemd[1]: Starting Save/Restore Sound Card State... May 19 11:13:05 volumio3 systemd[1]: Started UPnP Renderer front-end to MPD. May 19 11:13:05 volumio3 systemd[1]: Starting triggerhappy global hotkey daemon... May 19 11:13:05 volumio3 systemd[1]: Started Volumio Iptables Module. May 19 11:13:05 volumio3 systemd[1]: Starting Login Service... May 19 11:13:06 volumio3 systemd-logind[562]: New seat seat0. May 19 11:13:06 volumio3 systemd-udevd[361]: Using default interface naming scheme 'v240'. May 19 11:13:06 volumio3 systemd-udevd[361]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 19 11:13:06 volumio3 btuart[547]: Can't open serial port: No such file or directory May 19 11:13:06 volumio3 btuart[547]: Can't initialize device: No such file or directory May 19 11:13:06 volumio3 mpd_monitor.sh[549]: MPD error: Connection refused May 19 11:13:06 volumio3 avahi-daemon[553]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). May 19 11:13:06 volumio3 mpd_monitor.sh[549]: MPD Monitor Service: MPD Appears to be inactive, restarting May 19 11:13:06 volumio3 avahi-daemon[553]: Successfully dropped root privileges. May 19 11:13:06 volumio3 avahi-daemon[553]: avahi-daemon 0.7 starting up. May 19 11:13:06 volumio3 mpd_monitor.sh[549]: mpd: no process found May 19 11:13:06 volumio3 kernel: Bluetooth: hci0: command 0xfc18 tx timeout May 19 11:13:06 volumio3 kernel: Bluetooth: hci0: BCM: failed to write update baudrate (-110) May 19 11:13:06 volumio3 kernel: Bluetooth: hci0: Failed to set baudrate May 19 11:13:06 volumio3 haveged[540]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K May 19 11:13:06 volumio3 haveged[540]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 May 19 11:13:06 volumio3 haveged[540]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99929 May 19 11:13:06 volumio3 haveged[540]: haveged: fills: 0, generated: 0 May 19 11:13:06 volumio3 sh[511]: eth0: waiting for carrier May 19 11:13:06 volumio3 dhcpcd[538]: eth0: waiting for carrier May 19 11:13:06 volumio3 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 19 11:13:06 volumio3 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 19 11:13:06 volumio3 dbus-daemon[545]: [system] Successfully activated service 'org.freedesktop.systemd1' May 19 11:13:06 volumio3 avahi-daemon[553]: Successfully called chroot(). May 19 11:13:06 volumio3 avahi-daemon[553]: Successfully dropped remaining capabilities. May 19 11:13:06 volumio3 systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 19 11:13:06 volumio3 systemd[1]: Started Login Service. May 19 11:13:06 volumio3 wpa_supplicant[546]: Successfully initialized wpa_supplicant May 19 11:13:06 volumio3 systemd[1]: Started triggerhappy global hotkey daemon. May 19 11:13:06 volumio3 systemd[1]: Started RPC bind portmap service. May 19 11:13:07 volumio3 systemd[1]: hciuart.service: Control process exited, code=exited, status=1/FAILURE May 19 11:13:07 volumio3 systemd[1]: hciuart.service: Failed with result 'exit-code'. May 19 11:13:07 volumio3 systemd[1]: Failed to start Configure Bluetooth Modems connected by UART. May 19 11:13:07 volumio3 systemd[1]: Dependency failed for Raspberry Pi bluetooth helper. May 19 11:13:07 volumio3 systemd[1]: bthelper@hci0.service: Job bthelper@hci0.service/start failed with result 'dependency'. May 19 11:13:07 volumio3 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED May 19 11:13:07 volumio3 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 19 11:13:07 volumio3 systemd[1]: Failed to start dhcpcd on all interfaces. May 19 11:13:07 volumio3 systemd[1]: Started Save/Restore Sound Card State. May 19 11:13:07 volumio3 avahi-daemon[553]: Loading service file /services/volumio.service. May 19 11:13:07 volumio3 avahi-daemon[553]: Network interface enumeration completed. May 19 11:13:07 volumio3 avahi-daemon[553]: Server startup complete. Host name is volumio3.local. Local service cookie is 225603048. May 19 11:13:07 volumio3 avahi-daemon[553]: Service "Volumio3" (/services/volumio.service) successfully established. May 19 11:13:07 volumio3 volumio-remote-updater[548]: Error: No active session May 19 11:13:07 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:07] [info] asio async_connect error: system:111 (Connection refused) May 19 11:13:07 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 19 11:13:07 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:07] [error] handle_connect error: Underlying Transport Error May 19 11:13:07 volumio3 volumio[556]: Could not open config: /tmp/upmpdcli.conf May 19 11:13:07 volumio3 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 19 11:13:07 volumio3 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 19 11:13:07 volumio3 systemd[1]: Started WPA supplicant. May 19 11:13:07 volumio3 volumio-time-update[552]: volumio-time-update-util: Date not found in response May 19 11:13:07 volumio3 volumio-time-update[552]: volumio-time-update-util: Retrying in 5 seconds... May 19 11:13:07 volumio3 systemd[1]: Listening on mpd.socket. May 19 11:13:07 volumio3 systemd[1]: Reached target Sound Card. May 19 11:13:07 volumio3 systemd[1]: Starting Bluetooth service... May 19 11:13:07 volumio3 systemd[1]: Reached target Remote File Systems (Pre). May 19 11:13:07 volumio3 systemd[1]: Reached target Remote File Systems. May 19 11:13:07 volumio3 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 19 11:13:07 volumio3 systemd[1]: Reached target RPC Port Mapper. May 19 11:13:07 volumio3 systemd[1]: Started Helper to synchronize boot up for ifupdown. May 19 11:13:07 volumio3 systemd[1]: Found device MT7601U Wireless Adapter. May 19 11:13:07 volumio3 systemd[1]: iptables.service: Succeeded. May 19 11:13:07 volumio3 systemd[1]: Started ifup for wlan0. May 19 11:13:07 volumio3 systemd[1]: Starting Raise network interfaces... May 19 11:13:07 volumio3 sh[649]: command failed: Operation not supported (-95) May 19 11:13:07 volumio3 ifup[655]: command failed: Operation not supported (-95) May 19 11:13:07 volumio3 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 May 19 11:13:07 volumio3 bluetoothd[643]: Bluetooth daemon 5.50 May 19 11:13:07 volumio3 bluetoothd[643]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 19 11:13:07 volumio3 systemd[1]: Started Bluetooth service. May 19 11:13:07 volumio3 systemd[1]: Reached target Bluetooth. May 19 11:13:07 volumio3 bluetoothd[643]: Starting SDP server May 19 11:13:07 volumio3 bluetoothd[643]: Excluding (cli) sap May 19 11:13:07 volumio3 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 19 11:13:07 volumio3 kernel: Bluetooth: BNEP filters: protocol multicast May 19 11:13:07 volumio3 kernel: Bluetooth: BNEP socket layer initialized May 19 11:13:07 volumio3 bluetoothd[643]: Bluetooth management interface 1.22 initialized May 19 11:13:07 volumio3 dbus-daemon[545]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=643 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 19 11:13:07 volumio3 loadcpufreq[645]: Loading cpufreq kernel modules...done (none). May 19 11:13:08 volumio3 systemd[1]: Starting Hostname Service... May 19 11:13:08 volumio3 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 19 11:13:08 volumio3 systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 19 11:13:08 volumio3 ifup[655]: ifup: waiting for lock on /run/network/ifstate.wlan0 May 19 11:13:08 volumio3 sh[649]: wlan0=wlan0 May 19 11:13:08 volumio3 cpufrequtils[753]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 19 11:13:08 volumio3 systemd[1]: Started LSB: set CPUFreq kernel parameters. May 19 11:13:08 volumio3 systemd[1]: Started Raise network interfaces. May 19 11:13:08 volumio3 systemd[1]: Reached target Network. May 19 11:13:08 volumio3 dbus-daemon[545]: [system] Successfully activated service 'org.freedesktop.hostname1' May 19 11:13:08 volumio3 systemd[1]: Starting Music Player Daemon... May 19 11:13:08 volumio3 systemd[1]: Reached target Network is Online. May 19 11:13:08 volumio3 systemd[1]: Starting LSB: Brings up/down network automatically... May 19 11:13:08 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 19 11:13:08 volumio3 systemd[1]: Starting /etc/rc.local Compatibility... May 19 11:13:08 volumio3 systemd[1]: Starting Samba NMB Daemon... May 19 11:13:08 volumio3 systemd[1]: Starting Permit User Sessions... May 19 11:13:08 volumio3 systemd[1]: Starting Network Time Service... May 19 11:13:08 volumio3 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 19 11:13:08 volumio3 systemd[1]: Started /etc/rc.local Compatibility. May 19 11:13:08 volumio3 systemd[1]: Started Hostname Service. May 19 11:13:08 volumio3 systemd[1]: Started Permit User Sessions. May 19 11:13:08 volumio3 systemd[1]: Starting Hold until boot process finishes up... May 19 11:13:08 volumio3 systemd[1]: Starting Terminate Plymouth Boot Screen... May 19 11:13:08 volumio3 systemd[1]: Received SIGRTMIN+21 from PID 196 (plymouthd). May 19 11:13:08 volumio3 kernel: Bluetooth: hci0: command 0xfc18 tx timeout May 19 11:13:08 volumio3 kernel: Bluetooth: hci0: BCM: Reset failed (-110) May 19 11:13:08 volumio3 systemd[1]: Received SIGRTMIN+21 from PID 196 (plymouthd). May 19 11:13:08 volumio3 systemd[1]: plymouth-quit-wait.service: Succeeded. May 19 11:13:08 volumio3 systemd[1]: Started Hold until boot process finishes up. May 19 11:13:08 volumio3 ifplugd(eth0)[819]: ifplugd 0.28 initializing. May 19 11:13:08 volumio3 ifplugd(eth0)[819]: Using interface eth0/B8:27:EB:58:C5:31 with driver (version: 6.6.62-v7+) May 19 11:13:08 volumio3 ifplugd(eth0)[819]: Using detection mode: SIOCETHTOOL May 19 11:13:08 volumio3 ifplugd(eth0)[819]: Initialization complete, link beat not detected. May 19 11:13:08 volumio3 systemd[1]: plymouth-quit.service: Succeeded. May 19 11:13:08 volumio3 systemd[1]: Started Terminate Plymouth Boot Screen. May 19 11:13:08 volumio3 systemd[1]: Started Getty on tty1. May 19 11:13:08 volumio3 systemd[1]: Reached target Login Prompts. May 19 11:13:08 volumio3 ntpd[802]: ntpd 4.2.8p12@1.3728-o (1): Starting May 19 11:13:08 volumio3 ntpd[802]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 May 19 11:13:08 volumio3 systemd[1]: Started Network Time Service. May 19 11:13:08 volumio3 ntpd[824]: proto: precision = 0.729 usec (-20) May 19 11:13:08 volumio3 ifplugd[794]: Network Interface Plugging Daemon...start eth0...done. May 19 11:13:08 volumio3 systemd[1]: Started LSB: Brings up/down network automatically. May 19 11:13:08 volumio3 ntpd[824]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 19 11:13:08 volumio3 ntpd[824]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 19 11:13:08 volumio3 ntpd[824]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 143 days ago May 19 11:13:08 volumio3 ntpd[824]: Listen and drop on 0 v6wildcard [::]:123 May 19 11:13:08 volumio3 ntpd[824]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 19 11:13:08 volumio3 ntpd[824]: Listen normally on 2 lo 127.0.0.1:123 May 19 11:13:08 volumio3 ntpd[824]: Listening on routing socket on fd #19 for interface updates May 19 11:13:08 volumio3 ntpd[824]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 19 11:13:08 volumio3 ntpd[824]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 19 11:13:08 volumio3 sudo[793]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 19 11:13:08 volumio3 sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:08 volumio3 sudo[793]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 19 11:13:08 volumio3 sudo[793]: pam_unix(sudo:session): session closed for user root May 19 11:13:09 volumio3 wireless.js[543]: Cleaning previous... May 19 11:13:09 volumio3 nmbd[803]: [2025/05/19 11:13:09.682640, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 19 11:13:09 volumio3 nmbd[803]: started asyncdns process 834 May 19 11:13:09 volumio3 nmbd[803]: [2025/05/19 11:13:09.684885, 0] ../lib/util/become_daemon.c:149(daemon_status) May 19 11:13:09 volumio3 nmbd[803]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 19 11:13:09 volumio3 nmbd[803]: [2025/05/19 11:13:09.685070, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 19 11:13:09 volumio3 nmbd[803]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 19 11:13:09 volumio3 ntpd[824]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 19 11:13:09 volumio3 sudo[836]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 19 11:13:09 volumio3 sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:09 volumio3 sudo[836]: pam_unix(sudo:session): session closed for user root May 19 11:13:09 volumio3 sudo[838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 19 11:13:09 volumio3 sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:09 volumio3 sudo[838]: pam_unix(sudo:session): session closed for user root May 19 11:13:10 volumio3 wireless.js[543]: Stopped aP May 19 11:13:10 volumio3 sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 19 11:13:10 volumio3 sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:10 volumio3 sudo[845]: pam_unix(sudo:session): session closed for user root May 19 11:13:10 volumio3 sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 19 11:13:10 volumio3 sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:10 volumio3 sudo[847]: pam_unix(sudo:session): session closed for user root May 19 11:13:10 volumio3 sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 19 11:13:10 volumio3 sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:10 volumio3 sudo[854]: pam_unix(sudo:session): session closed for user root May 19 11:13:10 volumio3 sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 19 11:13:10 volumio3 sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:10 volumio3 ntpd[824]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 19 11:13:11 volumio3 systemd[1]: systemd-rfkill.service: Succeeded. May 19 11:13:11 volumio3 ntpd[824]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 19 11:13:11 volumio3 sudo[856]: pam_unix(sudo:session): session closed for user root May 19 11:13:11 volumio3 wireless.js[543]: Start wireless flow May 19 11:13:11 volumio3 wireless.js[543]: Stopped hotspot (if there).. May 19 11:13:11 volumio3 sudo[861]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 19 11:13:11 volumio3 sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:11 volumio3 sudo[861]: pam_unix(sudo:session): session closed for user root May 19 11:13:11 volumio3 sudo[863]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 19 11:13:11 volumio3 sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:11 volumio3 sudo[863]: pam_unix(sudo:session): session closed for user root May 19 11:13:11 volumio3 wireless.js[543]: DHCP IP May 19 11:13:11 volumio3 wireless.js[543]: Start ap May 19 11:13:11 volumio3 wpa_supplicant[865]: Successfully initialized wpa_supplicant May 19 11:13:11 volumio3 sudo[866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 19 11:13:11 volumio3 sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:11 volumio3 dhcpcd[867]: dev: loaded udev May 19 11:13:12 volumio3 dhcpcd-run-hooks[885]: wlan0: starting wpa_supplicant May 19 11:13:12 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:12] [info] asio async_connect error: system:111 (Connection refused) May 19 11:13:12 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 19 11:13:12 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:12] [error] handle_connect error: Underlying Transport Error May 19 11:13:12 volumio3 mpd[827]: May 19 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 19 11:13:12 volumio3 mpd[827]: May 19 11:13 : exception: Tag list mismatch, discarding database file May 19 11:13:12 volumio3 systemd[1]: Started Music Player Daemon. May 19 11:13:12 volumio3 mpd_monitor.sh[549]: MPD Monitor Service: MPD restarted due to no mpc output. May 19 11:13:12 volumio3 volumio-time-update[552]: volumio-time-update-util: Fetching time from Volumio... May 19 11:13:12 volumio3 volumio-time-update[552]: volumio-time-update-util: Date not found in response May 19 11:13:12 volumio3 volumio-time-update[552]: volumio-time-update-util: Retrying in 5 seconds... May 19 11:13:12 volumio3 dhcpcd-run-hooks[897]: wlan0: failed to start wpa_supplicant May 19 11:13:12 volumio3 dhcpcd-run-hooks[898]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma May 19 11:13:12 volumio3 dhcpcd[867]: wlan0: connected to Access Point `' May 19 11:13:12 volumio3 dhcpcd[867]: no interfaces have a carrier May 19 11:13:12 volumio3 dhcpcd[867]: forked to background, child pid 908 May 19 11:13:12 volumio3 dhcpcd[908]: eth0: waiting for carrier May 19 11:13:12 volumio3 dhcpcd[908]: wlan0: waiting for carrier May 19 11:13:12 volumio3 sudo[866]: pam_unix(sudo:session): session closed for user root May 19 11:13:12 volumio3 ntpd[824]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 19 11:13:12 volumio3 wireless.js[543]: trying... May 19 11:13:12 volumio3 sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:12 volumio3 sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:12 volumio3 sudo[910]: pam_unix(sudo:session): session closed for user root May 19 11:13:13 volumio3 wpa_supplicant[887]: wlan0: SME: Trying to authenticate with 48:0e:ec:8c:f9:a6 (SSID='jasonNet' freq=2437 MHz) May 19 11:13:13 volumio3 kernel: wlan0: authenticate with 48:0e:ec:8c:f9:a6 May 19 11:13:13 volumio3 kernel: wlan0: 80 MHz not supported, disabling VHT May 19 11:13:13 volumio3 kernel: wlan0: send auth to 48:0e:ec:8c:f9:a6 (try 1/3) May 19 11:13:13 volumio3 wireless.js[543]: trying... May 19 11:13:13 volumio3 sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:13 volumio3 sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:13 volumio3 sudo[913]: pam_unix(sudo:session): session closed for user root May 19 11:13:13 volumio3 kernel: wlan0: send auth to 48:0e:ec:8c:f9:a6 (try 2/3) May 19 11:13:13 volumio3 wpa_supplicant[887]: wlan0: Trying to associate with 48:0e:ec:8c:f9:a6 (SSID='jasonNet' freq=2437 MHz) May 19 11:13:13 volumio3 kernel: wlan0: authenticated May 19 11:13:14 volumio3 kernel: wlan0: associate with 48:0e:ec:8c:f9:a6 (try 1/3) May 19 11:13:14 volumio3 kernel: wlan0: RX AssocResp from 48:0e:ec:8c:f9:a6 (capab=0x1411 status=0 aid=3) May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: carrier acquired May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: connected to Access Point `jasonNet' May 19 11:13:14 volumio3 wpa_supplicant[887]: wlan0: Associated with 48:0e:ec:8c:f9:a6 May 19 11:13:14 volumio3 wpa_supplicant[887]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 19 11:13:14 volumio3 wpa_supplicant[887]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN May 19 11:13:14 volumio3 kernel: wlan0: associated May 19 11:13:14 volumio3 dhcpcd[908]: DUID 00:01:00:01:2c:2b:36:12:b8:27:eb:58:c5:31 May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: IAID 80:e9:95:91 May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: adding address fe80::92de:80ff:fee9:9591 May 19 11:13:14 volumio3 dhcpcd[908]: ipv6_addaddr1: Permission denied May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: carrier lost May 19 11:13:14 volumio3 kernel: wlan0: Limiting TX power to 35 (35 - 0) dBm as advertised by 48:0e:ec:8c:f9:a6 May 19 11:13:14 volumio3 kernel: cryptd: max_cpu_qlen set to 1000 May 19 11:13:14 volumio3 wpa_supplicant[887]: wlan0: WPA: Key negotiation completed with 48:0e:ec:8c:f9:a6 [PTK=CCMP GTK=CCMP] May 19 11:13:14 volumio3 wpa_supplicant[887]: wlan0: CTRL-EVENT-CONNECTED - Connection to 48:0e:ec:8c:f9:a6 completed [id=0 id_str=] May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: carrier acquired May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: IAID 80:e9:95:91 May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: soliciting a DHCP lease May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: offered 192.168.1.107 from 192.168.1.1 May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: probing address 192.168.1.107/24 May 19 11:13:14 volumio3 dhcpcd[908]: wlan0: soliciting an IPv6 router May 19 11:13:14 volumio3 wireless.js[543]: trying... May 19 11:13:14 volumio3 sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:14 volumio3 sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:14 volumio3 sudo[939]: pam_unix(sudo:session): session closed for user root May 19 11:13:15 volumio3 wireless.js[543]: trying... May 19 11:13:15 volumio3 sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:15 volumio3 sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:15 volumio3 sudo[942]: pam_unix(sudo:session): session closed for user root May 19 11:13:16 volumio3 wireless.js[543]: trying... May 19 11:13:16 volumio3 sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:16 volumio3 sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:16 volumio3 sudo[945]: pam_unix(sudo:session): session closed for user root May 19 11:13:17 volumio3 volumio-time-update[552]: volumio-time-update-util: Fetching time from Volumio... May 19 11:13:17 volumio3 volumio-time-update[552]: volumio-time-update-util: Date not found in response May 19 11:13:17 volumio3 volumio-time-update[552]: volumio-time-update-util: Retrying in 5 seconds... May 19 11:13:17 volumio3 wireless.js[543]: trying... May 19 11:13:17 volumio3 sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:17 volumio3 sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:17 volumio3 sudo[953]: pam_unix(sudo:session): session closed for user root May 19 11:13:18 volumio3 wireless.js[543]: trying... May 19 11:13:18 volumio3 sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:18 volumio3 sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:18 volumio3 sudo[956]: pam_unix(sudo:session): session closed for user root May 19 11:13:19 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:19] [info] asio async_connect error: system:111 (Connection refused) May 19 11:13:19 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 19 11:13:19 volumio3 volumio-remote-updater[548]: [2025-05-19 11:13:19] [error] handle_connect error: Underlying Transport Error May 19 11:13:19 volumio3 wireless.js[543]: trying... May 19 11:13:19 volumio3 sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:19 volumio3 sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:19 volumio3 sudo[959]: pam_unix(sudo:session): session closed for user root May 19 11:13:20 volumio3 dhcpcd[908]: wlan0: leased 192.168.1.107 for 7200 seconds May 19 11:13:20 volumio3 avahi-daemon[553]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.107. May 19 11:13:20 volumio3 avahi-daemon[553]: New relevant interface wlan0.IPv4 for mDNS. May 19 11:13:20 volumio3 avahi-daemon[553]: Registering new address record for 192.168.1.107 on wlan0.IPv4. May 19 11:13:20 volumio3 dhcpcd[908]: wlan0: adding route to 192.168.1.0/24 May 19 11:13:20 volumio3 dhcpcd[908]: wlan0: adding default route via 192.168.1.1 May 19 11:13:20 volumio3 wireless.js[543]: trying... May 19 11:13:20 volumio3 sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 11:13:20 volumio3 sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:20 volumio3 sudo[982]: pam_unix(sudo:session): session closed for user root May 19 11:13:20 volumio3 wireless.js[543]: Connected to: ----jasonNet May 19 11:13:20 volumio3 wireless.js[543]: ---- May 19 11:13:20 volumio3 sudo[985]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 11:13:20 volumio3 sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:21 volumio3 sudo[985]: pam_unix(sudo:session): session closed for user root May 19 11:13:21 volumio3 wireless.js[543]: ... joined AP, wlan0 IPv4 is 192.168.1.107, ipV6 is undefined May 19 11:13:21 volumio3 wireless.js[543]: It's done! AP May 19 11:13:21 volumio3 iwconfig[989]: Error for wireless request "Set Power Management" (8B2C) : May 19 11:13:21 volumio3 iwconfig[989]: SET failed on device wlan0 ; Operation not supported. May 19 11:13:21 volumio3 systemd[1]: Started Wireless Services. May 19 11:13:21 volumio3 systemd[1]: Started Volumio Backend Module. May 19 11:13:21 volumio3 systemd[1]: Started Volumio Cpu Tweaker. May 19 11:13:21 volumio3 volumio-cpu-tweak[991]: Setting RT Priority for mpd May 19 11:13:21 volumio3 volumio-cpu-tweak[991]: Setting MPD Affinity May 19 11:13:21 volumio3 volumio-cpu-tweak[991]: pid 827's current affinity mask: f May 19 11:13:21 volumio3 volumio-cpu-tweak[991]: pid 827's new affinity mask: 3 May 19 11:13:21 volumio3 volumio-cpu-tweak[991]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 19 11:13:21 volumio3 volumio-cpu-tweak[991]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 19 11:13:21 volumio3 systemd[1]: volumio_cpu_tweak.service: Succeeded. May 19 11:13:21 volumio3 ntpd[824]: Listen normally on 3 wlan0 192.168.1.107:123 May 19 11:13:21 volumio3 ntpd[824]: new interface(s) found: waking up resolver May 19 11:13:22 volumio3 volumio-time-update[552]: volumio-time-update-util: Fetching time from Volumio... May 19 11:13:23 volumio3 volumio-time-update[552]: volumio-time-update-util: Setting system time to: 2025-05-19 11:14:24 May 19 11:13:23 volumio3 sudo[1012]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-19 11:14:24 May 19 11:13:23 volumio3 sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:13:23 volumio3 dbus-daemon[545]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.7' (uid=0 pid=1017 comm="timedatectl set-time 2025-05-19 11:14:24 ") May 19 11:13:23 volumio3 systemd[1]: Starting Time & Date Service... May 19 11:13:23 volumio3 dbus-daemon[545]: [system] Successfully activated service 'org.freedesktop.timedate1' May 19 11:13:23 volumio3 systemd[1]: Started Time & Date Service. May 19 11:14:24 volumio3 systemd-timedated[1022]: Changed local time to Mon May 19 11:14:24 2025 May 19 11:14:24 volumio3 sudo[1012]: pam_unix(sudo:session): session closed for user root May 19 11:14:24 volumio3 volumio-time-update[552]: volumio-time-update-util: System time updated successfully. May 19 11:14:24 volumio3 systemd[1]: Started Volumio Time Update Utility. May 19 11:14:24 volumio3 volumio[990]: info: ------------------------------------------- May 19 11:14:24 volumio3 volumio[990]: info: ----- Volumio3 ---- May 19 11:14:24 volumio3 volumio[990]: info: ------------------------------------------- May 19 11:14:24 volumio3 volumio[990]: info: ----- System startup ---- May 19 11:14:24 volumio3 volumio[990]: info: ------------------------------------------- May 19 11:14:24 volumio3 nmbd[803]: [2025/05/19 11:14:24.865846, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 19 11:14:24 volumio3 nmbd[803]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 19 11:14:24 volumio3 systemd[1]: Started Samba NMB Daemon. May 19 11:14:24 volumio3 systemd[1]: Starting Samba Winbind Daemon... May 19 11:14:25 volumio3 volumio[990]: info: MYVOLUMIO Environment detected May 19 11:14:25 volumio3 winbindd[1023]: [2025/05/19 11:14:25.321048, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 19 11:14:25 volumio3 winbindd[1023]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 19 11:14:25 volumio3 winbindd[1023]: [2025/05/19 11:14:25.575170, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 19 11:14:25 volumio3 systemd[1]: Started Samba Winbind Daemon. May 19 11:14:25 volumio3 winbindd[1023]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 19 11:14:25 volumio3 systemd[1]: Starting Samba SMB Daemon... May 19 11:14:25 volumio3 volumio[990]: info: Plugin folders cleanup May 19 11:14:25 volumio3 volumio[990]: info: Scanning into folder /volumio/app/plugins/ May 19 11:14:25 volumio3 volumio[990]: info: Scanning category audio_interface May 19 11:14:25 volumio3 volumio[990]: info: Scanning category miscellanea May 19 11:14:25 volumio3 volumio[990]: info: Scanning category music_service May 19 11:14:25 volumio3 volumio[990]: info: Scanning category plugins.json May 19 11:14:25 volumio3 volumio[990]: info: Scanning category system_controller May 19 11:14:25 volumio3 volumio[990]: info: Scanning category user_interface May 19 11:14:25 volumio3 volumio[990]: info: Scanning into folder /data/plugins/ May 19 11:14:25 volumio3 volumio[990]: info: Plugin folders cleanup completed May 19 11:14:25 volumio3 volumio[990]: info: ------------------------------------------- May 19 11:14:25 volumio3 volumio[990]: info: ----- Core plugins startup ---- May 19 11:14:25 volumio3 volumio[990]: info: ------------------------------------------- May 19 11:14:25 volumio3 volumio[990]: info: Loading plugins from folder /volumio/app/plugins/ May 19 11:14:25 volumio3 volumio[990]: info: Adding plugin upnp to MyMusic Plugins May 19 11:14:25 volumio3 volumio[990]: info: Adding plugin airplay_emulation to MyMusic Plugins May 19 11:14:25 volumio3 volumio[990]: info: Adding plugin upnp_browser to MyMusic Plugins May 19 11:14:25 volumio3 volumio[990]: info: Loading plugins from folder /data/plugins/ May 19 11:14:25 volumio3 volumio[990]: info: Loading plugin "system"... May 19 11:14:25 volumio3 volumio[990]: info: Loading plugin "appearance"... May 19 11:14:26 volumio3 smbd[1030]: [2025/05/19 11:14:26.456046, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 19 11:14:26 volumio3 systemd[1]: Started Samba SMB Daemon. May 19 11:14:26 volumio3 smbd[1030]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 19 11:14:26 volumio3 systemd[1]: Reached target Multi-User System. May 19 11:14:26 volumio3 systemd[1]: Reached target Graphical Interface. May 19 11:14:26 volumio3 systemd[1]: Starting Update UTMP about System Runlevel Changes... May 19 11:14:26 volumio3 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 19 11:14:26 volumio3 systemd[1]: Started Update UTMP about System Runlevel Changes. May 19 11:14:26 volumio3 systemd[1]: Startup finished in 14.210s (kernel) + 24.739s (userspace) = 38.949s. May 19 11:14:27 volumio3 volumio[990]: info: Loading plugin "network"... May 19 11:14:27 volumio3 volumio[990]: info: Refreshing Cached IP Addresses May 19 11:14:27 volumio3 sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 19 11:14:27 volumio3 sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:27 volumio3 sudo[1043]: pam_unix(sudo:session): session closed for user root May 19 11:14:27 volumio3 sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 11:14:27 volumio3 sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:27 volumio3 sudo[1045]: pam_unix(sudo:session): session closed for user root May 19 11:14:27 volumio3 volumio[990]: info: Loading plugin "services"... May 19 11:14:27 volumio3 volumio[990]: info: Loading plugin "alsa_controller"... May 19 11:14:27 volumio3 sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 19 11:14:27 volumio3 sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:27 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 19 11:14:27 volumio3 volumio[990]: info: Loading plugin "wizard"... May 19 11:14:27 volumio3 volumio[990]: info: Loading plugin "networkfs"... May 19 11:14:28 volumio3 volumio[990]: info: Starting Udev Watcher for removable devices May 19 11:14:28 volumio3 sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.252/music /mnt/NAS/music May 19 11:14:28 volumio3 sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:28 volumio3 volumio[990]: info: Ignoring mount for partition: boot May 19 11:14:28 volumio3 volumio[990]: info: Ignoring mount for partition: volumio May 19 11:14:28 volumio3 volumio[990]: info: Ignoring mount for partition: volumio_data May 19 11:14:28 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 11:14:28 volumio3 volumio[990]: info: Loading plugin "volumio_command_line_client"... May 19 11:14:28 volumio3 volumio[990]: info: Loading plugin "upnp"... May 19 11:14:28 volumio3 volumio[990]: info: [1747624468080] Starting Upmpd Daemon May 19 11:14:28 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 11:14:28 volumio3 volumio[990]: info: Loading plugin "my_music"... May 19 11:14:28 volumio3 volumio[990]: info: Loading plugin "mpd"... May 19 11:14:28 volumio3 kernel: Key type cifs.spnego registered May 19 11:14:28 volumio3 kernel: Key type cifs.idmap registered May 19 11:14:28 volumio3 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 19 11:14:28 volumio3 kernel: CIFS: Attempting to mount //192.168.1.252/music May 19 11:14:28 volumio3 volumio[990]: info: Loading plugin "upnp_browser"... May 19 11:14:29 volumio3 volumio-remote-updater[548]: [2025-05-19 11:14:29] [connect] Successful connection May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "alarm-clock"... May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "airplay_emulation"... May 19 11:14:30 volumio3 volumio[990]: info: Starting Shairport Sync May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "last_100"... May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "webradio"... May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "i2s_dacs"... May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "volumiodiscovery"... May 19 11:14:30 volumio3 volumio[990]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 19 11:14:30 volumio3 volumio[990]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 11:14:30 volumio3 volumio[990]: *** WARNING *** For more information see May 19 11:14:30 volumio3 volumio[990]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 19 11:14:30 volumio3 node[990]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 19 11:14:30 volumio3 volumio[990]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 11:14:30 volumio3 volumio[990]: *** WARNING *** For more information see May 19 11:14:30 volumio3 node[990]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 11:14:30 volumio3 node[990]: *** WARNING *** For more information see May 19 11:14:30 volumio3 node[990]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 19 11:14:30 volumio3 node[990]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 11:14:30 volumio3 node[990]: *** WARNING *** For more information see May 19 11:14:30 volumio3 volumio[990]: info: Applying required configuration parameters for plugin volumiodiscovery May 19 11:14:30 volumio3 volumio[990]: info: Discovery: Started advertising with name: Volumio3 May 19 11:14:30 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "outputs"... May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "albumart"... May 19 11:14:30 volumio3 volumio[990]: info: Plugin example_plugin is not enabled May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "inputs"... May 19 11:14:30 volumio3 volumio[990]: info: Loading plugin "updater_comm"... May 19 11:14:31 volumio3 volumio[990]: info: Plugin mpdemulation is not enabled May 19 11:14:31 volumio3 volumio[990]: info: Loading plugin "rest_api"... May 19 11:14:31 volumio3 volumio[990]: info: Loading plugin "websocket"... May 19 11:14:31 volumio3 volumio[990]: info: Starting Socket.io Server version 2.3.0 May 19 11:14:31 volumio3 volumio[990]: info: Loading i18n strings for locale zh May 19 11:14:31 volumio3 volumio[990]: Forking 3 albumart workers May 19 11:14:31 volumio3 volumio[990]: Updating browse sources language May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 11:14:31 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -112 May 19 11:14:31 volumio3 sudo[1071]: pam_unix(sudo:session): session closed for user root May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::initPlayerControls May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 11:14:31 volumio3 volumio[990]: Express server listening on port 3000 May 19 11:14:31 volumio3 volumio[990]: [Metrics] WebUI: 8s 681.44ms May 19 11:14:31 volumio3 volumio[990]: info: CoreStateMachine::resetVolumioState May 19 11:14:31 volumio3 volumio[990]: info: CoreStateMachine::getcurrentVolume May 19 11:14:31 volumio3 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume May 19 11:14:31 volumio3 volumio[990]: info: Volumio Network Manager: Network status updated: 2 May 19 11:14:31 volumio3 volumio[990]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds May 19 11:14:31 volumio3 volumio[990]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.101 UA: okhttp/3.12.12 Engine version: 3 Transport: polling Total Clients: 1 May 19 11:14:32 volumio3 volumio[990]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.101 UA: okhttp/3.12.12 Engine version: 3 Transport: websocket Total Clients: 2 May 19 11:14:32 volumio3 volumio-remote-updater[548]: [2025-05-19 11:14:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747624469 101 May 19 11:14:32 volumio3 volumio[990]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 19 11:14:32 volumio3 volumio[990]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.101 UA: okhttp/3.12.12 Engine version: 3 Transport: polling Total Clients: 4 May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:32 volumio3 volumio[990]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ May 19 11:14:32 volumio3 volumio[990]: info: Reloading queue from file May 19 11:14:32 volumio3 volumio[990]: info: CoreStateMachine::setRepeat null single undefined May 19 11:14:32 volumio3 volumio[990]: info: CoreStateMachine::pushState May 19 11:14:32 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::volumioPushState May 19 11:14:32 volumio3 volumio[990]: info: CoreStateMachine::setRandom null May 19 11:14:32 volumio3 volumio[990]: info: CoreStateMachine::pushState May 19 11:14:32 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::volumioPushState May 19 11:14:32 volumio3 volumio[990]: info: Setting Device type: Raspberry PI May 19 11:14:32 volumio3 volumio[990]: info: VolumeController:: Volume=30 Mute =false May 19 11:14:32 volumio3 volumio[990]: info: CoreStateMachine::pushState May 19 11:14:32 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::volumioPushState May 19 11:14:32 volumio3 volumio[990]: info: CoreStateMachine::updateTrackBlock May 19 11:14:32 volumio3 volumio[990]: info: CorePlayQueue::getTrackBlock May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume May 19 11:14:32 volumio3 volumio[990]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 19 11:14:32 volumio3 volumio[990]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 May 19 11:14:32 volumio3 volumio[990]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 19 11:14:32 volumio3 volumio[990]: info: DAC script executed May 19 11:14:32 volumio3 volumio[990]: info: Completed loading Core Plugins May 19 11:14:32 volumio3 volumio[990]: info: Preparing to generate the ALSA configuration file May 19 11:14:32 volumio3 volumio[990]: Starting albumart workers May 19 11:14:32 volumio3 volumio[990]: Starting albumart workers May 19 11:14:32 volumio3 volumio[990]: Starting albumart workers May 19 11:14:32 volumio3 volumio[990]: info: Asound.conf file unchanged, so no further update is needed May 19 11:14:32 volumio3 volumio[990]: info: Output device has changed, restarting MPD May 19 11:14:32 volumio3 sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 19 11:14:32 volumio3 sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:32 volumio3 volumio[990]: info: Output device has changed, restarting Shairport Sync May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:32 volumio3 sudo[1143]: pam_unix(sudo:session): session closed for user root May 19 11:14:32 volumio3 sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 19 11:14:32 volumio3 sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:32 volumio3 systemd[1]: Stopping Music Player Daemon... May 19 11:14:32 volumio3 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 11:14:32 volumio3 volumio[990]: info: ___________ START PLUGINS ___________ May 19 11:14:32 volumio3 volumio[990]: info: ControllerMpd::onStart: Initializing MPD May 19 11:14:32 volumio3 volumio[990]: info: Creating MPD Configuration file May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 11:14:32 volumio3 volumio[990]: info: [1747624472652] CoreMusicLibrary::Adding element 媒体服务器 May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 11:14:32 volumio3 sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 19 11:14:32 volumio3 sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:32 volumio3 sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 19 11:14:32 volumio3 sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:32 volumio3 sudo[1155]: pam_unix(sudo:session): session closed for user root May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:32 volumio3 systemd[1]: mpd.service: Succeeded. May 19 11:14:32 volumio3 systemd[1]: Stopped Music Player Daemon. May 19 11:14:32 volumio3 systemd[1]: Starting Music Player Daemon... May 19 11:14:32 volumio3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 19 11:14:32 volumio3 systemd[1]: mpd.service: Succeeded. May 19 11:14:32 volumio3 systemd[1]: Stopped Music Player Daemon. May 19 11:14:32 volumio3 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:32 volumio3 systemd[1]: Starting Music Player Daemon... May 19 11:14:32 volumio3 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 11:14:32 volumio3 volumio[990]: info: [1747624472906] CoreMusicLibrary::Adding element Last_100 May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 11:14:32 volumio3 volumio[990]: info: [1747624472911] CoreMusicLibrary::Adding element Webradio May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 11:14:32 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 19 11:14:32 volumio3 volumio[990]: info: Initializing BBC Radios May 19 11:14:32 volumio3 sudo[1164]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 19 11:14:32 volumio3 sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:33 volumio3 sudo[1164]: pam_unix(sudo:session): session closed for user root May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: Volumio Calling Home May 19 11:14:33 volumio3 volumio[990]: info: Discovery: adding 9181ef53-2d7f-4190-aeee-0c7a7b2e2877 May 19 11:14:33 volumio3 volumio[990]: info: Discovery: Found device Volumio3 May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::volumioGetState May 19 11:14:33 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:33 volumio3 volumio[990]: info: MPD Permissions set May 19 11:14:33 volumio3 volumio[990]: info: MPD Permissions set May 19 11:14:33 volumio3 volumio[990]: info: VolumeController:: Volume=30 Mute =false May 19 11:14:33 volumio3 volumio[990]: info: CoreStateMachine::pushState May 19 11:14:33 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::volumioPushState May 19 11:14:33 volumio3 volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 11:14:33 volumio3 volumio[990]: info: Starting Shairport Sync May 19 11:14:33 volumio3 volumio[990]: info: Starting Shairport Sync May 19 11:14:33 volumio3 volumio[990]: info: Starting Shairport Sync May 19 11:14:33 volumio3 sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 11:14:33 volumio3 sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:33 volumio3 sudo[1054]: pam_unix(sudo:session): session closed for user root May 19 11:14:33 volumio3 sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 11:14:33 volumio3 sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:33 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 19 11:14:33 volumio3 sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 11:14:33 volumio3 systemd[1]: shairport-sync.service: Succeeded. May 19 11:14:33 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 19 11:14:33 volumio3 sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:33 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 19 11:14:33 volumio3 sudo[1216]: pam_unix(sudo:session): session closed for user root May 19 11:14:33 volumio3 volumio[990]: info: Shairport-Sync Started May 19 11:14:33 volumio3 volumio[990]: Error adding Membership: Error: addMembership EINVAL May 19 11:14:33 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 19 11:14:33 volumio3 systemd[1]: shairport-sync.service: Succeeded. May 19 11:14:33 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 19 11:14:33 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 19 11:14:33 volumio3 sudo[1218]: pam_unix(sudo:session): session closed for user root May 19 11:14:33 volumio3 sudo[1221]: pam_unix(sudo:session): session closed for user root May 19 11:14:33 volumio3 volumio[990]: info: Shairport-Sync Started May 19 11:14:33 volumio3 volumio[990]: info: Shairport-Sync Started May 19 11:14:33 volumio3 volumio[990]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.101 UA: okhttp/3.12.12 Engine version: 3 Transport: websocket Total Clients: 6 May 19 11:14:33 volumio3 volumio[990]: info: CoreCommandRouter::volumioGetState May 19 11:14:33 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:33 volumio3 volumio[990]: info: Volumio called home May 19 11:14:34 volumio3 mpd[1179]: May 19 11:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 19 11:14:34 volumio3 systemd[1]: Started Music Player Daemon. May 19 11:14:34 volumio3 sudo[1157]: pam_unix(sudo:session): session closed for user root May 19 11:14:34 volumio3 sudo[1146]: pam_unix(sudo:session): session closed for user root May 19 11:14:34 volumio3 volumio[990]: info: Completed starting Core Plugins May 19 11:14:34 volumio3 volumio[990]: info: ------------------------------------------- May 19 11:14:34 volumio3 volumio[990]: info: ----- MyVolumio plugins startup ---- May 19 11:14:34 volumio3 volumio[990]: info: ------------------------------------------- May 19 11:14:34 volumio3 volumio[990]: info: [MyVolumio PluginManager] Fetching plans data.... May 19 11:14:34 volumio3 systemd[1]: systemd-fsckd.service: Succeeded. May 19 11:14:34 volumio3 volumio[990]: error: MPD error: The expression evaluated to a falsy value: May 19 11:14:34 volumio3 volumio[990]: assert.ok(self.idling) May 19 11:14:34 volumio3 volumio[990]: error: The expression evaluated to a falsy value: May 19 11:14:34 volumio3 volumio[990]: assert.ok(self.idling) May 19 11:14:34 volumio3 volumio[990]: error: updateQueue error: null May 19 11:14:34 volumio3 volumio[990]: info: MPD running with PID1179 May 19 11:14:34 volumio3 volumio[990]: ,establishing connection May 19 11:14:34 volumio3 volumio[990]: error: updateQueue error: null May 19 11:14:36 volumio3 volumio[990]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.101 UA: okhttp/3.12.12 Engine version: 3 Transport: polling Total Clients: 7 May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::volumioGetVisibleSources May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::volumioGetState May 19 11:14:36 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 19 11:14:36 volumio3 volumio[990]: info: Received Get System Info May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 11:14:36 volumio3 volumio[990]: info: Discovery: Getting this device information May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::volumioGetState May 19 11:14:36 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 11:14:36 volumio3 volumio[990]: info: CoreCommandRouter::volumioGetState May 19 11:14:36 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:36 volumio3 volumio[990]: info: Listing playlists May 19 11:14:36 volumio3 sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.252/music /mnt/NAS/music May 19 11:14:36 volumio3 sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:36 volumio3 kernel: CIFS: Attempting to mount //192.168.1.252/music May 19 11:14:36 volumio3 kernel: CIFS: VFS: \\192.168.1.252 Dialect not supported by server. Consider specifying vers=1.0 or vers=2.0 on mount for accessing older servers May 19 11:14:36 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -95 May 19 11:14:36 volumio3 sudo[1234]: pam_unix(sudo:session): session closed for user root May 19 11:14:36 volumio3 volumio[990]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds May 19 11:14:37 volumio3 sh[511]: timed out May 19 11:14:37 volumio3 dhcpcd[538]: timed out May 19 11:14:37 volumio3 sh[511]: dhcpcd exited May 19 11:14:37 volumio3 dhcpcd[538]: dhcpcd exited May 19 11:14:37 volumio3 sh[511]: ifup: failed to bring up eth0 May 19 11:14:37 volumio3 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 19 11:14:37 volumio3 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 19 11:14:38 volumio3 sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 19 11:14:38 volumio3 sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:38 volumio3 sudo[1255]: pam_unix(sudo:session): session closed for user root May 19 11:14:38 volumio3 sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 11:14:38 volumio3 sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:38 volumio3 sudo[1257]: pam_unix(sudo:session): session closed for user root May 19 11:14:38 volumio3 sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 19 11:14:38 volumio3 sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:38 volumio3 systemd[1]: Started UPnP Renderer front-end to MPD. May 19 11:14:38 volumio3 sudo[1261]: pam_unix(sudo:session): session closed for user root May 19 11:14:38 volumio3 volumio[990]: info: Upmpdcli Daemon Started May 19 11:14:38 volumio3 volumio[1263]: Generating RSA private key, 4096 bit long modulus (2 primes) May 19 11:14:38 volumio3 systemd[1]: systemd-hostnamed.service: Succeeded. May 19 11:14:38 volumio3 volumio[990]: info: CoreCommandRouter::volumioGetState May 19 11:14:38 volumio3 volumio[990]: info: CorePlayQueue::getTrack 0 May 19 11:14:38 volumio3 volumio[1263]: ..++++ May 19 11:14:39 volumio3 volumio[990]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 19 11:14:40 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 11:14:40 volumio3 wpa_supplicant[887]: wlan0: CTRL-EVENT-BEACON-LOSS May 19 11:14:41 volumio3 sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.252/music /mnt/NAS/music May 19 11:14:41 volumio3 sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:41 volumio3 kernel: CIFS: Attempting to mount //192.168.1.252/music May 19 11:14:41 volumio3 sudo[1283]: pam_unix(sudo:session): session closed for user root May 19 11:14:41 volumio3 volumio[990]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds May 19 11:14:41 volumio3 kernel: CIFS: VFS: \\192.168.1.252 Dialect not supported by server. Consider specifying vers=1.0 or vers=2.0 on mount for accessing older servers May 19 11:14:41 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -95 May 19 11:14:42 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 19 11:14:43 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 19 11:14:44 volumio3 volumio[990]: info: Adding plugin bluetooth to MyMusic Plugins May 19 11:14:44 volumio3 volumio[990]: info: Adding plugin multiroom to MyMusic Plugins May 19 11:14:44 volumio3 volumio[990]: info: Adding plugin metavolumio to MyMusic Plugins May 19 11:14:44 volumio3 volumio[990]: info: Adding plugin cd_controller to MyMusic Plugins May 19 11:14:44 volumio3 volumio[990]: info: Adding plugin qobuzconnect to MyMusic Plugins May 19 11:14:44 volumio3 volumio[990]: info: Adding plugin smart_inputs to MyMusic Plugins May 19 11:14:44 volumio3 volumio[990]: info: Adding plugin tidalconnect to MyMusic Plugins May 19 11:14:44 volumio3 volumio[990]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 19 11:14:45 volumio3 volumio[990]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 19 11:14:45 volumio3 volumio[990]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 19 11:14:45 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:45 volumio3 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 11:14:45 volumio3 volumio[990]: info: Starting MyVolumio Remote Streaming Endpoints May 19 11:14:45 volumio3 volumio[990]: info: MyVolumio not started May 19 11:14:45 volumio3 volumio[990]: info: Initializing device activation check May 19 11:14:45 volumio3 volumio[990]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 19 11:14:45 volumio3 volumio[990]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 19 11:14:45 volumio3 wpa_supplicant[887]: wlan0: CTRL-EVENT-BEACON-LOSS May 19 11:14:47 volumio3 volumio[990]: info: Starting Streaming Service Transparent Proxy May 19 11:14:47 volumio3 volumio[990]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 19 11:14:47 volumio3 volumio[990]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 19 11:14:47 volumio3 volumio[990]: info: Streaming services startup May 19 11:14:47 volumio3 volumio[990]: info: Starting Streaming Daemon May 19 11:14:47 volumio3 sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 19 11:14:47 volumio3 sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 11:14:47 volumio3 sudo[1297]: pam_unix(sudo:session): session closed for user root May 19 11:14:47 volumio3 volumio[990]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 19 11:14:47 volumio3 volumio[990]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 11:14:47 volumio3 volumio[990]: TypeError: Cannot read property 'replace' of undefined May 19 11:14:47 volumio3 volumio[990]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) May 19 11:14:47 volumio3 volumio[990]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) May 19 11:14:47 volumio3 volumio[990]: at listOnTimeout (internal/timers.js:554:17) May 19 11:14:47 volumio3 volumio[990]: at processTimers (internal/timers.js:497:7) May 19 11:14:47 volumio3 volumio[990]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 11:14:47 volumio3 sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-19 11:13 May 19 11:14:47 volumio3 sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"