-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Mon 2024-07-15 21:39:30 CDT. -- Jul 15 21:38:41 orchard fake-hwclock[190]: Tue Jul 16 02:38:41 UTC 2024 Jul 15 21:38:41 orchard systemd[1]: Mounted POSIX Message Queue File System. Jul 15 21:38:41 orchard systemd[1]: Mounted RPC Pipe File System. Jul 15 21:38:41 orchard systemd[1]: Started Restore / save the current clock. Jul 15 21:38:41 orchard systemd[1]: Mounted Kernel Debug File System. Jul 15 21:38:41 orchard systemd[1]: Started Remount Root and Kernel File Systems. Jul 15 21:38:41 orchard systemd[1]: Starting Load/Save Random Seed... Jul 15 21:38:41 orchard systemd[1]: Starting Create System Users... Jul 15 21:38:41 orchard systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 15 21:38:41 orchard systemd[1]: Starting Apply Kernel Variables... Jul 15 21:38:41 orchard systemd[1]: Mounting Kernel Configuration File System... Jul 15 21:38:41 orchard systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jul 15 21:38:41 orchard systemd[1]: Mounted Kernel Configuration File System. Jul 15 21:38:41 orchard systemd[1]: Started Load/Save Random Seed. Jul 15 21:38:41 orchard systemd[1]: Started Apply Kernel Variables. Jul 15 21:38:41 orchard systemd[1]: dynamicswap.service: Succeeded. Jul 15 21:38:41 orchard systemd[1]: Started Create System Users. Jul 15 21:38:41 orchard systemd[1]: Starting Create Static Device Nodes in /dev... Jul 15 21:38:41 orchard systemd[1]: Started Create Static Device Nodes in /dev. Jul 15 21:38:41 orchard systemd[1]: Reached target Local File Systems (Pre). Jul 15 21:38:41 orchard systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 15 21:38:41 orchard systemd[1]: Mounting /var/log... Jul 15 21:38:41 orchard systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 15 21:38:41 orchard systemd[1]: Mounting /tmp... Jul 15 21:38:41 orchard systemd[1]: Mounting /var/spool/cups... Jul 15 21:38:41 orchard systemd[1]: Starting udev Kernel Device Manager... Jul 15 21:38:41 orchard systemd[1]: Started udev Coldplug all Devices. Jul 15 21:38:41 orchard systemd[1]: Mounted /var/log. Jul 15 21:38:41 orchard systemd[1]: Mounted /tmp. Jul 15 21:38:41 orchard systemd[1]: Mounted /var/spool/cups. Jul 15 21:38:41 orchard systemd[1]: Mounting /var/spool/cups/tmp... Jul 15 21:38:41 orchard systemd[1]: Starting Flush Journal to Persistent Storage... Jul 15 21:38:41 orchard systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jul 15 21:38:41 orchard systemd[1]: Mounted /var/spool/cups/tmp. Jul 15 21:38:41 orchard systemd-udevd[224]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jul 15 21:38:41 orchard systemd[1]: Started Helper to synchronize boot up for ifupdown. Jul 15 21:38:41 orchard systemd-journald[194]: Runtime journal (/run/log/journal/a9f3abf802dbb0b478e09f0f65cbe839) is 7.5M, max 30.0M, 22.5M free. Jul 15 21:38:41 orchard systemd[1]: Started Flush Journal to Persistent Storage. Jul 15 21:38:41 orchard systemd[1]: Started udev Kernel Device Manager. Jul 15 21:38:41 orchard systemd[1]: Starting Show Plymouth Boot Screen... Jul 15 21:38:41 orchard systemd[1]: Received SIGRTMIN+20 from PID 241 (plymouthd). Jul 15 21:38:41 orchard systemd[1]: Started Show Plymouth Boot Screen. Jul 15 21:38:41 orchard systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 15 21:38:41 orchard systemd[1]: Reached target Paths. Jul 15 21:38:41 orchard systemd[1]: Reached target Local Encrypted Volumes. Jul 15 21:38:41 orchard systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jul 15 21:38:42 orchard kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Jul 15 21:38:42 orchard kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Jul 15 21:38:42 orchard kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jul 15 21:38:42 orchard kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jul 15 21:38:42 orchard kernel: [vc_sm_connected_init]: start Jul 15 21:38:42 orchard kernel: mc: Linux media interface: v0.10 Jul 15 21:38:42 orchard kernel: [vc_sm_connected_init]: installed successfully Jul 15 21:38:42 orchard kernel: videodev: Linux video capture interface: v2.00 Jul 15 21:38:42 orchard kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 15 21:38:42 orchard kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jul 15 21:38:42 orchard systemd[1]: Found device /dev/ttyAMA0. Jul 15 21:38:42 orchard kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jul 15 21:38:42 orchard kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jul 15 21:38:42 orchard kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 15 21:38:42 orchard kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jul 15 21:38:42 orchard kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jul 15 21:38:42 orchard kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jul 15 21:38:42 orchard kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Jul 15 21:38:43 orchard kernel: i2c i2c-11: Added multiplexed i2c bus 0 Jul 15 21:38:43 orchard kernel: i2c i2c-11: Added multiplexed i2c bus 10 Jul 15 21:38:43 orchard systemd-udevd[230]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 15 21:38:43 orchard kernel: dwc2 3f980000.usb: supply vusb_d not found, using dummy regulator Jul 15 21:38:43 orchard kernel: dwc2 3f980000.usb: supply vusb_a not found, using dummy regulator Jul 15 21:38:43 orchard kernel: dwc2 3f980000.usb: DWC OTG Controller Jul 15 21:38:43 orchard kernel: dwc2 3f980000.usb: new USB bus registered, assigned bus number 1 Jul 15 21:38:43 orchard kernel: dwc2 3f980000.usb: irq 66, io mem 0x3f980000 Jul 15 21:38:43 orchard kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Jul 15 21:38:43 orchard kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 15 21:38:43 orchard kernel: usb usb1: Product: DWC OTG Controller Jul 15 21:38:43 orchard kernel: usb usb1: Manufacturer: Linux 6.1.77-v7+ dwc2_hsotg Jul 15 21:38:43 orchard kernel: usb usb1: SerialNumber: 3f980000.usb Jul 15 21:38:43 orchard kernel: hub 1-0:1.0: USB hub found Jul 15 21:38:43 orchard kernel: hub 1-0:1.0: 1 port detected Jul 15 21:38:43 orchard systemd[1]: Found device /dev/mmcblk0p1. Jul 15 21:38:43 orchard kernel: usb 1-1: new high-speed USB device number 2 using dwc2 Jul 15 21:38:43 orchard kernel: mipi-dsi 3f700000.dsi.0: Fixed dependency cycle(s) with /soc/dsi@7e700000/port/endpoint Jul 15 21:38:43 orchard kernel: mipi-dsi 3f700000.dsi.0: Fixed dependency cycle(s) with /panel_disp@1/port/endpoint Jul 15 21:38:44 orchard kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 15 21:38:44 orchard kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 15 21:38:44 orchard kernel: hub 1-1:1.0: USB hub found Jul 15 21:38:44 orchard kernel: hub 1-1:1.0: 5 ports detected Jul 15 21:38:44 orchard kernel: Console: switching to colour dummy device 80x30 Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4]) Jul 15 21:38:44 orchard kernel: Registered IR keymap rc-cec Jul 15 21:38:44 orchard kernel: rc rc0: vc4-hdmi as /devices/platform/soc/3f902000.hdmi/rc/rc0 Jul 15 21:38:44 orchard kernel: input: vc4-hdmi as /devices/platform/soc/3f902000.hdmi/rc/rc0/input0 Jul 15 21:38:44 orchard kernel: vc4_hdmi 3f902000.hdmi: 'dmas' DT property is missing or empty, no HDMI audio Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4]) Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: bound 3f700000.dsi (ops vc4_dsi_ops [vc4]) Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: bound 3f004000.txp (ops vc4_txp_ops [vc4]) Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4]) Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4]) Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4]) Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4]) Jul 15 21:38:44 orchard kernel: [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0 Jul 15 21:38:44 orchard kernel: usb 1-1.1: new high-speed USB device number 3 using dwc2 Jul 15 21:38:44 orchard kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 15 21:38:44 orchard kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 15 21:38:44 orchard kernel: smsc95xx v2.0.0 Jul 15 21:38:44 orchard kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199) Jul 15 21:38:44 orchard kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:f6:ce:0e Jul 15 21:38:44 orchard systemd-udevd[233]: Using default interface naming scheme 'v240'. Jul 15 21:38:44 orchard systemd-udevd[233]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 15 21:38:44 orchard kernel: Console: switching to colour frame buffer device 100x30 Jul 15 21:38:44 orchard systemd-udevd[238]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 15 21:38:44 orchard kernel: vc4-drm soc:gpu: [drm] fb0: vc4drmfb frame buffer device Jul 15 21:38:44 orchard kernel: edt_ft5x06 10-0038: supply iovcc not found, using dummy regulator Jul 15 21:38:45 orchard systemd[1]: Created slice system-systemd\x2dbacklight.slice. Jul 15 21:38:45 orchard kernel: usb 1-1.2: new high-speed USB device number 4 using dwc2 Jul 15 21:38:45 orchard systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:10-0045... Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 15 21:38:45 orchard systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jul 15 21:38:45 orchard systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:10-0045. Jul 15 21:38:45 orchard systemd[1]: Started File System Check Daemon to report status. Jul 15 21:38:45 orchard kernel: usb 1-1.2: New USB device found, idVendor=0781, idProduct=55ab, bcdDevice= 1.00 Jul 15 21:38:45 orchard kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 15 21:38:45 orchard kernel: usb 1-1.2: Product: SanDisk 3.2Gen1 Jul 15 21:38:45 orchard kernel: usb 1-1.2: Manufacturer: USB Jul 15 21:38:45 orchard kernel: usb 1-1.2: SerialNumber: 0401140710bcdf9d302d1d68d1733e4113a7c968a56f40bfb3e59bfbc50e24514ee20000000000000000000033f6fce9ff9b5e18ab5581077faf49e4 Jul 15 21:38:45 orchard kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 21:38:45 orchard kernel: input: 10-0038 generic ft5x06 (79) as /devices/platform/soc/3f205000.i2c/i2c-11/i2c-10/10-0038/input/input1 Jul 15 21:38:45 orchard kernel: scsi host0: usb-storage 1-1.2:1.0 Jul 15 21:38:45 orchard kernel: usbcore: registered new interface driver uas Jul 15 21:38:45 orchard systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 15 21:38:45 orchard systemd-fsck[433]: fsck.fat 4.1 (2017-01-24) Jul 15 21:38:45 orchard systemd-fsck[433]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jul 15 21:38:45 orchard systemd-fsck[433]: Automatically removing dirty bit. Jul 15 21:38:45 orchard systemd-fsck[433]: Performing changes. Jul 15 21:38:45 orchard systemd-fsck[433]: /dev/mmcblk0p1: 369 files, 16799/43896 clusters Jul 15 21:38:45 orchard systemd[1]: Started File System Check on /dev/mmcblk0p1. Jul 15 21:38:45 orchard systemd[1]: Mounting /boot... Jul 15 21:38:45 orchard systemd[1]: Mounted /boot. Jul 15 21:38:45 orchard systemd[1]: Reached target Local File Systems. Jul 15 21:38:45 orchard systemd-udevd[230]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 15 21:38:45 orchard systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 15 21:38:45 orchard systemd[1]: Starting Create Volatile Files and Directories... Jul 15 21:38:45 orchard systemd[1]: Starting Raise network interfaces... Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 15 21:38:45 orchard systemd[1]: Started ifup for eth0. Jul 15 21:38:45 orchard systemd[1]: Starting Preprocess NFS configuration... Jul 15 21:38:45 orchard systemd[1]: Received SIGRTMIN+20 from PID 241 (plymouthd). Jul 15 21:38:45 orchard systemd[1]: plymouth-read-write.service: Succeeded. Jul 15 21:38:45 orchard systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 15 21:38:45 orchard systemd[1]: nfs-config.service: Succeeded. Jul 15 21:38:45 orchard systemd[1]: Started Preprocess NFS configuration. Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jul 15 21:38:45 orchard systemd[1]: Reached target NFS client services. Jul 15 21:38:45 orchard systemd[1]: Started Create Volatile Files and Directories. Jul 15 21:38:45 orchard systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jul 15 21:38:45 orchard systemd-udevd[234]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 15 21:38:45 orchard systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jul 15 21:38:45 orchard systemd[1]: Starting RPC bind portmap service... Jul 15 21:38:45 orchard systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jul 15 21:38:45 orchard systemd[1]: Started Update UTMP about System Boot/Shutdown. Jul 15 21:38:45 orchard kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 15 21:38:45 orchard systemd[1]: Reached target System Initialization. Jul 15 21:38:45 orchard systemd[1]: man-db.timer: Not using persistent file timestamp Tue 2024-07-16 08:18:24 CDT as it is in the future. Jul 15 21:38:45 orchard systemd[1]: Started Daily man-db regeneration. Jul 15 21:38:45 orchard systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 15 21:38:45 orchard systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2024-07-16 06:46:01 CDT as it is in the future. Jul 15 21:38:45 orchard systemd[1]: Started Daily apt download activities. Jul 15 21:38:45 orchard systemd[1]: Listening on triggerhappy.socket. Jul 15 21:38:46 orchard kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jul 15 21:38:46 orchard kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jul 15 21:38:46 orchard kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jul 15 21:38:46 orchard systemd[1]: Started Volumio Splash. Jul 15 21:38:46 orchard systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2024-07-16 08:18:24 CDT as it is in the future. Jul 15 21:38:46 orchard systemd[1]: Started Daily apt upgrade and clean activities. Jul 15 21:38:46 orchard systemd[487]: volumiosplash.service: Failed to execute command: No such file or directory Jul 15 21:38:46 orchard systemd[487]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Jul 15 21:38:46 orchard systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 15 21:38:46 orchard kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 15 21:38:46 orchard systemd[1]: Reached target Sockets. Jul 15 21:38:46 orchard systemd[1]: Reached target Basic System. Jul 15 21:38:46 orchard systemd[1]: Started Volumio Log Rotation Service. Jul 15 21:38:46 orchard systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jul 15 21:38:46 orchard systemd[1]: Starting dhcpcd on all interfaces... Jul 15 21:38:46 orchard systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jul 15 21:38:46 orchard systemd[1]: Starting Wireless Services... Jul 15 21:38:46 orchard systemd[1]: Starting triggerhappy global hotkey daemon... Jul 15 21:38:46 orchard systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 15 21:38:46 orchard systemd[1]: Started Manage Sound Card State (restore and store). Jul 15 21:38:46 orchard systemd[1]: Starting Save/Restore Sound Card State... Jul 15 21:38:46 orchard alsactl[501]: alsactl 1.1.8 daemon started Jul 15 21:38:46 orchard systemd[1]: Started UPnP Renderer front-end to MPD. Jul 15 21:38:46 orchard systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Jul 15 21:38:46 orchard ifup[449]: command failed: No such device (-19) Jul 15 21:38:46 orchard dhcpcd[495]: Not running dhcpcd because /etc/network/interfaces Jul 15 21:38:46 orchard dhcpcd[495]: defines some interfaces that will use a Jul 15 21:38:46 orchard dhcpcd[495]: DHCP client or static address Jul 15 21:38:46 orchard systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 15 21:38:46 orchard systemd[1]: Starting Login Service... Jul 15 21:38:46 orchard systemd[1]: Started D-Bus System Message Bus. Jul 15 21:38:46 orchard thd[499]: Unable to parse trigger line: Jul 15 21:38:46 orchard thd[499]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 15 21:38:46 orchard thd[499]: Unable to parse trigger line: Jul 15 21:38:46 orchard thd[499]: Found socket passed from systemd Jul 15 21:38:46 orchard sh[450]: command failed: No such device (-19) Jul 15 21:38:46 orchard systemd[1]: Starting WPA supplicant... Jul 15 21:38:46 orchard systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 15 21:38:46 orchard systemd[1]: Reached target Timers. Jul 15 21:38:46 orchard kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 Jul 15 21:38:46 orchard systemd[1]: Started volumio-remote-updater.service. Jul 15 21:38:46 orchard kernel: sd 0:0:0:0: [sda] 965246976 512-byte logical blocks: (494 GB/460 GiB) Jul 15 21:38:46 orchard kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 15 21:38:46 orchard kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Jul 15 21:38:46 orchard kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Jul 15 21:38:46 orchard systemd[1]: Started Volumio Iptables Module. Jul 15 21:38:46 orchard systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 15 21:38:46 orchard systemd[1]: Started RPC bind portmap service. Jul 15 21:38:46 orchard systemd[1]: Started triggerhappy global hotkey daemon. Jul 15 21:38:46 orchard systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Jul 15 21:38:46 orchard systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Jul 15 21:38:46 orchard systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jul 15 21:38:46 orchard systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jul 15 21:38:46 orchard systemd[1]: Failed to start dhcpcd on all interfaces. Jul 15 21:38:46 orchard systemd[1]: Started Save/Restore Sound Card State. Jul 15 21:38:46 orchard systemd[1]: Reached target Sound Card. Jul 15 21:38:46 orchard systemd[1]: Reached target RPC Port Mapper. Jul 15 21:38:46 orchard systemd[1]: Reached target Remote File Systems (Pre). Jul 15 21:38:46 orchard systemd[1]: Reached target Remote File Systems. Jul 15 21:38:46 orchard systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jul 15 21:38:46 orchard kernel: sda: sda1 sda2 sda3 Jul 15 21:38:46 orchard kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Jul 15 21:38:46 orchard kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jul 15 21:38:46 orchard systemd-logind[507]: New seat seat0. Jul 15 21:38:46 orchard systemd-logind[507]: Watching system buttons on /dev/input/event0 (vc4-hdmi) Jul 15 21:38:46 orchard avahi-daemon[506]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jul 15 21:38:46 orchard avahi-daemon[506]: Successfully dropped root privileges. Jul 15 21:38:46 orchard avahi-daemon[506]: avahi-daemon 0.7 starting up. Jul 15 21:38:47 orchard ifup[449]: command failed: No such device (-19) Jul 15 21:38:47 orchard kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 15 21:38:47 orchard systemd[1]: Started Login Service. Jul 15 21:38:47 orchard avahi-daemon[506]: Successfully called chroot(). Jul 15 21:38:47 orchard avahi-daemon[506]: Successfully dropped remaining capabilities. Jul 15 21:38:47 orchard wpa_supplicant[514]: Successfully initialized wpa_supplicant Jul 15 21:38:47 orchard avahi-daemon[506]: Loading service file /services/volumio.service. Jul 15 21:38:47 orchard avahi-daemon[506]: Network interface enumeration completed. Jul 15 21:38:47 orchard avahi-daemon[506]: Server startup complete. Host name is orchard.local. Local service cookie is 428052480. Jul 15 21:38:47 orchard avahi-daemon[506]: Service "orchard" (/services/volumio.service) successfully established. Jul 15 21:38:48 orchard volumio-remote-updater[517]: Error: No active session Jul 15 21:38:48 orchard volumio-remote-updater[517]: [2024-07-15 21:38:48] [info] asio async_connect error: system:111 (Connection refused) Jul 15 21:38:48 orchard volumio-remote-updater[517]: [2024-07-15 21:38:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 15 21:38:48 orchard volumio-remote-updater[517]: [2024-07-15 21:38:48] [error] handle_connect error: Underlying Transport Error Jul 15 21:38:48 orchard sh[450]: eth0: waiting for carrier Jul 15 21:38:48 orchard dhcpcd[540]: eth0: waiting for carrier Jul 15 21:38:48 orchard kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 15 21:38:48 orchard kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 15 21:38:48 orchard sh[450]: eth0: carrier acquired Jul 15 21:38:48 orchard dhcpcd[540]: eth0: carrier acquired Jul 15 21:38:48 orchard volumio[504]: Could not open config: /tmp/upmpdcli.conf Jul 15 21:38:48 orchard sh[450]: DUID 00:01:00:01:2d:5e:a7:53:b8:27:eb:f6:ce:0e Jul 15 21:38:48 orchard sh[450]: eth0: IAID eb:f6:ce:0e Jul 15 21:38:48 orchard dhcpcd[540]: DUID 00:01:00:01:2d:5e:a7:53:b8:27:eb:f6:ce:0e Jul 15 21:38:48 orchard dhcpcd[540]: eth0: IAID eb:f6:ce:0e Jul 15 21:38:48 orchard sh[450]: eth0: adding address fe80::666:1891:3944:89a6 Jul 15 21:38:48 orchard sh[450]: ipv6_addaddr1: Permission denied Jul 15 21:38:48 orchard dhcpcd[540]: eth0: adding address fe80::666:1891:3944:89a6 Jul 15 21:38:48 orchard dhcpcd[540]: ipv6_addaddr1: Permission denied Jul 15 21:38:48 orchard systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 15 21:38:48 orchard systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 15 21:38:48 orchard sh[450]: eth0: soliciting an IPv6 router Jul 15 21:38:48 orchard dhcpcd[540]: eth0: soliciting an IPv6 router Jul 15 21:38:48 orchard systemd[1]: iptables.service: Succeeded. Jul 15 21:38:48 orchard haveged[475]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jul 15 21:38:48 orchard haveged[475]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jul 15 21:38:48 orchard haveged[475]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8 Jul 15 21:38:48 orchard haveged[475]: haveged: fills: 0, generated: 0 Jul 15 21:38:48 orchard sh[450]: eth0: rebinding lease of 192.168.42.18 Jul 15 21:38:48 orchard dhcpcd[540]: eth0: rebinding lease of 192.168.42.18 Jul 15 21:38:48 orchard systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 15 21:38:48 orchard systemd[1]: Started WPA supplicant. Jul 15 21:38:49 orchard systemd[1]: Started Raise network interfaces. Jul 15 21:38:49 orchard systemd[1]: Reached target Network. Jul 15 21:38:49 orchard loadcpufreq[520]: Loading cpufreq kernel modules...done (none). Jul 15 21:38:49 orchard systemd[1]: Starting Permit User Sessions... Jul 15 21:38:49 orchard systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jul 15 21:38:49 orchard sh[450]: eth0: probing address 192.168.42.18/24 Jul 15 21:38:49 orchard dhcpcd[540]: eth0: probing address 192.168.42.18/24 Jul 15 21:38:49 orchard systemd[1]: Starting OpenBSD Secure Shell server... Jul 15 21:38:49 orchard systemd[1]: Starting Network Time Service... Jul 15 21:38:49 orchard systemd[1]: Reached target Network is Online. Jul 15 21:38:49 orchard systemd[1]: Starting LSB: Brings up/down network automatically... Jul 15 21:38:49 orchard systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 15 21:38:49 orchard systemd[1]: Starting Samba NMB Daemon... Jul 15 21:38:49 orchard systemd[1]: Starting /etc/rc.local Compatibility... Jul 15 21:38:49 orchard systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jul 15 21:38:49 orchard systemd[1]: Started Permit User Sessions. Jul 15 21:38:49 orchard systemd[1]: Started /etc/rc.local Compatibility. Jul 15 21:38:49 orchard systemd[1]: Starting Terminate Plymouth Boot Screen... Jul 15 21:38:49 orchard systemd[1]: Starting Hold until boot process finishes up... Jul 15 21:38:49 orchard systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jul 15 21:38:49 orchard systemd[1]: Received SIGRTMIN+21 from PID 241 (plymouthd). Jul 15 21:38:49 orchard systemd[1]: Received SIGRTMIN+21 from PID 241 (plymouthd). Jul 15 21:38:49 orchard systemd[1]: plymouth-quit.service: Succeeded. Jul 15 21:38:49 orchard systemd[1]: Started Terminate Plymouth Boot Screen. Jul 15 21:38:49 orchard systemd[1]: plymouth-quit-wait.service: Succeeded. Jul 15 21:38:49 orchard systemd[1]: Started Hold until boot process finishes up. Jul 15 21:38:49 orchard systemd[1]: plymouth-start.service: Succeeded. Jul 15 21:38:49 orchard systemd[1]: Started Serial Getty on ttyAMA0. Jul 15 21:38:49 orchard systemd[1]: Reached target Login Prompts. Jul 15 21:38:49 orchard ifplugd(eth0)[677]: ifplugd 0.28 initializing. Jul 15 21:38:49 orchard ifplugd(eth0)[677]: Using interface eth0/B8:27:EB:F6:CE:0E with driver (version: 6.1.77-v7+) Jul 15 21:38:49 orchard ifplugd(eth0)[677]: Using detection mode: SIOCETHTOOL Jul 15 21:38:49 orchard ifplugd(eth0)[677]: Initialization complete, link beat detected. Jul 15 21:38:49 orchard ntpd[646]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 15 21:38:49 orchard ntpd[646]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jul 15 21:38:49 orchard systemd[1]: Started Network Time Service. Jul 15 21:38:49 orchard ntpd[679]: proto: precision = 0.729 usec (-20) Jul 15 21:38:49 orchard cpufrequtils[660]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jul 15 21:38:49 orchard systemd[1]: Started LSB: set CPUFreq kernel parameters. Jul 15 21:38:49 orchard ntpd[679]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 15 21:38:49 orchard ntpd[679]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 15 21:38:49 orchard ntpd[679]: Listen and drop on 0 v6wildcard [::]:123 Jul 15 21:38:49 orchard ntpd[679]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 15 21:38:49 orchard ntpd[679]: Listen normally on 2 lo 127.0.0.1:123 Jul 15 21:38:49 orchard ifplugd(eth0)[677]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 15 21:38:49 orchard ntpd[679]: Listening on routing socket on fd #19 for interface updates Jul 15 21:38:49 orchard ntpd[679]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 15 21:38:49 orchard ntpd[679]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 15 21:38:49 orchard sshd[685]: Server listening on 0.0.0.0 port 22. Jul 15 21:38:49 orchard sshd[685]: Server listening on :: port 22. Jul 15 21:38:49 orchard systemd[1]: Started OpenBSD Secure Shell server. Jul 15 21:38:49 orchard ifplugd(eth0)[677]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jul 15 21:38:50 orchard nmbd[654]: [2024/07/15 21:38:50.442241, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 15 21:38:50 orchard nmbd[654]: started asyncdns process 694 Jul 15 21:38:50 orchard nmbd[654]: [2024/07/15 21:38:50.445565, 0] ../lib/util/become_daemon.c:149(daemon_status) Jul 15 21:38:50 orchard nmbd[654]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 15 21:38:50 orchard nmbd[654]: [2024/07/15 21:38:50.445755, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jul 15 21:38:50 orchard nmbd[654]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 15 21:38:50 orchard wireless.js[497]: WIRELESS: No wireless interface, exiting Jul 15 21:38:50 orchard iw[699]: command failed: No such device (-19) Jul 15 21:38:50 orchard iwconfig[702]: Error for wireless request "Set Power Management" (8B2C) : Jul 15 21:38:50 orchard iwconfig[702]: SET failed on device wlan0 ; No such device. Jul 15 21:38:50 orchard systemd[1]: wireless.service: Succeeded. Jul 15 21:38:50 orchard systemd[1]: Started Wireless Services. Jul 15 21:38:50 orchard systemd[1]: Started Volumio Backend Module. Jul 15 21:38:50 orchard systemd[1]: Started Start Volumio Kiosk. Jul 15 21:38:50 orchard systemd[1]: Started Volumio Cpu Tweaker. Jul 15 21:38:50 orchard startkiosk.sh[706]: Starting Volumio Kiosk with arguments for cursor display Jul 15 21:38:50 orchard ntpd[679]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 15 21:38:50 orchard volumio-cpu-tweak[707]: Setting RT Priority for mpd Jul 15 21:38:50 orchard volumio-cpu-tweak[707]: pid 35's current scheduling policy: SCHED_OTHER Jul 15 21:38:50 orchard volumio-cpu-tweak[707]: pid 35's current scheduling priority: 0 Jul 15 21:38:50 orchard volumio-cpu-tweak[707]: Setting MPD Affinity Jul 15 21:38:50 orchard volumio-cpu-tweak[707]: pid 3's current affinity mask: f Jul 15 21:38:50 orchard volumio-cpu-tweak[707]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 15 21:38:50 orchard volumio-cpu-tweak[707]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 15 21:38:50 orchard systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 15 21:38:50 orchard startkiosk.sh[706]: X.Org X Server 1.20.4 Jul 15 21:38:50 orchard startkiosk.sh[706]: X Protocol Version 11, Revision 0 Jul 15 21:38:50 orchard startkiosk.sh[706]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 15 21:38:50 orchard startkiosk.sh[706]: Current Operating System: Linux orchard 6.1.77-v7+ #1730 SMP Thu Feb 8 15:17:51 GMT 2024 armv7l Jul 15 21:38:50 orchard startkiosk.sh[706]: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 Jul 15 21:38:50 orchard startkiosk.sh[706]: Build Date: 04 April 2023 07:50:56AM Jul 15 21:38:50 orchard startkiosk.sh[706]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 15 21:38:50 orchard startkiosk.sh[706]: Current version of pixman: 0.36.0 Jul 15 21:38:50 orchard startkiosk.sh[706]: Before reporting problems, check http://wiki.x.org Jul 15 21:38:50 orchard startkiosk.sh[706]: to make sure that you have the latest version. Jul 15 21:38:50 orchard startkiosk.sh[706]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 15 21:38:50 orchard startkiosk.sh[706]: (++) from command line, (!!) notice, (II) informational, Jul 15 21:38:50 orchard startkiosk.sh[706]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 15 21:38:50 orchard startkiosk.sh[706]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Jul 15 21:38:50 2024 Jul 15 21:38:50 orchard startkiosk.sh[706]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 15 21:38:51 orchard ntpd[679]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 15 21:38:52 orchard kernel: broken atomic modeset userspace detected, disabling atomic Jul 15 21:38:52 orchard ntpd[679]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 15 21:38:52 orchard startkiosk.sh[706]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jul 15 21:38:53 orchard volumio-remote-updater[517]: [2024-07-15 21:38:53] [info] asio async_connect error: system:111 (Connection refused) Jul 15 21:38:53 orchard volumio-remote-updater[517]: [2024-07-15 21:38:53] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 15 21:38:53 orchard volumio-remote-updater[517]: [2024-07-15 21:38:53] [error] handle_connect error: Underlying Transport Error Jul 15 21:38:53 orchard ntpd[679]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 15 21:38:54 orchard sh[450]: eth0: leased 192.168.42.18 for 86400 seconds Jul 15 21:38:54 orchard dhcpcd[540]: eth0: leased 192.168.42.18 for 86400 seconds Jul 15 21:38:54 orchard sh[450]: eth0: adding route to 192.168.42.0/24 Jul 15 21:38:54 orchard sh[450]: eth0: adding default route via 192.168.42.1 Jul 15 21:38:54 orchard dhcpcd[540]: eth0: adding route to 192.168.42.0/24 Jul 15 21:38:54 orchard avahi-daemon[506]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.42.18. Jul 15 21:38:54 orchard avahi-daemon[506]: New relevant interface eth0.IPv4 for mDNS. Jul 15 21:38:54 orchard avahi-daemon[506]: Registering new address record for 192.168.42.18 on eth0.IPv4. Jul 15 21:38:54 orchard dhcpcd[540]: eth0: adding default route via 192.168.42.1 Jul 15 21:38:54 orchard sh[450]: forked to background, child pid 788 Jul 15 21:38:54 orchard dhcpcd[540]: forked to background, child pid 788 Jul 15 21:38:54 orchard ifplugd(eth0)[677]: client: ifup: interface eth0 already configured Jul 15 21:38:54 orchard sh[450]: eth0=eth0 Jul 15 21:38:54 orchard ifplugd(eth0)[677]: Program executed successfully. Jul 15 21:38:54 orchard ifplugd[647]: Network Interface Plugging Daemon...start eth0...done. Jul 15 21:38:54 orchard systemd[1]: Started LSB: Brings up/down network automatically. Jul 15 21:38:54 orchard volumio[705]: info: ------------------------------------------- Jul 15 21:38:54 orchard volumio[705]: info: ----- Volumio3 ---- Jul 15 21:38:54 orchard volumio[705]: info: ------------------------------------------- Jul 15 21:38:54 orchard volumio[705]: info: ----- System startup ---- Jul 15 21:38:54 orchard volumio[705]: info: ------------------------------------------- Jul 15 21:38:55 orchard systemd[1]: Started Samba NMB Daemon. Jul 15 21:38:55 orchard nmbd[654]: [2024/07/15 21:38:55.461023, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 15 21:38:55 orchard nmbd[654]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 15 21:38:55 orchard systemd[1]: Starting Samba Winbind Daemon... Jul 15 21:38:55 orchard ntpd[679]: Listen normally on 3 eth0 192.168.42.18:123 Jul 15 21:38:55 orchard ntpd[679]: new interface(s) found: waking up resolver Jul 15 21:38:55 orchard winbindd[852]: [2024/07/15 21:38:55.985849, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 15 21:38:55 orchard winbindd[852]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 15 21:38:56 orchard systemd[1]: Started Samba Winbind Daemon. Jul 15 21:38:56 orchard winbindd[852]: [2024/07/15 21:38:56.027785, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 15 21:38:56 orchard winbindd[852]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 15 21:38:56 orchard systemd[1]: Starting Samba SMB Daemon... Jul 15 21:38:56 orchard winbindd[852]: [2024/07/15 21:38:56.631074, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jul 15 21:38:56 orchard winbindd[852]: res_names->count = 3, expected 4 Jul 15 21:38:56 orchard smbd[857]: [2024/07/15 21:38:56.660797, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 15 21:38:56 orchard systemd[1]: Started Samba SMB Daemon. Jul 15 21:38:56 orchard smbd[857]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 15 21:38:56 orchard volumio[705]: info: MYVOLUMIO Environment detected Jul 15 21:38:56 orchard systemd[1]: Reached target Multi-User System. Jul 15 21:38:56 orchard systemd[1]: Reached target Graphical Interface. Jul 15 21:38:56 orchard systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 15 21:38:56 orchard systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 15 21:38:56 orchard systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 15 21:38:56 orchard systemd[1]: Startup finished in 10.096s (kernel) + 17.773s (userspace) = 27.870s. Jul 15 21:38:56 orchard volumio[705]: info: Plugin folders cleanup Jul 15 21:38:56 orchard volumio[705]: info: Scanning into folder /volumio/app/plugins/ Jul 15 21:38:56 orchard volumio[705]: info: Scanning category audio_interface Jul 15 21:38:56 orchard volumio[705]: info: Scanning category miscellanea Jul 15 21:38:56 orchard volumio[705]: info: Scanning category music_service Jul 15 21:38:56 orchard volumio[705]: info: Scanning category plugins.json Jul 15 21:38:56 orchard volumio[705]: info: Scanning category system_controller Jul 15 21:38:56 orchard volumio[705]: info: Scanning category user_interface Jul 15 21:38:56 orchard volumio[705]: info: Scanning into folder /data/plugins/ Jul 15 21:38:56 orchard volumio[705]: info: Scanning category audio_interface Jul 15 21:38:56 orchard volumio[705]: info: Plugin folders cleanup completed Jul 15 21:38:56 orchard volumio[705]: info: ------------------------------------------- Jul 15 21:38:56 orchard volumio[705]: info: ----- Core plugins startup ---- Jul 15 21:38:56 orchard volumio[705]: info: ------------------------------------------- Jul 15 21:38:56 orchard volumio[705]: info: Loading plugins from folder /volumio/app/plugins/ Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin multiroom to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin upnp to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin metavolumio to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin cd_controller to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin raat to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 15 21:38:56 orchard volumio[705]: info: Loading plugins from folder /data/plugins/ Jul 15 21:38:56 orchard volumio[705]: info: Loading plugin "system"... Jul 15 21:38:57 orchard volumio[705]: info: Loading plugin "appearance"... Jul 15 21:38:58 orchard volumio[705]: info: Loading plugin "network"... Jul 15 21:38:58 orchard volumio[705]: info: Refreshing Cached IP Addresses Jul 15 21:38:58 orchard volumio[705]: info: Loading plugin "services"... Jul 15 21:38:58 orchard volumio[705]: info: Loading plugin "alsa_controller"... Jul 15 21:38:58 orchard sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 15 21:38:58 orchard sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 15 21:38:58 orchard sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:38:58 orchard sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 15 21:38:58 orchard sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:38:58 orchard sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:38:58 orchard sudo[877]: pam_unix(sudo:session): session closed for user root Jul 15 21:38:58 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 21:38:58 orchard volumio[705]: info: Loading plugin "wizard"... Jul 15 21:38:58 orchard sudo[881]: pam_unix(sudo:session): session closed for user root Jul 15 21:38:58 orchard sudo[879]: pam_unix(sudo:session): session closed for user root Jul 15 21:38:58 orchard volumio[705]: info: Loading plugin "networkfs"... Jul 15 21:38:59 orchard volumio[705]: info: Starting Udev Watcher for removable devices Jul 15 21:38:59 orchard volumio[705]: info: Ignoring mount for partition: boot Jul 15 21:38:59 orchard volumio[705]: info: Ignoring mount for partition: volumio Jul 15 21:38:59 orchard volumio[705]: info: Ignoring mount for partition: volumio_data Jul 15 21:38:59 orchard volumio[705]: info: Ignoring mount for partition: boot Jul 15 21:38:59 orchard volumio[705]: info: Ignoring mount for partition: volumio Jul 15 21:38:59 orchard volumio[705]: info: Ignoring mount for partition: volumio_data Jul 15 21:38:59 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 15 21:38:59 orchard volumio[705]: info: Loading plugin "volumio_command_line_client"... Jul 15 21:38:59 orchard volumio[705]: info: Loading plugin "upnp"... Jul 15 21:38:59 orchard volumio[705]: info: [1721097539183] Starting Upmpd Daemon Jul 15 21:38:59 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 15 21:38:59 orchard volumio[705]: info: Loading plugin "my_music"... Jul 15 21:38:59 orchard volumio[705]: info: Loading plugin "mpd"... Jul 15 21:39:00 orchard volumio[705]: info: Loading plugin "upnp_browser"... Jul 15 21:39:00 orchard volumio-remote-updater[517]: [2024-07-15 21:39:00] [connect] Successful connection Jul 15 21:39:01 orchard volumio[705]: info: Loading plugin "alarm-clock"... Jul 15 21:39:01 orchard volumio[705]: info: Loading plugin "manifestui"... Jul 15 21:39:02 orchard volumio[705]: info: Loading plugin "metavolumio"... Jul 15 21:39:03 orchard dbus-daemon[509]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=0 pid=871 comm="/usr/bin/vivaldi --kiosk --no-sandbox --disable-ba") Jul 15 21:39:03 orchard systemd[1]: Condition check resulted in Bluetooth service being skipped. Jul 15 21:39:04 orchard volumio[705]: info: Loading plugin "airplay_emulation"... Jul 15 21:39:04 orchard volumio[705]: info: Starting Shairport Sync Jul 15 21:39:04 orchard volumio[705]: info: Loading plugin "cd_controller"... Jul 15 21:39:05 orchard volumio[705]: info: Loading plugin "last_100"... Jul 15 21:39:05 orchard volumio[705]: info: Loading plugin "raat"... Jul 15 21:39:06 orchard volumio[705]: info: RAAT Plugin loaded Jul 15 21:39:06 orchard volumio[705]: info: Adding restartRAATSocket REST API Endpoint Jul 15 21:39:06 orchard volumio[705]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 15 21:39:06 orchard volumio[705]: info: Loading plugin "streaming_services"... Jul 15 21:39:10 orchard volumio[705]: info: Starting Streaming Service Transparent Proxy Jul 15 21:39:10 orchard volumio[705]: info: Loading plugin "tidalconnect"... Jul 15 21:39:11 orchard volumio[705]: info: Loading plugin "webradio"... Jul 15 21:39:11 orchard volumio[705]: info: Loading plugin "i2s_dacs"... Jul 15 21:39:11 orchard volumio[705]: info: I2S DAC not set, start Auto-detection Jul 15 21:39:11 orchard volumio[705]: info: Loading plugin "volumiodiscovery"... Jul 15 21:39:12 orchard volumio[705]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 15 21:39:12 orchard volumio[705]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 15 21:39:12 orchard volumio[705]: *** WARNING *** For more information see Jul 15 21:39:12 orchard volumio[705]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 15 21:39:12 orchard node[705]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 15 21:39:12 orchard volumio[705]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 15 21:39:12 orchard volumio[705]: *** WARNING *** For more information see Jul 15 21:39:12 orchard node[705]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 15 21:39:12 orchard node[705]: *** WARNING *** For more information see Jul 15 21:39:12 orchard node[705]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 15 21:39:12 orchard node[705]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 15 21:39:12 orchard node[705]: *** WARNING *** For more information see Jul 15 21:39:12 orchard volumio[705]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 15 21:39:12 orchard volumio[705]: info: Discovery: Started advertising with name: orchard Jul 15 21:39:12 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 15 21:39:12 orchard volumio[705]: info: Loading plugin "multiroom"... Jul 15 21:39:13 orchard volumio[705]: STREAMING PROXY: Starting server on port 3245 Jul 15 21:39:13 orchard volumio[705]: Node JS runtime: 14 Jul 15 21:39:15 orchard volumio[705]: info: Applying required configuration parameters for plugin multiroom Jul 15 21:39:15 orchard sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 15 21:39:15 orchard sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:15 orchard sudo[1035]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:15 orchard volumio[705]: info: MRS: MultiRoom plugin initialized Jul 15 21:39:15 orchard volumio[705]: info: MRS: STOPPING SNAPCLIENT Jul 15 21:39:15 orchard volumio[705]: info: MRS: Snap server stop Jul 15 21:39:15 orchard sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 15 21:39:15 orchard sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:15 orchard systemd[1]: systemd-fsckd.service: Succeeded. Jul 15 21:39:15 orchard volumio[705]: info: MRS: STOPPING volumioStreaming Jul 15 21:39:15 orchard sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 15 21:39:15 orchard sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:15 orchard sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 15 21:39:15 orchard sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:15 orchard volumio[705]: info: Loading plugin "outputs"... Jul 15 21:39:15 orchard volumio[705]: info: Loading plugin "albumart"... Jul 15 21:39:15 orchard sudo[1051]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:15 orchard sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 15 21:39:15 orchard sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:15 orchard sudo[1059]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:15 orchard sudo[1054]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:15 orchard volumio[705]: info: Plugin example_plugin is not enabled Jul 15 21:39:15 orchard volumio[705]: info: Loading plugin "hi_res_audio"... Jul 15 21:39:15 orchard sudo[1057]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:15 orchard volumio-remote-updater[517]: [2024-07-15 21:39:15] [connect] Successful connection Jul 15 21:39:15 orchard volumio[705]: Forking 3 albumart workers Jul 15 21:39:17 orchard volumio[705]: Starting albumart workers Jul 15 21:39:17 orchard volumio[705]: Starting albumart workers Jul 15 21:39:17 orchard volumio[705]: Starting albumart workers Jul 15 21:39:17 orchard volumio[705]: info: Applying required configuration parameters for plugin hi_res_audio Jul 15 21:39:17 orchard volumio[705]: info: Loading plugin "inputs"... Jul 15 21:39:17 orchard volumio[705]: info: Loading plugin "qobuz"... Jul 15 21:39:19 orchard volumio[705]: info: Loading plugin "smart_inputs"... Jul 15 21:39:20 orchard volumio[705]: info: Loading plugin "tidal"... Jul 15 21:39:21 orchard volumio[705]: info: Loading plugin "acontrol"... Jul 15 21:39:21 orchard volumio[705]: info: Adding this device properties Jul 15 21:39:21 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jul 15 21:39:21 orchard volumio[705]: info: Setting Additional Device Volumio Properties: [object Object] Jul 15 21:39:21 orchard volumio[705]: info: Loading plugin "oem_helper"... Jul 15 21:39:23 orchard volumio[705]: info: Applying required configuration parameters for plugin oem_helper Jul 15 21:39:23 orchard volumio[705]: info: Loading plugin "updater_comm"... Jul 15 21:39:23 orchard volumio[705]: info: Plugin mpdemulation is not enabled Jul 15 21:39:23 orchard volumio[705]: info: Loading plugin "rest_api"... Jul 15 21:39:23 orchard volumio[705]: info: Loading plugin "websocket"... Jul 15 21:39:23 orchard volumio[705]: info: Loading plugin "fusiondsp"... Jul 15 21:39:23 orchard volumio[705]: info: Applying required configuration parameters for plugin fusiondsp Jul 15 21:39:24 orchard volumio[705]: info: Loading i18n strings for locale en Jul 15 21:39:24 orchard volumio[705]: Updating browse sources language Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::initPlayerControls Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 21:39:24 orchard volumio[705]: Express server listening on port 3000 Jul 15 21:39:24 orchard volumio[705]: [Metrics] WebUI: 30s 986.69ms Jul 15 21:39:24 orchard volumio[705]: info: CoreStateMachine::resetVolumioState Jul 15 21:39:24 orchard volumio[705]: info: CoreStateMachine::getcurrentVolume Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::volumioRetrievevolume Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:24 orchard volumio[705]: info: MRS: Removed streaming files Jul 15 21:39:24 orchard volumio[705]: info: MRS: volumioStreaming STOPPED Jul 15 21:39:24 orchard volumio[705]: info: MRS: SNAPSERVER STOPPED Jul 15 21:39:24 orchard volumio[705]: info: MRS: SNAPCLIENT STOPPED Jul 15 21:39:24 orchard volumio[705]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 15 21:39:24 orchard volumio[705]: wlan0 Interface doesn't support scanning. Jul 15 21:39:24 orchard volumio[705]: info: Cannot use regular scanning, forcing with ap-force Jul 15 21:39:24 orchard sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 15 21:39:24 orchard sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 15 21:39:24 orchard sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:24 orchard sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:24 orchard sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 15 21:39:24 orchard sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:24 orchard sudo[1131]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:24 orchard sudo[1135]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:24 orchard sudo[1133]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:24 orchard volumio[705]: command failed: No such device (-19) Jul 15 21:39:24 orchard volumio[705]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jul 15 21:39:24 orchard volumio[705]: command failed: No such device (-19) Jul 15 21:39:24 orchard volumio[705]: info: CoreStateMachine::pushState Jul 15 21:39:24 orchard volumio[705]: info: CorePlayQueue::getTrack 0 Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::volumioPushState Jul 15 21:39:24 orchard volumio[705]: info: CoreStateMachine::updateTrackBlock Jul 15 21:39:24 orchard volumio[705]: info: CorePlayQueue::getTrackBlock Jul 15 21:39:24 orchard volumio[705]: info: CoreCommandRouter::volumioRetrievevolume Jul 15 21:39:25 orchard volumio[705]: info: Reloading queue from file Jul 15 21:39:25 orchard volumio[705]: info: CoreStateMachine::pushState Jul 15 21:39:25 orchard volumio[705]: info: CorePlayQueue::getTrack 0 Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::volumioPushState Jul 15 21:39:25 orchard volumio[705]: info: Setting Device type: Raspberry PI Jul 15 21:39:25 orchard volumio[705]: info: CoreStateMachine::setRepeat null single undefined Jul 15 21:39:25 orchard volumio[705]: info: CoreStateMachine::pushState Jul 15 21:39:25 orchard volumio[705]: info: CorePlayQueue::getTrack 0 Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::volumioPushState Jul 15 21:39:25 orchard volumio[705]: info: CoreStateMachine::setRandom null Jul 15 21:39:25 orchard volumio[705]: info: CoreStateMachine::pushState Jul 15 21:39:25 orchard volumio[705]: info: CorePlayQueue::getTrack 0 Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::volumioPushState Jul 15 21:39:25 orchard volumio[705]: info: Completed loading Core Plugins Jul 15 21:39:25 orchard volumio[705]: info: Preparing to generate the ALSA configuration file Jul 15 21:39:25 orchard sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 15 21:39:25 orchard sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:25 orchard volumio[705]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 15 21:39:25 orchard volumio[705]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 15 21:39:25 orchard volumio[705]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 15 21:39:25 orchard volumio[705]: info: Reading ALSA contributions from plugins. Jul 15 21:39:25 orchard systemd[1]: Started UPnP Renderer front-end to MPD. Jul 15 21:39:25 orchard sudo[1155]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:25 orchard volumio[705]: info: Upmpdcli Daemon Started Jul 15 21:39:25 orchard volumio[705]: info: Asound.conf file unchanged, so no further update is needed Jul 15 21:39:25 orchard volumio[705]: info: Output device has changed, restarting MPD Jul 15 21:39:25 orchard sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 21:39:25 orchard sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:25 orchard sudo[1159]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:25 orchard volumio[705]: info: Output device has changed, restarting Shairport Sync Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:25 orchard sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 21:39:25 orchard sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:25 orchard volumio[705]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 21:39:25 orchard volumio[705]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:25 orchard volumio[705]: info: ___________ START PLUGINS ___________ Jul 15 21:39:25 orchard volumio[705]: info: ControllerMpd::onStart: Initializing MPD Jul 15 21:39:25 orchard volumio[705]: info: Creating MPD Configuration file Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 15 21:39:25 orchard volumio[705]: info: [1721097565826] CoreMusicLibrary::Adding element Media Servers Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 15 21:39:25 orchard sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 21:39:25 orchard sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 21:39:25 orchard sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:25 orchard sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:25 orchard systemd[1]: Listening on mpd.socket. Jul 15 21:39:25 orchard systemd[1]: Starting Music Player Daemon... Jul 15 21:39:25 orchard volumio[705]: info: Adding Manifest REST API Endpoints Jul 15 21:39:25 orchard volumio[705]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jul 15 21:39:25 orchard sudo[1171]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:25 orchard volumio[705]: info: Adding METAVOLUMIO REST API Endpoints Jul 15 21:39:25 orchard volumio[705]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 15 21:39:25 orchard volumio[705]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 15 21:39:25 orchard volumio[705]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 15 21:39:25 orchard volumio[705]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:25 orchard systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 15 21:39:25 orchard systemd[1]: mpd.service: Succeeded. Jul 15 21:39:25 orchard systemd[1]: Stopped Music Player Daemon. Jul 15 21:39:25 orchard systemd[1]: Starting Music Player Daemon... Jul 15 21:39:25 orchard volumio[705]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:25 orchard volumio[705]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 21:39:25 orchard volumio[705]: info: Preparing CD Folders Jul 15 21:39:25 orchard volumio[705]: info: Adding CD REST API Endpoints Jul 15 21:39:25 orchard volumio[705]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 15 21:39:25 orchard volumio[705]: info: Starting UDEV Watcher for CD Jul 15 21:39:25 orchard volumio[705]: info: Detecting CD presence with UDEV Jul 15 21:39:25 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 15 21:39:26 orchard volumio[705]: info: [1721097566036] CoreMusicLibrary::Adding element Last_100 Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 15 21:39:26 orchard volumio[705]: info: Starting RAAT Plugin Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 15 21:39:26 orchard volumio[705]: info: Additional UI Settings Added for plugin music_service/raat Jul 15 21:39:26 orchard volumio[705]: info: Registering DSP Elements listener and retrieving current ones Jul 15 21:39:26 orchard volumio[705]: info: Additional DSP elements updated Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: Updating RAAT Signal Path Jul 15 21:39:26 orchard volumio[705]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: Streaming services startup Jul 15 21:39:26 orchard volumio[705]: info: Starting Streaming Daemon Jul 15 21:39:26 orchard sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 15 21:39:26 orchard sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:26 orchard sudo[1192]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 15 21:39:26 orchard volumio[705]: info: [1721097566348] CoreMusicLibrary::Adding element Webradio Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 21:39:26 orchard volumio[705]: info: Initializing BBC Radios Jul 15 21:39:26 orchard sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 15 21:39:26 orchard sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:26 orchard sudo[1199]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 15 21:39:26 orchard volumio[705]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:26 orchard volumio[705]: error: Hi Res Audio Failed Login: Missing Login Data Jul 15 21:39:26 orchard volumio[705]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 15 21:39:26 orchard volumio[705]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 15 21:39:26 orchard volumio[705]: info: Refreshing QOBUZ token Jul 15 21:39:26 orchard volumio[705]: info: Adding inputs REST Endpoints Jul 15 21:39:26 orchard volumio[705]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 15 21:39:26 orchard volumio[705]: info: Scanning Audio Inputs Jul 15 21:39:26 orchard volumio[705]: info: Checking against Known Cards name Jul 15 21:39:26 orchard volumio[705]: info: Checking against Known Cards name Jul 15 21:39:26 orchard volumio[705]: info: Adding Server instance for streaming Jul 15 21:39:26 orchard volumio[705]: info: Refreshing TIDAL token Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: Loading i18n strings for locale en Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 15 21:39:26 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:26 orchard volumio[705]: info: FusionDsp - mixtype--------------------- Software Jul 15 21:39:26 orchard volumio[705]: info: Volumio Calling Home Jul 15 21:39:27 orchard volumio[705]: (node:705) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jul 15 21:39:27 orchard volumio[705]: (Use `node --trace-deprecation ...` to show where the warning was created) Jul 15 21:39:27 orchard volumio[705]: info: Preparing to generate the ALSA configuration file Jul 15 21:39:27 orchard volumio[705]: info: Discovery: adding 1a4db29c-174a-462c-a36f-448bb50ac2cf Jul 15 21:39:27 orchard volumio[705]: info: Discovery: Found device orchard Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::volumioGetState Jul 15 21:39:27 orchard volumio[705]: info: CorePlayQueue::getTrack 0 Jul 15 21:39:27 orchard volumio[705]: info: MRS: Pushing multiroomSync output for this device Jul 15 21:39:27 orchard volumio[705]: info: MRS: Pushing multiroomSync output Jul 15 21:39:27 orchard volumio[705]: info: Adding audio output: Jul 15 21:39:27 orchard volumio[705]: info: Adding audio output: Jul 15 21:39:27 orchard volumio[705]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 15 21:39:27 orchard volumio[705]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 15 21:39:27 orchard volumio[705]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 15 21:39:27 orchard volumio[705]: info: Reading ALSA contributions from plugins. Jul 15 21:39:27 orchard volumio[705]: error: Cannot start Volumio Streaming Daemon Jul 15 21:39:27 orchard volumio[705]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 15 21:39:27 orchard volumio[705]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 15 21:39:27 orchard volumio[705]: info: RAAT Albumart path created successfully Jul 15 21:39:27 orchard volumio[705]: info: MPD Permissions set Jul 15 21:39:27 orchard volumio[705]: info: MPD Permissions set Jul 15 21:39:27 orchard volumio[705]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:27 orchard sudo[1234]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:27 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:27 orchard sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 15 21:39:27 orchard sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:27 orchard volumio[705]: info: Starting Shairport Sync Jul 15 21:39:28 orchard systemd[1]: Started RAAT DAEMON. Jul 15 21:39:28 orchard sudo[1244]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:28 orchard volumio[705]: info: Starting Shairport Sync Jul 15 21:39:28 orchard sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 15 21:39:28 orchard sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:28 orchard volumio[705]: info: Starting Shairport Sync Jul 15 21:39:28 orchard systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 15 21:39:28 orchard sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 15 21:39:28 orchard sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:28 orchard systemd[1]: shairport-sync.service: Succeeded. Jul 15 21:39:28 orchard systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 15 21:39:28 orchard sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 15 21:39:28 orchard volumio[705]: info: Raat Daemon started successfully Jul 15 21:39:28 orchard volumio[705]: info: Volumio called home Jul 15 21:39:28 orchard volumio[705]: info: Asound.conf file unchanged, so no further update is needed Jul 15 21:39:28 orchard volumio[705]: info: Output device has changed, restarting MPD Jul 15 21:39:28 orchard volumio[705]: info: Output device has changed, restarting Shairport Sync Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 21:39:28 orchard volumio[705]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 15 21:39:28 orchard volumio[705]: info: MRS: Found cast device: V505-G9-9d2bd7e285d0f08d909be62c52283507 Jul 15 21:39:28 orchard volumio[705]: info: Adding audio output: Jul 15 21:39:28 orchard systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 15 21:39:28 orchard sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:28 orchard sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 21:39:28 orchard sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:28 orchard sudo[1262]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:28 orchard sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 21:39:28 orchard sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:28 orchard dbus-daemon[509]: [system] Failed to activate service 'org.bluez': timed out (service_start_timeout=25000ms) Jul 15 21:39:28 orchard sudo[1248]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:28 orchard volumio[705]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 15 21:39:28 orchard volumio[705]: info: MPD Permissions set Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::volumioGetState Jul 15 21:39:28 orchard volumio[705]: info: CorePlayQueue::getTrack 0 Jul 15 21:39:28 orchard systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 15 21:39:28 orchard systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 15 21:39:28 orchard systemd[1]: mpd.service: Succeeded. Jul 15 21:39:28 orchard volumio[705]: info: Shairport-Sync Started Jul 15 21:39:28 orchard systemd[1]: Stopped Music Player Daemon. Jul 15 21:39:28 orchard volumio[705]: Error adding Membership: Error: addMembership EINVAL Jul 15 21:39:28 orchard systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 15 21:39:28 orchard systemd[1]: shairport-sync.service: Succeeded. Jul 15 21:39:28 orchard systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 15 21:39:28 orchard systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 15 21:39:28 orchard sudo[1257]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:28 orchard sudo[1250]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:28 orchard systemd[1]: Starting Music Player Daemon... Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:28 orchard volumio[705]: info: Shairport-Sync Started Jul 15 21:39:28 orchard volumio[705]: info: Shairport-Sync Started Jul 15 21:39:29 orchard volumio[705]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 15 21:39:29 orchard volumio[705]: info: camilladsp service started and running in background, instance 1 Jul 15 21:39:29 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:29 orchard volumio[705]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 15 21:39:29 orchard volumio[705]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 15 21:39:29 orchard volumio[705]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 15 21:39:29 orchard volumio[705]: info: FusionDsp loaded Jul 15 21:39:29 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 21:39:29 orchard volumio[705]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 15 21:39:29 orchard volumio[705]: info: Adding Signal Path Element [object Object] Jul 15 21:39:29 orchard volumio[705]: info: Adding fusiondspeq DSP Signal Path Element Jul 15 21:39:29 orchard volumio[705]: info: Additional DSP elements updated Jul 15 21:39:29 orchard volumio[705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 21:39:29 orchard volumio[705]: info: Updating RAAT Signal Path Jul 15 21:39:29 orchard volumio[705]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 15 21:39:29 orchard volumio[705]: info: FusionDsp - ---- installed callbackRead Jul 15 21:39:29 orchard volumio[705]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 21:39:29 orchard volumio[705]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 15 21:39:29 orchard volumio[705]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 15 21:39:29 orchard volumio[705]: at onErrorNT (internal/child_process.js:465:16) Jul 15 21:39:29 orchard volumio[705]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 15 21:39:29 orchard volumio[705]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 15 21:39:29 orchard volumio[705]: at listOnTimeout (internal/timers.js:523:9) Jul 15 21:39:29 orchard volumio[705]: at processTimers (internal/timers.js:497:7) { Jul 15 21:39:29 orchard volumio[705]: errno: -2, Jul 15 21:39:29 orchard volumio[705]: code: 'ENOENT', Jul 15 21:39:29 orchard volumio[705]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 15 21:39:29 orchard volumio[705]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 15 21:39:29 orchard volumio[705]: spawnargs: [ Jul 15 21:39:29 orchard volumio[705]: '-p', Jul 15 21:39:29 orchard volumio[705]: 9876, Jul 15 21:39:29 orchard volumio[705]: '-o', Jul 15 21:39:29 orchard volumio[705]: '/tmp/camilladsp.log', Jul 15 21:39:29 orchard volumio[705]: '-l', Jul 15 21:39:29 orchard volumio[705]: 'warn', Jul 15 21:39:29 orchard volumio[705]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 15 21:39:29 orchard volumio[705]: ] Jul 15 21:39:29 orchard volumio[705]: } Jul 15 21:39:29 orchard volumio[705]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 21:39:29 orchard sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 15 21:39:29 orchard sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:29 orchard sudo[1290]: pam_unix(sudo:session): session closed for user root Jul 15 21:39:30 orchard sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-15 21:38 Jul 15 21:39:30 orchard sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 21:39:30 orchard volumio-remote-updater[517]: [2024-07-15 21:39:30] [connect] Successful connection 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="729fabca6039b61f31dfca69e97e8cbc8803e4d0" VOLUMIO_FE_VERSION="4046dddc0717a69457a40270e2fcbc46f9ee4c24" VOLUMIO_FE3_VERSION="19db4344fa87f8eaaab1560f7319710ca849c94e" VOLUMIO_BE_VERSION="9010a4e812306a439f27f77137bdcca269a30a20" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="orchard" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 05 May 2024 10:22:41 PM CEST" VOLUMIO_VERSION="3.667" VOLUMIO_HARDWARE="pi-kiosk" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Orchard Audio PecanPi+ Streamer Premium" VOLUMIO_VENDOR="Orchard Audio" VOLUMIO_MODEL="PecanPi+ Streamer Premium" VOLUMIO_HASH="7cde398471f902a1506eb2e779a2c221"