-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2024-02-27 04:22:09 UTC. -- Feb 27 04:21:24 volumiojason fake-hwclock[201]: Tue Feb 27 04:21:24 UTC 2024 Feb 27 04:21:24 volumiojason systemd[1]: Mounting Kernel Configuration File System... Feb 27 04:21:24 volumiojason systemd[1]: Started Restore / save the current clock. Feb 27 04:21:24 volumiojason systemd[1]: Mounted Kernel Configuration File System. Feb 27 04:21:24 volumiojason systemd[1]: Started Load/Save Random Seed. Feb 27 04:21:24 volumiojason systemd[1]: Started Apply Kernel Variables. Feb 27 04:21:24 volumiojason systemd[1]: dynamicswap.service: Succeeded. Feb 27 04:21:24 volumiojason systemd[1]: Started Create System Users. Feb 27 04:21:24 volumiojason systemd[1]: Starting Create Static Device Nodes in /dev... Feb 27 04:21:24 volumiojason systemd[1]: Started Create Static Device Nodes in /dev. Feb 27 04:21:24 volumiojason systemd[1]: Starting udev Kernel Device Manager... Feb 27 04:21:24 volumiojason systemd[1]: Reached target Local File Systems (Pre). Feb 27 04:21:24 volumiojason systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 27 04:21:24 volumiojason systemd[1]: Mounting /tmp... Feb 27 04:21:24 volumiojason systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 27 04:21:24 volumiojason systemd[1]: Mounting /var/log... Feb 27 04:21:24 volumiojason systemd[1]: Mounting /var/spool/cups... Feb 27 04:21:24 volumiojason systemd[1]: Mounted /tmp. Feb 27 04:21:24 volumiojason systemd[1]: Mounted /var/log. Feb 27 04:21:24 volumiojason systemd[1]: Mounted /var/spool/cups. Feb 27 04:21:24 volumiojason systemd-udevd[235]: Network interface NamePolicy= disabled on kernel command line, ignoring. Feb 27 04:21:24 volumiojason systemd[1]: Mounting /var/spool/cups/tmp... Feb 27 04:21:24 volumiojason systemd[1]: Starting Flush Journal to Persistent Storage... Feb 27 04:21:24 volumiojason systemd[1]: Mounted /var/spool/cups/tmp. Feb 27 04:21:24 volumiojason systemd[1]: Started udev Kernel Device Manager. Feb 27 04:21:24 volumiojason systemd[1]: Started udev Coldplug all Devices. Feb 27 04:21:24 volumiojason systemd[1]: Starting Helper to synchronize boot up for ifupdown... Feb 27 04:21:24 volumiojason systemd[1]: Starting Show Plymouth Boot Screen... Feb 27 04:21:24 volumiojason systemd-journald[199]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 7.5M, max 30.0M, 22.5M free. Feb 27 04:21:24 volumiojason systemd[1]: Started Flush Journal to Persistent Storage. Feb 27 04:21:24 volumiojason systemd[1]: Received SIGRTMIN+20 from PID 262 (plymouthd). Feb 27 04:21:24 volumiojason systemd[1]: Started Show Plymouth Boot Screen. Feb 27 04:21:24 volumiojason systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 27 04:21:24 volumiojason systemd[1]: Reached target Local Encrypted Volumes. Feb 27 04:21:24 volumiojason systemd[1]: Reached target Paths. Feb 27 04:21:24 volumiojason systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 27 04:21:24 volumiojason kernel: mc: Linux media interface: v0.10 Feb 27 04:21:24 volumiojason systemd-udevd[257]: Using default interface naming scheme 'v240'. Feb 27 04:21:24 volumiojason systemd-udevd[257]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 27 04:21:24 volumiojason kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Feb 27 04:21:24 volumiojason kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Feb 27 04:21:25 volumiojason kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:25 volumiojason kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Feb 27 04:21:25 volumiojason kernel: [vc_sm_connected_init]: start Feb 27 04:21:25 volumiojason kernel: [vc_sm_connected_init]: installed successfully Feb 27 04:21:25 volumiojason kernel: videodev: Linux video capture interface: v2.00 Feb 27 04:21:25 volumiojason systemd-udevd[269]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 27 04:21:25 volumiojason kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:25 volumiojason kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:25 volumiojason kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:25 volumiojason kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:25 volumiojason kernel: Bluetooth: Core ver 2.22 Feb 27 04:21:25 volumiojason kernel: NET: Registered PF_BLUETOOTH protocol family Feb 27 04:21:25 volumiojason kernel: Bluetooth: HCI device and connection manager initialized Feb 27 04:21:25 volumiojason kernel: Bluetooth: HCI socket layer initialized Feb 27 04:21:25 volumiojason kernel: Bluetooth: L2CAP socket layer initialized Feb 27 04:21:25 volumiojason kernel: Bluetooth: SCO socket layer initialized Feb 27 04:21:25 volumiojason kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:25 volumiojason kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 27 04:21:25 volumiojason kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Feb 27 04:21:25 volumiojason kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Feb 27 04:21:25 volumiojason kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:25 volumiojason kernel: Bluetooth: HCI UART driver ver 2.3 Feb 27 04:21:25 volumiojason kernel: Bluetooth: HCI UART protocol H4 registered Feb 27 04:21:25 volumiojason kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 27 04:21:25 volumiojason kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 27 04:21:25 volumiojason kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Feb 27 04:21:25 volumiojason kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Feb 27 04:21:25 volumiojason kernel: rpivid feb10000.codec: Device registered as /dev/video19 Feb 27 04:21:25 volumiojason kernel: uart-pl011 fe201000.serial: no DMA platform data Feb 27 04:21:26 volumiojason kernel: Bluetooth: hci0: BCM: chip id 107 Feb 27 04:21:26 volumiojason kernel: Bluetooth: hci0: BCM: features 0x2f Feb 27 04:21:26 volumiojason kernel: Bluetooth: hci0: BCM4345C0 Feb 27 04:21:26 volumiojason kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Feb 27 04:21:26 volumiojason kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 27 04:21:26 volumiojason kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Feb 27 04:21:26 volumiojason kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Feb 27 04:21:26 volumiojason kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Feb 27 04:21:26 volumiojason kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Feb 27 04:21:26 volumiojason kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Feb 27 04:21:26 volumiojason kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 27 04:21:26 volumiojason kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Feb 27 04:21:26 volumiojason kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Feb 27 04:21:26 volumiojason systemd-udevd[246]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 27 04:21:26 volumiojason kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Feb 27 04:21:26 volumiojason kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 27 04:21:26 volumiojason kernel: usbcore: registered new interface driver brcmfmac Feb 27 04:21:26 volumiojason kernel: usbcore: registered new interface driver snd-usb-audio Feb 27 04:21:27 volumiojason kernel: Bluetooth: hci0: BCM: features 0x2f Feb 27 04:21:27 volumiojason kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Feb 27 04:21:27 volumiojason kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Feb 27 04:21:27 volumiojason kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Feb 27 04:21:27 volumiojason systemd-udevd[274]: Using default interface naming scheme 'v240'. Feb 27 04:21:27 volumiojason systemd-udevd[274]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 27 04:21:27 volumiojason systemd-udevd[246]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Feb 27 04:21:28 volumiojason systemd-udevd[269]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. Feb 27 04:21:28 volumiojason systemd[1]: Started Helper to synchronize boot up for ifupdown. Feb 27 04:21:28 volumiojason systemd[1]: Found device /dev/mmcblk0p1. Feb 27 04:21:28 volumiojason systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Feb 27 04:21:28 volumiojason systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Feb 27 04:21:28 volumiojason systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 27 04:21:28 volumiojason systemd[1]: Condition check resulted in Huge Pages File System being skipped. Feb 27 04:21:28 volumiojason systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 27 04:21:28 volumiojason systemd[1]: Condition check resulted in FUSE Control File System being skipped. Feb 27 04:21:28 volumiojason systemd[1]: Created slice system-bthelper.slice. Feb 27 04:21:29 volumiojason systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Feb 27 04:21:29 volumiojason systemd[1]: Starting File System Check on /dev/mmcblk0p1... Feb 27 04:21:29 volumiojason systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 27 04:21:29 volumiojason systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 27 04:21:29 volumiojason systemd[1]: Started File System Check Daemon to report status. Feb 27 04:21:29 volumiojason systemd[1]: Started Load/Save RF Kill Switch Status. Feb 27 04:21:29 volumiojason systemd-fsck[407]: fsck.fat 4.1 (2017-01-24) Feb 27 04:21:29 volumiojason systemd-fsck[407]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Feb 27 04:21:29 volumiojason systemd-fsck[407]: Automatically removing dirty bit. Feb 27 04:21:29 volumiojason systemd-fsck[407]: Performing changes. Feb 27 04:21:29 volumiojason systemd-fsck[407]: /dev/mmcblk0p1: 373 files, 33360/46774 clusters Feb 27 04:21:29 volumiojason systemd[1]: Started File System Check on /dev/mmcblk0p1. Feb 27 04:21:29 volumiojason systemd[1]: Mounting /boot... Feb 27 04:21:29 volumiojason systemd[1]: Mounted /boot. Feb 27 04:21:29 volumiojason systemd[1]: Reached target Local File Systems. Feb 27 04:21:29 volumiojason systemd[1]: Started ifup for wlan0. Feb 27 04:21:29 volumiojason systemd[1]: Starting Preprocess NFS configuration... Feb 27 04:21:29 volumiojason systemd[1]: Starting Raise network interfaces... Feb 27 04:21:29 volumiojason systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 27 04:21:29 volumiojason systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 27 04:21:29 volumiojason systemd[1]: Starting Create Volatile Files and Directories... Feb 27 04:21:29 volumiojason systemd[1]: Started ifup for eth0. Feb 27 04:21:29 volumiojason systemd[1]: Received SIGRTMIN+20 from PID 262 (plymouthd). Feb 27 04:21:29 volumiojason systemd[1]: plymouth-read-write.service: Succeeded. Feb 27 04:21:29 volumiojason systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 27 04:21:30 volumiojason systemd[1]: nfs-config.service: Succeeded. Feb 27 04:21:30 volumiojason systemd[1]: Started Preprocess NFS configuration. Feb 27 04:21:30 volumiojason systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 27 04:21:30 volumiojason systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 27 04:21:30 volumiojason systemd[1]: Reached target NFS client services. Feb 27 04:21:30 volumiojason systemd[1]: Started Create Volatile Files and Directories. Feb 27 04:21:30 volumiojason systemd[1]: Starting RPC bind portmap service... Feb 27 04:21:30 volumiojason kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 27 04:21:30 volumiojason systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Feb 27 04:21:30 volumiojason systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 27 04:21:30 volumiojason kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 27 04:21:30 volumiojason systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 27 04:21:30 volumiojason systemd[1]: Started RPC bind portmap service. Feb 27 04:21:30 volumiojason systemd[1]: Reached target Remote File Systems (Pre). Feb 27 04:21:30 volumiojason systemd[1]: Reached target Remote File Systems. Feb 27 04:21:30 volumiojason systemd[1]: Reached target RPC Port Mapper. Feb 27 04:21:30 volumiojason systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 27 04:21:30 volumiojason systemd[1]: Reached target System Initialization. Feb 27 04:21:30 volumiojason systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 27 04:21:30 volumiojason systemd[1]: Listening on triggerhappy.socket. Feb 27 04:21:30 volumiojason systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 27 04:21:30 volumiojason sh[426]: eth0: waiting for carrier Feb 27 04:21:30 volumiojason kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Feb 27 04:21:30 volumiojason kernel: bcmgenet fd580000.ethernet eth0: Link is Down Feb 27 04:21:30 volumiojason dhcpcd[462]: eth0: waiting for carrier Feb 27 04:21:30 volumiojason systemd[1]: Started Volumio Splash. Feb 27 04:21:30 volumiojason systemd[519]: volumiosplash.service: Failed to execute command: No such file or directory Feb 27 04:21:30 volumiojason systemd[519]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Feb 27 04:21:30 volumiojason systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2026-08-25 13:48:11 UTC as it is in the future. Feb 27 04:21:30 volumiojason systemd[1]: Started Daily apt download activities. Feb 27 04:21:30 volumiojason systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 27 04:21:30 volumiojason systemd[1]: Reached target Sockets. Feb 27 04:21:30 volumiojason systemd[1]: Reached target Basic System. Feb 27 04:21:30 volumiojason systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 27 04:21:30 volumiojason systemd[1]: Starting Login Service... Feb 27 04:21:30 volumiojason systemd[1]: Started UPnP Renderer front-end to MPD. Feb 27 04:21:30 volumiojason systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 27 04:21:30 volumiojason systemd[1]: Started volumio-remote-updater.service. Feb 27 04:21:30 volumiojason systemd[1]: Starting triggerhappy global hotkey daemon... Feb 27 04:21:30 volumiojason systemd[1]: Starting dhcpcd on all interfaces... Feb 27 04:21:30 volumiojason systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 27 04:21:30 volumiojason systemd[1]: Started Volumio Log Rotation Service. Feb 27 04:21:30 volumiojason systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 27 04:21:30 volumiojason systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 27 04:21:30 volumiojason systemd[1]: Starting Wireless Services... Feb 27 04:21:30 volumiojason systemd[1]: Started D-Bus System Message Bus. Feb 27 04:21:30 volumiojason thd[534]: Unable to parse trigger line: Feb 27 04:21:30 volumiojason thd[534]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 27 04:21:30 volumiojason thd[534]: Unable to parse trigger line: Feb 27 04:21:30 volumiojason thd[534]: Found socket passed from systemd Feb 27 04:21:30 volumiojason dhcpcd[536]: Not running dhcpcd because /etc/network/interfaces Feb 27 04:21:30 volumiojason dhcpcd[536]: defines some interfaces that will use a Feb 27 04:21:30 volumiojason dhcpcd[536]: DHCP client or static address Feb 27 04:21:30 volumiojason systemd[1]: Starting WPA supplicant... Feb 27 04:21:30 volumiojason systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 27 04:21:30 volumiojason systemd[1]: Started Volumio Iptables Module. Feb 27 04:21:30 volumiojason systemd[1]: Started Manage Sound Card State (restore and store). Feb 27 04:21:30 volumiojason systemd[1]: Starting Save/Restore Sound Card State... Feb 27 04:21:30 volumiojason systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2026-08-25 06:22:32 UTC as it is in the future. Feb 27 04:21:30 volumiojason systemd[1]: Started Daily apt upgrade and clean activities. Feb 27 04:21:30 volumiojason systemd[1]: Reached target Timers. Feb 27 04:21:30 volumiojason systemd[1]: Started triggerhappy global hotkey daemon. Feb 27 04:21:30 volumiojason alsactl[549]: alsactl 1.1.8 daemon started Feb 27 04:21:30 volumiojason systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Feb 27 04:21:30 volumiojason systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Feb 27 04:21:30 volumiojason systemd[1]: hciuart.service: Succeeded. Feb 27 04:21:30 volumiojason systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 27 04:21:30 volumiojason systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Feb 27 04:21:30 volumiojason systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Feb 27 04:21:30 volumiojason systemd[1]: Failed to start dhcpcd on all interfaces. Feb 27 04:21:30 volumiojason ifup[417]: ifup: waiting for lock on /run/network/ifstate.wlan0 Feb 27 04:21:30 volumiojason systemd-logind[530]: New seat seat0. Feb 27 04:21:30 volumiojason systemd[1]: Started Save/Restore Sound Card State. Feb 27 04:21:30 volumiojason sh[414]: wlan0=wlan0 Feb 27 04:21:31 volumiojason systemd[1]: Reached target Sound Card. Feb 27 04:21:31 volumiojason avahi-daemon[547]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 27 04:21:31 volumiojason avahi-daemon[547]: Successfully dropped root privileges. Feb 27 04:21:31 volumiojason systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 27 04:21:31 volumiojason avahi-daemon[547]: avahi-daemon 0.7 starting up. Feb 27 04:21:31 volumiojason volumio-remote-updater[532]: Error: No active session Feb 27 04:21:31 volumiojason volumio-remote-updater[532]: [2024-02-27 04:21:31] [info] asio async_connect error: system:111 (Connection refused) Feb 27 04:21:31 volumiojason volumio-remote-updater[532]: [2024-02-27 04:21:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 27 04:21:31 volumiojason volumio-remote-updater[532]: [2024-02-27 04:21:31] [error] handle_connect error: Underlying Transport Error Feb 27 04:21:31 volumiojason bthelper[582]: Raspberry Pi BDADDR already set Feb 27 04:21:31 volumiojason volumio[531]: Could not open config: /tmp/upmpdcli.conf Feb 27 04:21:31 volumiojason loadcpufreq[538]: Loading cpufreq kernel modules...done (none). Feb 27 04:21:31 volumiojason systemd[1]: Started Login Service. Feb 27 04:21:31 volumiojason systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 27 04:21:31 volumiojason systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 27 04:21:31 volumiojason systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 27 04:21:31 volumiojason systemd[1]: Started Raspberry Pi bluetooth helper. Feb 27 04:21:31 volumiojason wpa_supplicant[544]: Successfully initialized wpa_supplicant Feb 27 04:21:31 volumiojason avahi-daemon[547]: Successfully called chroot(). Feb 27 04:21:31 volumiojason avahi-daemon[547]: Successfully dropped remaining capabilities. Feb 27 04:21:31 volumiojason systemd[1]: Started WPA supplicant. Feb 27 04:21:31 volumiojason systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 27 04:21:31 volumiojason avahi-daemon[547]: Loading service file /services/volumio.service. Feb 27 04:21:31 volumiojason avahi-daemon[547]: Network interface enumeration completed. Feb 27 04:21:31 volumiojason avahi-daemon[547]: Server startup complete. Host name is volumiojason.local. Local service cookie is 3832914280. Feb 27 04:21:31 volumiojason avahi-daemon[547]: Service "VolumioJason" (/services/volumio.service) successfully established. Feb 27 04:21:31 volumiojason systemd[1]: Starting Bluetooth service... Feb 27 04:21:31 volumiojason systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 27 04:21:31 volumiojason systemd[1]: Started Raise network interfaces. Feb 27 04:21:31 volumiojason systemd[1]: Reached target Network. Feb 27 04:21:31 volumiojason systemd[1]: Starting Permit User Sessions... Feb 27 04:21:31 volumiojason systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 27 04:21:31 volumiojason systemd[1]: Starting Network Time Service... Feb 27 04:21:31 volumiojason systemd[1]: Reached target Network is Online. Feb 27 04:21:31 volumiojason systemd[1]: Starting Samba NMB Daemon... Feb 27 04:21:31 volumiojason systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 04:21:31 volumiojason systemd[1]: Starting LSB: Brings up/down network automatically... Feb 27 04:21:31 volumiojason systemd[1]: Starting /etc/rc.local Compatibility... Feb 27 04:21:32 volumiojason systemd[1]: Starting OpenBSD Secure Shell server... Feb 27 04:21:32 volumiojason bluetoothd[633]: Bluetooth daemon 5.50 Feb 27 04:21:32 volumiojason systemd[1]: Started Permit User Sessions. Feb 27 04:21:32 volumiojason systemd[1]: Started /etc/rc.local Compatibility. Feb 27 04:21:32 volumiojason bluetoothd[633]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 27 04:21:32 volumiojason bluetoothd[633]: Starting SDP server Feb 27 04:21:32 volumiojason bluetoothd[633]: Excluding (cli) sap Feb 27 04:21:32 volumiojason ntpd[641]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 27 04:21:32 volumiojason ntpd[641]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 27 04:21:32 volumiojason systemd[1]: Started Network Time Service. Feb 27 04:21:32 volumiojason systemd[1]: Started Bluetooth service. Feb 27 04:21:32 volumiojason ntpd[660]: proto: precision = 2.074 usec (-19) Feb 27 04:21:32 volumiojason ntpd[660]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 27 04:21:32 volumiojason ntpd[660]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 27 04:21:32 volumiojason ntpd[660]: Listen and drop on 0 v6wildcard [::]:123 Feb 27 04:21:32 volumiojason ntpd[660]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 27 04:21:32 volumiojason ntpd[660]: Listen normally on 2 lo 127.0.0.1:123 Feb 27 04:21:32 volumiojason ntpd[660]: Listening on routing socket on fd #19 for interface updates Feb 27 04:21:32 volumiojason ntpd[660]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 27 04:21:32 volumiojason ntpd[660]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 27 04:21:32 volumiojason kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 27 04:21:32 volumiojason kernel: Bluetooth: BNEP filters: protocol multicast Feb 27 04:21:32 volumiojason kernel: Bluetooth: BNEP socket layer initialized Feb 27 04:21:32 volumiojason bluetoothd[633]: Bluetooth management interface 1.22 initialized Feb 27 04:21:32 volumiojason kernel: Bluetooth: MGMT ver 1.22 Feb 27 04:21:32 volumiojason dbus-daemon[540]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=633 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 27 04:21:32 volumiojason cpufrequtils[637]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 27 04:21:32 volumiojason systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 27 04:21:32 volumiojason bluetoothd[633]: Failed to set privacy: Rejected (0x0b) Feb 27 04:21:32 volumiojason ifplugd(eth0)[684]: ifplugd 0.28 initializing. Feb 27 04:21:32 volumiojason ifplugd(eth0)[684]: Using interface eth0/E4:5F:01:2D:4E:1B with driver (version: 6.1.69-v7l+) Feb 27 04:21:32 volumiojason ifplugd(eth0)[684]: Using detection mode: SIOCETHTOOL Feb 27 04:21:32 volumiojason ifplugd(eth0)[684]: Initialization complete, link beat not detected. Feb 27 04:21:32 volumiojason ifplugd[646]: Network Interface Plugging Daemon...start eth0...done. Feb 27 04:21:32 volumiojason systemd[1]: Started LSB: Brings up/down network automatically. Feb 27 04:21:32 volumiojason systemd[1]: iptables.service: Succeeded. Feb 27 04:21:32 volumiojason systemd[1]: Starting Hostname Service... Feb 27 04:21:32 volumiojason systemd[1]: Reached target Bluetooth. Feb 27 04:21:32 volumiojason systemd[1]: Starting Hold until boot process finishes up... Feb 27 04:21:32 volumiojason systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 27 04:21:32 volumiojason sshd[690]: Server listening on 0.0.0.0 port 22. Feb 27 04:21:32 volumiojason sshd[690]: Server listening on :: port 22. Feb 27 04:21:32 volumiojason systemd[1]: Started OpenBSD Secure Shell server. Feb 27 04:21:32 volumiojason systemd[1]: Received SIGRTMIN+21 from PID 262 (plymouthd). Feb 27 04:21:33 volumiojason systemd[1]: Received SIGRTMIN+21 from PID 262 (plymouthd). Feb 27 04:21:33 volumiojason systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 27 04:21:33 volumiojason systemd[1]: Started Hold until boot process finishes up. Feb 27 04:21:33 volumiojason systemd[1]: plymouth-quit.service: Succeeded. Feb 27 04:21:33 volumiojason systemd[1]: Started Terminate Plymouth Boot Screen. Feb 27 04:21:33 volumiojason systemd[1]: plymouth-start.service: Succeeded. Feb 27 04:21:33 volumiojason systemd[1]: Started Getty on tty1. Feb 27 04:21:33 volumiojason systemd[1]: Reached target Login Prompts. Feb 27 04:21:33 volumiojason dbus-daemon[540]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 27 04:21:33 volumiojason systemd[1]: Started Hostname Service. Feb 27 04:21:33 volumiojason haveged[491]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 27 04:21:33 volumiojason haveged[491]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Feb 27 04:21:33 volumiojason haveged[491]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00191 Feb 27 04:21:33 volumiojason haveged[491]: haveged: fills: 0, generated: 0 Feb 27 04:21:33 volumiojason ntpd[660]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 27 04:21:33 volumiojason nmbd[657]: [2024/02/27 04:21:33.524213, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 27 04:21:33 volumiojason nmbd[657]: started asyncdns process 696 Feb 27 04:21:33 volumiojason nmbd[657]: [2024/02/27 04:21:33.525924, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 27 04:21:33 volumiojason nmbd[657]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 27 04:21:33 volumiojason nmbd[657]: [2024/02/27 04:21:33.526040, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 27 04:21:33 volumiojason nmbd[657]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 27 04:21:33 volumiojason wireless.js[539]: Cleaning previous... Feb 27 04:21:34 volumiojason sudo[704]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 27 04:21:34 volumiojason sudo[704]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:34 volumiojason sudo[704]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:34 volumiojason sudo[706]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 27 04:21:34 volumiojason sudo[706]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:34 volumiojason systemd[1]: systemd-rfkill.service: Succeeded. Feb 27 04:21:34 volumiojason ntpd[660]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 27 04:21:34 volumiojason sudo[706]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:34 volumiojason sh[426]: eth0: carrier acquired Feb 27 04:21:34 volumiojason dhcpcd[462]: eth0: carrier acquired Feb 27 04:21:34 volumiojason wireless.js[539]: Stopped aP Feb 27 04:21:34 volumiojason sh[426]: DUID 00:01:00:01:2b:b8:6c:41:e4:5f:01:2d:4e:1b Feb 27 04:21:34 volumiojason sh[426]: eth0: IAID 01:2d:4e:1b Feb 27 04:21:34 volumiojason sh[426]: eth0: adding address fe80::e65f:1ff:fe2d:4e1b Feb 27 04:21:34 volumiojason sh[426]: ipv6_addaddr1: Permission denied Feb 27 04:21:34 volumiojason dhcpcd[462]: DUID 00:01:00:01:2b:b8:6c:41:e4:5f:01:2d:4e:1b Feb 27 04:21:34 volumiojason dhcpcd[462]: eth0: IAID 01:2d:4e:1b Feb 27 04:21:34 volumiojason dhcpcd[462]: eth0: adding address fe80::e65f:1ff:fe2d:4e1b Feb 27 04:21:34 volumiojason dhcpcd[462]: ipv6_addaddr1: Permission denied Feb 27 04:21:34 volumiojason kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Feb 27 04:21:34 volumiojason wireless.js[539]: Wireless Networking DISABLED, not starting wireless flow Feb 27 04:21:34 volumiojason systemd[1]: wireless.service: Succeeded. Feb 27 04:21:34 volumiojason systemd[1]: Started Wireless Services. Feb 27 04:21:34 volumiojason systemd[1]: Started Volumio Backend Module. Feb 27 04:21:34 volumiojason ifplugd(eth0)[684]: Link beat detected. Feb 27 04:21:34 volumiojason systemd[1]: Started Volumio Cpu Tweaker. Feb 27 04:21:34 volumiojason volumio-cpu-tweak[716]: Setting RT Priority for mpd Feb 27 04:21:34 volumiojason volumio-cpu-tweak[716]: pid 35's current scheduling policy: SCHED_OTHER Feb 27 04:21:34 volumiojason volumio-cpu-tweak[716]: pid 35's current scheduling priority: 0 Feb 27 04:21:34 volumiojason volumio-cpu-tweak[716]: Setting MPD Affinity Feb 27 04:21:34 volumiojason volumio-cpu-tweak[716]: pid 3's current affinity mask: f Feb 27 04:21:34 volumiojason volumio-cpu-tweak[716]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 27 04:21:34 volumiojason volumio-cpu-tweak[716]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 27 04:21:34 volumiojason ifplugd(eth0)[684]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 27 04:21:34 volumiojason ifplugd(eth0)[684]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Feb 27 04:21:34 volumiojason systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 27 04:21:35 volumiojason sh[426]: eth0: rebinding lease of 10.10.1.52 Feb 27 04:21:35 volumiojason dhcpcd[462]: eth0: rebinding lease of 10.10.1.52 Feb 27 04:21:35 volumiojason ntpd[660]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 27 04:21:35 volumiojason sh[426]: eth0: soliciting an IPv6 router Feb 27 04:21:35 volumiojason dhcpcd[462]: eth0: soliciting an IPv6 router Feb 27 04:21:36 volumiojason ntpd[660]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 27 04:21:36 volumiojason volumio-remote-updater[532]: [2024-02-27 04:21:36] [connect] Successful connection Feb 27 04:21:36 volumiojason bthelper[582]: Changing power off succeeded Feb 27 04:21:36 volumiojason bthelper[582]: [CHG] Controller E4:5F:01:2D:4E:1F Powered: no Feb 27 04:21:36 volumiojason bthelper[582]: [CHG] Controller E4:5F:01:2D:4E:1F Discovering: no Feb 27 04:21:36 volumiojason bthelper[582]: [CHG] Controller E4:5F:01:2D:4E:1F Class: 0x00000000 Feb 27 04:21:36 volumiojason volumio[715]: info: ------------------------------------------- Feb 27 04:21:36 volumiojason volumio[715]: info: ----- Volumio3 ---- Feb 27 04:21:36 volumiojason volumio[715]: info: ------------------------------------------- Feb 27 04:21:36 volumiojason volumio[715]: info: ----- System startup ---- Feb 27 04:21:36 volumiojason volumio[715]: info: ------------------------------------------- Feb 27 04:21:36 volumiojason bthelper[582]: [CHG] Controller E4:5F:01:2D:4E:1F Class: 0x0000041c Feb 27 04:21:36 volumiojason bthelper[582]: Changing power on succeeded Feb 27 04:21:36 volumiojason bthelper[582]: [CHG] Controller E4:5F:01:2D:4E:1F Powered: yes Feb 27 04:21:37 volumiojason volumio[715]: info: MYVOLUMIO Environment detected Feb 27 04:21:37 volumiojason volumio[715]: info: Plugin folders cleanup Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning into folder /volumio/app/plugins/ Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning category audio_interface Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning category miscellanea Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning category music_service Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning category plugins.json Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning category system_controller Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning category user_interface Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning into folder /data/plugins/ Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning category audio_interface Feb 27 04:21:37 volumiojason volumio[715]: info: Scanning category music_service Feb 27 04:21:37 volumiojason volumio[715]: info: Plugin folders cleanup completed Feb 27 04:21:37 volumiojason volumio[715]: info: ------------------------------------------- Feb 27 04:21:37 volumiojason volumio[715]: info: ----- Core plugins startup ---- Feb 27 04:21:37 volumiojason volumio[715]: info: ------------------------------------------- Feb 27 04:21:37 volumiojason volumio[715]: info: Loading plugins from folder /volumio/app/plugins/ Feb 27 04:21:37 volumiojason volumio[715]: info: Adding plugin upnp to MyMusic Plugins Feb 27 04:21:37 volumiojason volumio[715]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 27 04:21:37 volumiojason volumio[715]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 27 04:21:37 volumiojason volumio[715]: info: Loading plugins from folder /data/plugins/ Feb 27 04:21:37 volumiojason volumio[715]: info: Loading plugin "system"... Feb 27 04:21:37 volumiojason volumio[715]: info: Loading plugin "appearance"... Feb 27 04:21:38 volumiojason sh[426]: eth0: probing address 10.10.1.52/24 Feb 27 04:21:38 volumiojason dhcpcd[462]: eth0: probing address 10.10.1.52/24 Feb 27 04:21:38 volumiojason volumio[715]: info: Loading plugin "network"... Feb 27 04:21:38 volumiojason volumio[715]: info: Refreshing Cached IP Addresses Feb 27 04:21:38 volumiojason volumio[715]: info: Loading plugin "services"... Feb 27 04:21:38 volumiojason sudo[758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 04:21:38 volumiojason sudo[758]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:38 volumiojason volumio[715]: info: Loading plugin "alsa_controller"... Feb 27 04:21:38 volumiojason sudo[758]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:38 volumiojason sudo[760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 04:21:38 volumiojason sudo[760]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:38 volumiojason sudo[760]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:38 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 04:21:38 volumiojason volumio[715]: info: Loading plugin "wizard"... Feb 27 04:21:38 volumiojason volumio[715]: info: Loading plugin "networkfs"... Feb 27 04:21:38 volumiojason volumio[715]: info: Starting Udev Watcher for removable devices Feb 27 04:21:38 volumiojason sudo[768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=selpelt,password=Tme9Xzf!49152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.10.0.9/Data\\myMusic\\Audio Books\\Audible\\English\\m4a /mnt/NAS/AudiobooksJ Feb 27 04:21:38 volumiojason sudo[768]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:38 volumiojason sudo[770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=selpelt,password=Tme9Xzf!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.10.0.7/Data\\myMusic\\Jason /mnt/NAS/JasonMusic Feb 27 04:21:38 volumiojason sudo[770]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:38 volumiojason volumio[715]: info: Ignoring mount for partition: boot Feb 27 04:21:38 volumiojason volumio[715]: info: Ignoring mount for partition: volumio Feb 27 04:21:38 volumiojason volumio[715]: info: Ignoring mount for partition: volumio_data Feb 27 04:21:38 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 27 04:21:38 volumiojason volumio[715]: info: Loading plugin "volumio_command_line_client"... Feb 27 04:21:38 volumiojason volumio[715]: info: Loading plugin "upnp"... Feb 27 04:21:39 volumiojason volumio[715]: info: [1709007699028] Starting Upmpd Daemon Feb 27 04:21:39 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "my_music"... Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "mpd"... Feb 27 04:21:39 volumiojason sudo[770]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:39 volumiojason sudo[768]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:39 volumiojason kernel: Key type cifs.spnego registered Feb 27 04:21:39 volumiojason kernel: Key type cifs.idmap registered Feb 27 04:21:39 volumiojason 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. Feb 27 04:21:39 volumiojason kernel: CIFS: Attempting to mount \\10.10.0.9\Data Feb 27 04:21:39 volumiojason kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 27 04:21:39 volumiojason kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 27 04:21:39 volumiojason kernel: CIFS: Attempting to mount \\10.10.0.7\Data Feb 27 04:21:39 volumiojason kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 27 04:21:39 volumiojason kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "upnp_browser"... Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "alarm-clock"... Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "airplay_emulation"... Feb 27 04:21:39 volumiojason volumio[715]: info: Starting Shairport Sync Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "last_100"... Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "webradio"... Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "i2s_dacs"... Feb 27 04:21:39 volumiojason volumio[715]: info: Loading plugin "volumiodiscovery"... Feb 27 04:21:39 volumiojason volumio[715]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 27 04:21:39 volumiojason volumio[715]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 27 04:21:39 volumiojason node[715]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 27 04:21:39 volumiojason volumio[715]: *** WARNING *** For more information see Feb 27 04:21:39 volumiojason volumio[715]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 27 04:21:39 volumiojason volumio[715]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 27 04:21:39 volumiojason volumio[715]: *** WARNING *** For more information see Feb 27 04:21:39 volumiojason node[715]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 27 04:21:39 volumiojason node[715]: *** WARNING *** For more information see Feb 27 04:21:39 volumiojason node[715]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 27 04:21:39 volumiojason node[715]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 27 04:21:39 volumiojason node[715]: *** WARNING *** For more information see Feb 27 04:21:40 volumiojason volumio[715]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 27 04:21:40 volumiojason volumio[715]: info: Discovery: Started advertising with name: VolumioJason Feb 27 04:21:40 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 27 04:21:40 volumiojason volumio[715]: info: Loading plugin "spop"... Feb 27 04:21:41 volumiojason volumio[715]: info: Loading plugin "youtube2"... Feb 27 04:21:43 volumiojason volumio[715]: info: Loading plugin "outputs"... Feb 27 04:21:43 volumiojason volumio[715]: info: Loading plugin "albumart"... Feb 27 04:21:43 volumiojason volumio[715]: info: Plugin example_plugin is not enabled Feb 27 04:21:43 volumiojason volumio[715]: info: Loading plugin "inputs"... Feb 27 04:21:43 volumiojason volumio[715]: info: Loading plugin "updater_comm"... Feb 27 04:21:43 volumiojason volumio[715]: info: Plugin mpdemulation is not enabled Feb 27 04:21:43 volumiojason volumio[715]: info: Loading plugin "rest_api"... Feb 27 04:21:43 volumiojason volumio[715]: info: Loading plugin "websocket"... Feb 27 04:21:43 volumiojason volumio[715]: info: Plugin fusiondsp is not enabled Feb 27 04:21:43 volumiojason volumio[715]: info: Loading i18n strings for locale en Feb 27 04:21:43 volumiojason volumio[715]: Updating browse sources language Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 04:21:43 volumiojason volumio[715]: Forking 3 albumart workers Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::initPlayerControls Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 04:21:43 volumiojason volumio[715]: Express server listening on port 3000 Feb 27 04:21:43 volumiojason volumio[715]: [Metrics] WebUI: 7s 567.22ms Feb 27 04:21:43 volumiojason sh[426]: eth0: leased 10.10.1.52 for 86400 seconds Feb 27 04:21:43 volumiojason sh[426]: eth0: adding route to 10.10.1.0/24 Feb 27 04:21:43 volumiojason sh[426]: eth0: adding default route via 10.10.1.1 Feb 27 04:21:43 volumiojason dhcpcd[462]: eth0: leased 10.10.1.52 for 86400 seconds Feb 27 04:21:43 volumiojason dhcpcd[462]: eth0: adding route to 10.10.1.0/24 Feb 27 04:21:43 volumiojason dhcpcd[462]: eth0: adding default route via 10.10.1.1 Feb 27 04:21:43 volumiojason avahi-daemon[547]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.10.1.52. Feb 27 04:21:43 volumiojason avahi-daemon[547]: New relevant interface eth0.IPv4 for mDNS. Feb 27 04:21:43 volumiojason avahi-daemon[547]: Registering new address record for 10.10.1.52 on eth0.IPv4. Feb 27 04:21:43 volumiojason volumio[715]: info: CoreStateMachine::resetVolumioState Feb 27 04:21:43 volumiojason volumio[715]: info: CoreStateMachine::getcurrentVolume Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::volumioRetrievevolume Feb 27 04:21:43 volumiojason sh[426]: forked to background, child pid 840 Feb 27 04:21:43 volumiojason dhcpcd[462]: forked to background, child pid 840 Feb 27 04:21:43 volumiojason volumio[715]: info: Cannot mount NAS JasonMusic at system boot, trial number 1 ,retrying in 5 seconds Feb 27 04:21:43 volumiojason volumio[715]: info: Cannot mount NAS AudiobooksJ at system boot, trial number 1 ,retrying in 5 seconds Feb 27 04:21:43 volumiojason ifplugd(eth0)[684]: client: ifup: interface eth0 already configured Feb 27 04:21:43 volumiojason sh[426]: eth0=eth0 Feb 27 04:21:43 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:43 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:43 volumiojason ifplugd(eth0)[684]: Program executed successfully. Feb 27 04:21:43 volumiojason volumio[715]: Starting albumart workers Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 04:21:44 volumiojason volumio[715]: info: Reloading queue from file Feb 27 04:21:44 volumiojason volumio[715]: Starting albumart workers Feb 27 04:21:44 volumiojason volumio[715]: info: VolumeController:: Volume=35 Mute =false Feb 27 04:21:44 volumiojason volumio[715]: info: CoreStateMachine::pushState Feb 27 04:21:44 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::volumioPushState Feb 27 04:21:44 volumiojason volumio[715]: info: CoreStateMachine::updateTrackBlock Feb 27 04:21:44 volumiojason volumio[715]: info: CorePlayQueue::getTrackBlock Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::volumioRetrievevolume Feb 27 04:21:44 volumiojason volumio[715]: Starting albumart workers Feb 27 04:21:44 volumiojason volumio[715]: info: Setting Device type: Raspberry PI Feb 27 04:21:44 volumiojason volumio[715]: info: CoreStateMachine::setRepeat false single undefined Feb 27 04:21:44 volumiojason volumio[715]: info: CoreStateMachine::pushState Feb 27 04:21:44 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::volumioPushState Feb 27 04:21:44 volumiojason volumio[715]: info: CoreStateMachine::setRandom false Feb 27 04:21:44 volumiojason volumio[715]: info: CoreStateMachine::pushState Feb 27 04:21:44 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::volumioPushState Feb 27 04:21:44 volumiojason volumio[715]: info: Listing playlists Feb 27 04:21:44 volumiojason volumio[715]: info: Listing playlists Feb 27 04:21:44 volumiojason volumio[715]: info: Discovery: adding dc236ac1-7883-4bc4-bd6f-4dad7582f64b Feb 27 04:21:44 volumiojason volumio[715]: info: Discovery: Found device VolumioHouse Feb 27 04:21:44 volumiojason volumio[715]: info: Discovery: Connecting to remote: 10.10.1.54 Feb 27 04:21:44 volumiojason volumio[715]: info: VolumeController:: Volume=35 Mute =false Feb 27 04:21:44 volumiojason volumio[715]: info: CoreStateMachine::pushState Feb 27 04:21:44 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::volumioPushState Feb 27 04:21:44 volumiojason volumio[715]: info: Discovery: Connected to remote: 10.10.1.54 Feb 27 04:21:44 volumiojason volumio[715]: info: Discovery: adding 5e93511a-d891-43e0-934e-ca89374c39ef Feb 27 04:21:44 volumiojason volumio[715]: info: Discovery: Found device VolumioJason Feb 27 04:21:44 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:44 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:45 volumiojason ntpd[660]: Listen normally on 3 eth0 10.10.1.52:123 Feb 27 04:21:45 volumiojason ntpd[660]: new interface(s) found: waking up resolver Feb 27 04:21:46 volumiojason volumio[715]: verbose: New Socket.io Connection to 10.10.1.52:3000 from 10.10.1.54 UA: node-XMLHttpRequest Total Clients: 1 Feb 27 04:21:46 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 27 04:21:46 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:46 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:47 volumiojason sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 04:21:47 volumiojason sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:47 volumiojason sudo[900]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:47 volumiojason sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 04:21:47 volumiojason sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:47 volumiojason sudo[902]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:48 volumiojason nmbd[657]: [2024/02/27 04:21:48.528896, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 27 04:21:48 volumiojason systemd[1]: Started Samba NMB Daemon. Feb 27 04:21:48 volumiojason nmbd[657]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 27 04:21:48 volumiojason nmbd[657]: [2024/02/27 04:21:48.548375, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 27 04:21:48 volumiojason nmbd[657]: query_name_response: Multiple (2) responses received for a query on subnet 10.10.1.52 for name WORKGROUP<1d>. Feb 27 04:21:48 volumiojason nmbd[657]: This response was from IP 10.10.1.8, reporting an IP address of 10.10.1.8. Feb 27 04:21:48 volumiojason systemd[1]: Starting Samba Winbind Daemon... Feb 27 04:21:48 volumiojason volumio[715]: verbose: New Socket.io Connection to 10.10.1.52 from 10.10.1.22 UA: Mozilla/5.0 (Linux; Android 5.1.1; KFAUWI Build/LVY48F; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/108.0.5359.220 Safari/537.36 Total Clients: 2 Feb 27 04:21:48 volumiojason sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=selpelt,password=Tme9Xzf!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.10.0.7/Data\\myMusic\\Jason /mnt/NAS/JasonMusic Feb 27 04:21:48 volumiojason sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:48 volumiojason sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=selpelt,password=Tme9Xzf!49152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.10.0.9/Data\\myMusic\\Audio Books\\Audible\\English\\m4a /mnt/NAS/AudiobooksJ Feb 27 04:21:48 volumiojason sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:48 volumiojason kernel: CIFS: Attempting to mount \\10.10.0.7\Data Feb 27 04:21:48 volumiojason winbindd[905]: [2024/02/27 04:21:48.804302, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 27 04:21:48 volumiojason winbindd[905]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 27 04:21:48 volumiojason winbindd[905]: [2024/02/27 04:21:48.839438, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 27 04:21:48 volumiojason winbindd[905]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 27 04:21:48 volumiojason systemd[1]: Started Samba Winbind Daemon. Feb 27 04:21:48 volumiojason volumio-remote-updater[532]: [2024-02-27 04:21:48] [connect] Successful connection Feb 27 04:21:48 volumiojason volumio-remote-updater[532]: [2024-02-27 04:21:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1709007708 101 Feb 27 04:21:48 volumiojason volumio[715]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Feb 27 04:21:48 volumiojason systemd[1]: Starting Samba SMB Daemon... Feb 27 04:21:49 volumiojason sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 04:21:49 volumiojason sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:49 volumiojason sudo[922]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:49 volumiojason sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 04:21:49 volumiojason sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:49 volumiojason sudo[924]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:49 volumiojason sudo[928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 27 04:21:49 volumiojason sudo[928]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:21:49 volumiojason systemd[1]: Started UPnP Renderer front-end to MPD. Feb 27 04:21:49 volumiojason sudo[928]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:49 volumiojason volumio[715]: info: Upmpdcli Daemon Started Feb 27 04:21:49 volumiojason volumio[715]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 27 04:21:49 volumiojason winbindd[905]: [2024/02/27 04:21:49.304840, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Feb 27 04:21:49 volumiojason winbindd[905]: res_names->count = 3, expected 4 Feb 27 04:21:49 volumiojason smbd[920]: [2024/02/27 04:21:49.332015, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 27 04:21:49 volumiojason smbd[920]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 27 04:21:49 volumiojason systemd[1]: Started Samba SMB Daemon. Feb 27 04:21:49 volumiojason systemd[1]: Reached target Multi-User System. Feb 27 04:21:49 volumiojason systemd[1]: Reached target Graphical Interface. Feb 27 04:21:49 volumiojason systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 27 04:21:49 volumiojason systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 27 04:21:49 volumiojason systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 27 04:21:49 volumiojason systemd[1]: Startup finished in 9.453s (kernel) + 27.304s (userspace) = 36.758s. Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:52 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetQueue Feb 27 04:21:52 volumiojason volumio[715]: info: CoreStateMachine::getQueue Feb 27 04:21:52 volumiojason volumio[715]: info: CorePlayQueue::getQueue Feb 27 04:21:52 volumiojason volumio[715]: info: Listing playlists Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 27 04:21:52 volumiojason volumio[715]: info: Received Get System Info Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 04:21:52 volumiojason volumio[715]: info: Discovery: Getting this device information Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:52 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:52 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 04:21:52 volumiojason volumio[715]: info: Received Get System Info Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 04:21:52 volumiojason volumio[715]: info: Discovery: Getting this device information Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:52 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 27 04:21:52 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 04:21:53 volumiojason volumio[715]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 27 04:21:53 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:53 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:53 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 27 04:21:54 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 04:21:54 volumiojason volumio[715]: info: Received Get System Info Feb 27 04:21:54 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 04:21:54 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 04:21:54 volumiojason volumio[715]: info: Discovery: Getting this device information Feb 27 04:21:54 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:21:54 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:54 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 04:21:55 volumiojason sudo[908]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:55 volumiojason volumio[715]: info: Cannot mount NAS JasonMusic at system boot, trial number 2 ,retrying in 5 seconds Feb 27 04:21:55 volumiojason kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 27 04:21:55 volumiojason kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Feb 27 04:21:55 volumiojason kernel: CIFS: Attempting to mount \\10.10.0.9\Data Feb 27 04:21:55 volumiojason sudo[910]: pam_unix(sudo:session): session closed for user root Feb 27 04:21:55 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 27 04:21:56 volumiojason volumio[715]: info: CoreCommandRouter::volumioPlay Feb 27 04:21:56 volumiojason volumio[715]: info: CoreStateMachine::play index undefined Feb 27 04:21:56 volumiojason volumio[715]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 04:21:56 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:56 volumiojason volumio[715]: info: CoreStateMachine::startPlaybackTimer Feb 27 04:21:56 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:21:56 volumiojason volumio[715]: verbose: ControllerMpd::clearAddPlayTracks NAS/AudiobooksJ/Torsten Weitze/Ahren/Ahren.m4a Feb 27 04:21:56 volumiojason volumio[715]: verbose: ControllerMpd::sendMpdCommand stop Feb 27 04:21:59 volumiojason volumio[715]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 27 04:21:59 volumiojason systemd[1]: systemd-fsckd.service: Succeeded. Feb 27 04:22:00 volumiojason sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=selpelt,password=Tme9Xzf!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.10.0.7/Data\\myMusic\\Jason /mnt/NAS/JasonMusic Feb 27 04:22:00 volumiojason sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 04:22:00 volumiojason kernel: CIFS: Attempting to mount \\10.10.0.7\Data Feb 27 04:22:03 volumiojason systemd[1]: systemd-hostnamed.service: Succeeded. Feb 27 04:22:03 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:22:03 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:22:03 volumiojason volumio[715]: info: Listing playlists Feb 27 04:22:03 volumiojason volumio[715]: info: Listing playlists Feb 27 04:22:05 volumiojason volumio[715]: info: CoreCommandRouter::volumioPlay Feb 27 04:22:05 volumiojason volumio[715]: info: CoreStateMachine::play index undefined Feb 27 04:22:05 volumiojason volumio[715]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 04:22:05 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:22:05 volumiojason volumio[715]: info: CoreStateMachine::startPlaybackTimer Feb 27 04:22:05 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:22:05 volumiojason volumio[715]: verbose: ControllerMpd::clearAddPlayTracks NAS/AudiobooksJ/Torsten Weitze/Ahren/Ahren.m4a Feb 27 04:22:05 volumiojason volumio[715]: verbose: ControllerMpd::sendMpdCommand stop Feb 27 04:22:06 volumiojason sudo[953]: pam_unix(sudo:session): session closed for user root Feb 27 04:22:06 volumiojason volumio[715]: info: Cannot mount NAS JasonMusic at system boot, trial number 3 ,retrying in 5 seconds Feb 27 04:22:06 volumiojason kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 27 04:22:06 volumiojason kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Feb 27 04:22:06 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 04:22:06 volumiojason volumio[715]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 27 04:22:06 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 27 04:22:06 volumiojason volumio[715]: info: Received Get System Version Feb 27 04:22:06 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 04:22:06 volumiojason volumio[715]: info: Received Get System Info Feb 27 04:22:06 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 04:22:06 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 04:22:06 volumiojason volumio[715]: info: Discovery: Getting this device information Feb 27 04:22:06 volumiojason volumio[715]: info: CoreCommandRouter::volumioGetState Feb 27 04:22:06 volumiojason volumio[715]: info: CorePlayQueue::getTrack 0 Feb 27 04:22:06 volumiojason volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 04:22:08 volumiojason volumio[715]: info: Preload queue cleared Feb 27 04:22:08 volumiojason volumio[715]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 27 04:22:08 volumiojason volumio[715]: info: CoreStateMachine::ClearQueue Feb 27 04:22:08 volumiojason volumio[715]: info: CoreStateMachine::stop Feb 27 04:22:08 volumiojason volumio[715]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 04:22:08 volumiojason volumio[715]: info: CorePlayQueue::clearPlayQueue Feb 27 04:22:08 volumiojason volumio[715]: info: CorePlayQueue::saveQueue Feb 27 04:22:08 volumiojason volumio[715]: info: CoreCommandRouter::volumioPushQueue Feb 27 04:22:08 volumiojason volumio[715]: info: CoreStateMachine::addQueueItems Feb 27 04:22:08 volumiojason volumio[715]: info: CorePlayQueue::addQueueItems Feb 27 04:22:08 volumiojason volumio[715]: info: Preload queue cleared Feb 27 04:22:08 volumiojason volumio[715]: info: Adding Item to queue: albums://Torsten%20Weitze/Ahren Feb 27 04:22:08 volumiojason volumio[715]: info: Exploding uri albums://Torsten%20Weitze/Ahren in service mpd Feb 27 04:22:08 volumiojason volumio[715]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 04:22:09 volumiojason volumio[715]: TypeError: Cannot read property 'sendCommand' of undefined Feb 27 04:22:09 volumiojason volumio[715]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2120:20) Feb 27 04:22:09 volumiojason volumio[715]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Feb 27 04:22:09 volumiojason volumio[715]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Feb 27 04:22:09 volumiojason volumio[715]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Feb 27 04:22:09 volumiojason volumio[715]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Feb 27 04:22:09 volumiojason volumio[715]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Feb 27 04:22:09 volumiojason volumio[715]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23) Feb 27 04:22:09 volumiojason volumio[715]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Feb 27 04:22:09 volumiojason volumio[715]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Feb 27 04:22:09 volumiojason volumio[715]: at Socket.emit (events.js:315:20) Feb 27 04:22:09 volumiojason volumio[715]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 27 04:22:09 volumiojason volumio[715]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 27 04:22:09 volumiojason volumio[715]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 04:22:09 volumiojason sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-02-27 04:21 Feb 27 04:22:09 volumiojason sudo[968]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"