-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Fri 2024-07-19 20:48:17 CST. -- Jul 19 20:47:17 volumio-7 fake-hwclock[332]: Fri Jul 19 12:47:17 UTC 2024 Jul 19 20:47:17 volumio-7 systemd[1]: Mounted Kernel Debug File System. Jul 19 20:47:17 volumio-7 systemd[1]: Started Remount Root and Kernel File Systems. Jul 19 20:47:17 volumio-7 systemd[1]: Started Restore / save the current clock. Jul 19 20:47:17 volumio-7 systemd[1]: Started Load Kernel Modules. Jul 19 20:47:17 volumio-7 systemd[1]: Mounted POSIX Message Queue File System. Jul 19 20:47:17 volumio-7 systemd[1]: Mounted RPC Pipe File System. Jul 19 20:47:17 volumio-7 systemd[1]: Started Create list of required static device nodes for the current kernel. Jul 19 20:47:17 volumio-7 systemd[1]: Mounting FUSE Control File System... Jul 19 20:47:17 volumio-7 systemd[1]: Starting Apply Kernel Variables... Jul 19 20:47:17 volumio-7 systemd[1]: Mounting Kernel Configuration File System... Jul 19 20:47:17 volumio-7 systemd[1]: Starting Load/Save Random Seed... Jul 19 20:47:17 volumio-7 systemd[1]: Starting Create System Users... Jul 19 20:47:17 volumio-7 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 19 20:47:17 volumio-7 systemd[1]: dynamicswap.service: Succeeded. Jul 19 20:47:17 volumio-7 systemd[1]: Mounted FUSE Control File System. Jul 19 20:47:17 volumio-7 systemd[1]: Started Apply Kernel Variables. Jul 19 20:47:17 volumio-7 systemd[1]: Mounted Kernel Configuration File System. Jul 19 20:47:17 volumio-7 systemd[1]: Started Load/Save Random Seed. Jul 19 20:47:17 volumio-7 systemd[1]: Started Create System Users. Jul 19 20:47:17 volumio-7 systemd[1]: Starting Create Static Device Nodes in /dev... Jul 19 20:47:17 volumio-7 systemd[1]: Started Create Static Device Nodes in /dev. Jul 19 20:47:17 volumio-7 systemd[1]: Reached target Local File Systems (Pre). Jul 19 20:47:17 volumio-7 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 19 20:47:17 volumio-7 systemd[1]: Mounting /tmp... Jul 19 20:47:17 volumio-7 systemd[1]: Mounting /var/spool/cups... Jul 19 20:47:17 volumio-7 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 19 20:47:17 volumio-7 systemd[1]: Mounting /var/log... Jul 19 20:47:17 volumio-7 systemd[1]: Starting udev Kernel Device Manager... Jul 19 20:47:17 volumio-7 systemd[1]: Mounted /tmp. Jul 19 20:47:17 volumio-7 systemd[1]: Mounted /var/spool/cups. Jul 19 20:47:17 volumio-7 systemd[1]: Mounting /var/spool/cups/tmp... Jul 19 20:47:17 volumio-7 systemd[1]: Mounted /var/log. Jul 19 20:47:17 volumio-7 systemd[1]: Started udev Coldplug all Devices. Jul 19 20:47:17 volumio-7 systemd[1]: Mounted /var/spool/cups/tmp. Jul 19 20:47:17 volumio-7 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jul 19 20:47:17 volumio-7 systemd[1]: Starting Flush Journal to Persistent Storage... Jul 19 20:47:17 volumio-7 systemd-udevd[366]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jul 19 20:47:17 volumio-7 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jul 19 20:47:17 volumio-7 systemd-journald[330]: Runtime journal (/run/log/journal/05c582d659be759fe7e0b6d56655aea5) is 7.5M, max 30.0M, 22.5M free. Jul 19 20:47:17 volumio-7 systemd[1]: Started udev Kernel Device Manager. Jul 19 20:47:17 volumio-7 systemd[1]: Starting Show Plymouth Boot Screen... Jul 19 20:47:17 volumio-7 systemd[1]: Started Flush Journal to Persistent Storage. Jul 19 20:47:17 volumio-7 systemd[1]: plymouth-start.service: Succeeded. Jul 19 20:47:17 volumio-7 systemd[1]: Started Show Plymouth Boot Screen. Jul 19 20:47:17 volumio-7 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 19 20:47:17 volumio-7 systemd[1]: Reached target Paths. Jul 19 20:47:17 volumio-7 systemd[1]: Reached target Local Encrypted Volumes. Jul 19 20:47:17 volumio-7 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jul 19 20:47:17 volumio-7 systemd-udevd[391]: Using default interface naming scheme 'v240'. Jul 19 20:47:17 volumio-7 systemd-udevd[391]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 19 20:47:17 volumio-7 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jul 19 20:47:18 volumio-7 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jul 19 20:47:18 volumio-7 kernel: [vc_sm_connected_init]: start Jul 19 20:47:18 volumio-7 kernel: [vc_sm_connected_init]: installed successfully Jul 19 20:47:18 volumio-7 kernel: mc: Linux media interface: v0.10 Jul 19 20:47:18 volumio-7 kernel: videodev: Linux video capture interface: v2.00 Jul 19 20:47:18 volumio-7 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jul 19 20:47:18 volumio-7 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jul 19 20:47:18 volumio-7 systemd[1]: Found device /dev/disk/by-uuid/F38E-0C3B. Jul 19 20:47:18 volumio-7 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 19 20:47:18 volumio-7 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 19 20:47:18 volumio-7 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jul 19 20:47:18 volumio-7 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jul 19 20:47:18 volumio-7 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jul 19 20:47:18 volumio-7 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jul 19 20:47:18 volumio-7 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jul 19 20:47:18 volumio-7 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jul 19 20:47:18 volumio-7 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jul 19 20:47:18 volumio-7 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jul 19 20:47:18 volumio-7 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jul 19 20:47:18 volumio-7 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 19 20:47:18 volumio-7 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 19 20:47:18 volumio-7 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 19 20:47:18 volumio-7 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 19 20:47:18 volumio-7 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 19 20:47:18 volumio-7 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jul 19 20:47:18 volumio-7 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jul 19 20:47:18 volumio-7 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jul 19 20:47:18 volumio-7 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jul 19 20:47:18 volumio-7 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 19 20:47:18 volumio-7 systemd-udevd[385]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jul 19 20:47:19 volumio-7 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jul 19 20:47:19 volumio-7 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jul 19 20:47:19 volumio-7 kernel: Bluetooth: Core ver 2.22 Jul 19 20:47:19 volumio-7 kernel: NET: Registered PF_BLUETOOTH protocol family Jul 19 20:47:19 volumio-7 kernel: Bluetooth: HCI device and connection manager initialized Jul 19 20:47:19 volumio-7 kernel: Bluetooth: HCI socket layer initialized Jul 19 20:47:19 volumio-7 kernel: Bluetooth: L2CAP socket layer initialized Jul 19 20:47:19 volumio-7 kernel: Bluetooth: SCO socket layer initialized Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 19 20:47:19 volumio-7 kernel: Bluetooth: HCI UART driver ver 2.3 Jul 19 20:47:19 volumio-7 kernel: Bluetooth: HCI UART protocol H4 registered Jul 19 20:47:19 volumio-7 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jul 19 20:47:19 volumio-7 kernel: Bluetooth: HCI UART protocol Broadcom registered Jul 19 20:47:19 volumio-7 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 19 20:47:19 volumio-7 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jul 19 20:47:19 volumio-7 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jul 19 20:47:19 volumio-7 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jul 19 20:47:19 volumio-7 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jul 19 20:47:19 volumio-7 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jul 19 20:47:19 volumio-7 kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jul 19 20:47:19 volumio-7 kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jul 19 20:47:19 volumio-7 kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jul 19 20:47:19 volumio-7 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 19 20:47:19 volumio-7 kernel: uart-pl011 fe201000.serial: no DMA platform data Jul 19 20:47:19 volumio-7 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jul 19 20:47:19 volumio-7 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jul 19 20:47:19 volumio-7 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jul 19 20:47:19 volumio-7 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jul 19 20:47:19 volumio-7 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Jul 19 20:47:19 volumio-7 kernel: Bluetooth: hci0: BCM: chip id 107 Jul 19 20:47:19 volumio-7 kernel: Bluetooth: hci0: BCM: features 0x2f Jul 19 20:47:19 volumio-7 kernel: Bluetooth: hci0: BCM4345C0 Jul 19 20:47:19 volumio-7 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jul 19 20:47:19 volumio-7 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jul 19 20:47:19 volumio-7 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jul 19 20:47:19 volumio-7 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jul 19 20:47:19 volumio-7 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jul 19 20:47:19 volumio-7 kernel: usbcore: registered new interface driver brcmfmac Jul 19 20:47:19 volumio-7 kernel: logitech-djreceiver 0003:046D:C534.0001: hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input0 Jul 19 20:47:20 volumio-7 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 Jul 19 20:47:20 volumio-7 systemd-udevd[391]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 19 20:47:20 volumio-7 kernel: logitech-djreceiver 0003:046D:C534.0002: hiddev96,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input1 Jul 19 20:47:20 volumio-7 kernel: logitech-djreceiver 0003:046D:C534.0002: device of type eQUAD nano Lite (0x0a) connected on slot 1 Jul 19 20:47:20 volumio-7 kernel: input: Logitech Wireless Keyboard PID:4023 Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C534.0002/0003:046D:4023.0003/input/input6 Jul 19 20:47:20 volumio-7 kernel: hid-generic 0003:046D:4023.0003: input,hidraw2: USB HID v1.11 Keyboard [Logitech Wireless Keyboard PID:4023] on usb-0000:01:00.0-1.4/input1:1 Jul 19 20:47:20 volumio-7 kernel: logitech-djreceiver 0003:046D:C534.0002: device of type eQUAD nano Lite (0x0a) connected on slot 2 Jul 19 20:47:20 volumio-7 kernel: input: Logitech Wireless Mouse PID:4022 Mouse as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C534.0002/0003:046D:4022.0004/input/input11 Jul 19 20:47:20 volumio-7 kernel: hid-generic 0003:046D:4022.0004: input,hidraw3: USB HID v1.11 Mouse [Logitech Wireless Mouse PID:4022] on usb-0000:01:00.0-1.4/input1:2 Jul 19 20:47:20 volumio-7 kernel: Bluetooth: hci0: BCM: features 0x2f Jul 19 20:47:20 volumio-7 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jul 19 20:47:20 volumio-7 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jul 19 20:47:20 volumio-7 systemd[1]: Created slice system-bthelper.slice. Jul 19 20:47:20 volumio-7 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 19 20:47:20 volumio-7 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jul 19 20:47:20 volumio-7 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jul 19 20:47:20 volumio-7 systemd[1]: Starting Show Plymouth Boot Screen... Jul 19 20:47:20 volumio-7 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 19 20:47:20 volumio-7 systemd[1]: Starting File System Check on /dev/disk/by-uuid/F38E-0C3B... Jul 19 20:47:20 volumio-7 systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 19 20:47:20 volumio-7 systemd[1]: Started File System Check Daemon to report status. Jul 19 20:47:20 volumio-7 systemd[1]: plymouth-start.service: Succeeded. Jul 19 20:47:20 volumio-7 systemd[1]: Started Show Plymouth Boot Screen. Jul 19 20:47:20 volumio-7 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 19 20:47:20 volumio-7 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jul 19 20:47:20 volumio-7 systemd[1]: Started Load/Save RF Kill Switch Status. Jul 19 20:47:20 volumio-7 systemd-fsck[542]: fsck.fat 4.1 (2017-01-24) Jul 19 20:47:20 volumio-7 systemd-fsck[542]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jul 19 20:47:20 volumio-7 systemd-fsck[542]: Automatically removing dirty bit. Jul 19 20:47:20 volumio-7 systemd-fsck[542]: Performing changes. Jul 19 20:47:20 volumio-7 systemd-fsck[542]: /dev/mmcblk0p1: 369 files, 134026/184582 clusters Jul 19 20:47:20 volumio-7 systemd[1]: Started File System Check on /dev/disk/by-uuid/F38E-0C3B. Jul 19 20:47:21 volumio-7 systemd[1]: Mounting /boot... Jul 19 20:47:21 volumio-7 systemd[1]: Mounted /boot. Jul 19 20:47:21 volumio-7 systemd[1]: Reached target Local File Systems. Jul 19 20:47:21 volumio-7 systemd[1]: Starting Raise network interfaces... Jul 19 20:47:21 volumio-7 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 19 20:47:21 volumio-7 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 19 20:47:21 volumio-7 systemd[1]: Starting Create Volatile Files and Directories... Jul 19 20:47:21 volumio-7 systemd[1]: Started ifup for wlan0. Jul 19 20:47:21 volumio-7 systemd[1]: Started ifup for eth0. Jul 19 20:47:21 volumio-7 systemd[1]: Starting Preprocess NFS configuration... Jul 19 20:47:21 volumio-7 systemd[1]: plymouth-read-write.service: Succeeded. Jul 19 20:47:21 volumio-7 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 19 20:47:21 volumio-7 systemd[1]: Received SIGRTMIN+20 from PID 218 (plymouthd). Jul 19 20:47:21 volumio-7 kernel: input: Logitech Wireless Keyboard PID:4023 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C534.0002/0003:046D:4023.0003/input/input15 Jul 19 20:47:21 volumio-7 kernel: logitech-hidpp-device 0003:046D:4023.0003: input,hidraw2: USB HID v1.11 Keyboard [Logitech Wireless Keyboard PID:4023] on usb-0000:01:00.0-1.4/input1:1 Jul 19 20:47:21 volumio-7 systemd[1]: nfs-config.service: Succeeded. Jul 19 20:47:21 volumio-7 systemd[1]: Started Preprocess NFS configuration. Jul 19 20:47:21 volumio-7 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jul 19 20:47:21 volumio-7 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jul 19 20:47:21 volumio-7 systemd[1]: Reached target NFS client services. Jul 19 20:47:21 volumio-7 kernel: input: Logitech Wireless Mouse PID:4022 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C534.0002/0003:046D:4022.0004/input/input16 Jul 19 20:47:21 volumio-7 kernel: logitech-hidpp-device 0003:046D:4022.0004: input,hidraw3: USB HID v1.11 Mouse [Logitech Wireless Mouse PID:4022] on usb-0000:01:00.0-1.4/input1:2 Jul 19 20:47:21 volumio-7 systemd[1]: Started Create Volatile Files and Directories. Jul 19 20:47:21 volumio-7 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jul 19 20:47:21 volumio-7 systemd[1]: Starting RPC bind portmap service... Jul 19 20:47:21 volumio-7 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jul 19 20:47:21 volumio-7 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jul 19 20:47:21 volumio-7 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 19 20:47:21 volumio-7 kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 19 20:47:21 volumio-7 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jul 19 20:47:21 volumio-7 systemd[1]: Reached target System Initialization. Jul 19 20:47:21 volumio-7 systemd[1]: Started Daily apt download activities. Jul 19 20:47:21 volumio-7 systemd[1]: Started Daily man-db regeneration. Jul 19 20:47:21 volumio-7 systemd[1]: Listening on lircd.socket. Jul 19 20:47:21 volumio-7 systemd[1]: Started Daily apt upgrade and clean activities. Jul 19 20:47:21 volumio-7 systemd[1]: Listening on triggerhappy.socket. Jul 19 20:47:21 volumio-7 systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 19 20:47:21 volumio-7 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 19 20:47:21 volumio-7 systemd[1]: Reached target Sockets. Jul 19 20:47:21 volumio-7 systemd[1]: Reached target Basic System. Jul 19 20:47:21 volumio-7 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jul 19 20:47:21 volumio-7 systemd[1]: Starting Wireless Services... Jul 19 20:47:21 volumio-7 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 19 20:47:21 volumio-7 systemd[1]: Reached target Login Prompts. Jul 19 20:47:21 volumio-7 systemd[1]: Started volumio-remote-updater.service. Jul 19 20:47:21 volumio-7 systemd[1]: Starting triggerhappy global hotkey daemon... Jul 19 20:47:21 volumio-7 systemd[1]: Starting Login Service... Jul 19 20:47:21 volumio-7 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jul 19 20:47:21 volumio-7 systemd[1]: Starting dhcpcd on all interfaces... Jul 19 20:47:21 volumio-7 systemd-udevd[371]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 20:47:21 volumio-7 systemd[1]: Started Volumio Iptables Module. Jul 19 20:47:21 volumio-7 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jul 19 20:47:21 volumio-7 dhcpcd[690]: Not running dhcpcd because /etc/network/interfaces Jul 19 20:47:21 volumio-7 dhcpcd[690]: defines some interfaces that will use a Jul 19 20:47:21 volumio-7 dhcpcd[690]: DHCP client or static address Jul 19 20:47:21 volumio-7 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 19 20:47:21 volumio-7 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 19 20:47:21 volumio-7 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jul 19 20:47:21 volumio-7 sh[558]: eth0: waiting for carrier Jul 19 20:47:21 volumio-7 dhcpcd[599]: eth0: waiting for carrier Jul 19 20:47:21 volumio-7 systemd[1]: Started Manage Sound Card State (restore and store). Jul 19 20:47:21 volumio-7 systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 19 20:47:21 volumio-7 systemd[1]: Reached target Timers. Jul 19 20:47:21 volumio-7 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jul 19 20:47:21 volumio-7 alsactl[699]: alsactl 1.1.8 daemon started Jul 19 20:47:21 volumio-7 systemd[1]: Starting Save/Restore Sound Card State... Jul 19 20:47:21 volumio-7 thd[688]: Unable to parse trigger line: Jul 19 20:47:21 volumio-7 thd[688]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 19 20:47:21 volumio-7 thd[688]: Unable to parse trigger line: Jul 19 20:47:21 volumio-7 thd[688]: Unable to parse trigger line: Jul 19 20:47:21 volumio-7 thd[688]: Found socket passed from systemd Jul 19 20:47:21 volumio-7 systemd[1]: Started D-Bus System Message Bus. Jul 19 20:47:22 volumio-7 systemd[1]: Starting WPA supplicant... Jul 19 20:47:22 volumio-7 systemd[1]: Started Volumio Log Rotation Service. Jul 19 20:47:22 volumio-7 systemd[1]: Started RPC bind portmap service. Jul 19 20:47:22 volumio-7 systemd[1]: Started triggerhappy global hotkey daemon. Jul 19 20:47:22 volumio-7 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jul 19 20:47:22 volumio-7 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jul 19 20:47:22 volumio-7 systemd[1]: Failed to start dhcpcd on all interfaces. Jul 19 20:47:22 volumio-7 systemd[1]: hciuart.service: Succeeded. Jul 19 20:47:22 volumio-7 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jul 19 20:47:22 volumio-7 systemd[1]: Starting Raspberry Pi bluetooth helper... Jul 19 20:47:22 volumio-7 systemd[1]: Reached target Remote File Systems (Pre). Jul 19 20:47:22 volumio-7 systemd[1]: Reached target Remote File Systems. Jul 19 20:47:22 volumio-7 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jul 19 20:47:22 volumio-7 systemd[1]: Reached target RPC Port Mapper. Jul 19 20:47:22 volumio-7 sh[556]: wlan0=wlan0 Jul 19 20:47:22 volumio-7 systemd[1]: Started Raise network interfaces. Jul 19 20:47:22 volumio-7 systemd[1]: Started Save/Restore Sound Card State. Jul 19 20:47:22 volumio-7 systemd[1]: Reached target Sound Card. Jul 19 20:47:22 volumio-7 avahi-daemon[696]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jul 19 20:47:22 volumio-7 avahi-daemon[696]: Successfully dropped root privileges. Jul 19 20:47:22 volumio-7 avahi-daemon[696]: avahi-daemon 0.7 starting up. Jul 19 20:47:22 volumio-7 systemd-logind[689]: New seat seat0. Jul 19 20:47:22 volumio-7 bthelper[708]: Raspberry Pi BDADDR already set Jul 19 20:47:22 volumio-7 systemd-udevd[377]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 20:47:22 volumio-7 systemd-udevd[406]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 20:47:22 volumio-7 systemd-logind[689]: Watching system buttons on /dev/input/event0 (Logitech Wireless Keyboard PID:4023) Jul 19 20:47:22 volumio-7 dbus-daemon[701]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 19 20:47:22 volumio-7 avahi-daemon[696]: Successfully called chroot(). Jul 19 20:47:22 volumio-7 avahi-daemon[696]: Successfully dropped remaining capabilities. Jul 19 20:47:22 volumio-7 wpa_supplicant[704]: Successfully initialized wpa_supplicant Jul 19 20:47:22 volumio-7 systemd[1]: Started Login Service. Jul 19 20:47:22 volumio-7 systemd[1]: Started Raspberry Pi bluetooth helper. Jul 19 20:47:22 volumio-7 avahi-daemon[696]: Loading service file /services/volumio.service. Jul 19 20:47:22 volumio-7 avahi-daemon[696]: Network interface enumeration completed. Jul 19 20:47:22 volumio-7 avahi-daemon[696]: Server startup complete. Host name is volumio-7.local. Local service cookie is 27277792. Jul 19 20:47:22 volumio-7 avahi-daemon[696]: Service "Volumio-7" (/services/volumio.service) successfully established. Jul 19 20:47:23 volumio-7 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 19 20:47:23 volumio-7 systemd[1]: Started WPA supplicant. Jul 19 20:47:23 volumio-7 systemd[1]: Reached target Network. Jul 19 20:47:23 volumio-7 systemd[1]: Reached target Network is Online. Jul 19 20:47:23 volumio-7 systemd[1]: Starting LSB: Brings up/down network automatically... Jul 19 20:47:23 volumio-7 systemd[1]: Starting Permit User Sessions... Jul 19 20:47:23 volumio-7 systemd[1]: Starting /etc/rc.local Compatibility... Jul 19 20:47:23 volumio-7 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jul 19 20:47:23 volumio-7 volumio-remote-updater[687]: Error: No active session Jul 19 20:47:23 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:23] [info] asio async_connect error: system:111 (Connection refused) Jul 19 20:47:23 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 19 20:47:23 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:23] [error] handle_connect error: Underlying Transport Error Jul 19 20:47:23 volumio-7 volumio[695]: Could not open config: /tmp/upmpdcli.conf Jul 19 20:47:23 volumio-7 systemd[1]: Starting Samba NMB Daemon... Jul 19 20:47:23 volumio-7 systemd[1]: Starting OpenBSD Secure Shell server... Jul 19 20:47:23 volumio-7 systemd[1]: Starting Network Time Service... Jul 19 20:47:23 volumio-7 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:47:23 volumio-7 systemd[1]: Starting Bluetooth service... Jul 19 20:47:23 volumio-7 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 19 20:47:23 volumio-7 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 19 20:47:23 volumio-7 systemd[1]: Started Permit User Sessions. Jul 19 20:47:23 volumio-7 systemd[1]: Started /etc/rc.local Compatibility. Jul 19 20:47:23 volumio-7 systemd[1]: iptables.service: Succeeded. Jul 19 20:47:23 volumio-7 systemd[1]: Starting Hold until boot process finishes up... Jul 19 20:47:23 volumio-7 systemd[1]: Starting Terminate Plymouth Boot Screen... Jul 19 20:47:23 volumio-7 loadcpufreq[718]: Loading cpufreq kernel modules...done (none). Jul 19 20:47:23 volumio-7 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jul 19 20:47:23 volumio-7 systemd[1]: Received SIGRTMIN+21 from PID 218 (plymouthd). Jul 19 20:47:23 volumio-7 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jul 19 20:47:23 volumio-7 systemd[1]: plymouth-quit-wait.service: Succeeded. Jul 19 20:47:23 volumio-7 systemd[1]: Started Hold until boot process finishes up. Jul 19 20:47:23 volumio-7 systemd[1]: plymouth-quit.service: Succeeded. Jul 19 20:47:23 volumio-7 systemd[1]: Started Terminate Plymouth Boot Screen. Jul 19 20:47:23 volumio-7 systemd[1]: Received SIGRTMIN+21 from PID 218 (n/a). Jul 19 20:47:23 volumio-7 ifplugd(eth0)[807]: ifplugd 0.28 initializing. Jul 19 20:47:23 volumio-7 ifplugd(eth0)[807]: Using interface eth0/D8:3A:DD:88:2D:6D with driver (version: 6.1.77-v7l+) Jul 19 20:47:23 volumio-7 ifplugd(eth0)[807]: Using detection mode: SIOCETHTOOL Jul 19 20:47:23 volumio-7 ifplugd(eth0)[807]: Initialization complete, link beat not detected. Jul 19 20:47:23 volumio-7 bluetoothd[784]: Bluetooth daemon 5.50 Jul 19 20:47:23 volumio-7 sh[558]: eth0: carrier acquired Jul 19 20:47:23 volumio-7 dhcpcd[599]: eth0: carrier acquired Jul 19 20:47:23 volumio-7 ntpd[778]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 19 20:47:23 volumio-7 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Jul 19 20:47:23 volumio-7 ntpd[778]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jul 19 20:47:23 volumio-7 sh[558]: DUID 00:01:00:01:2d:e8:6d:6e:dc:a6:32:de:93:e3 Jul 19 20:47:23 volumio-7 sh[558]: eth0: IAID dd:88:2d:6d Jul 19 20:47:23 volumio-7 dhcpcd[599]: DUID 00:01:00:01:2d:e8:6d:6e:dc:a6:32:de:93:e3 Jul 19 20:47:23 volumio-7 systemd[1]: Started Network Time Service. Jul 19 20:47:23 volumio-7 sh[558]: eth0: adding address fe80::9a9a:299:43ee:e723 Jul 19 20:47:23 volumio-7 sh[558]: ipv6_addaddr1: Permission denied Jul 19 20:47:23 volumio-7 dhcpcd[599]: eth0: IAID dd:88:2d:6d Jul 19 20:47:23 volumio-7 dhcpcd[599]: eth0: adding address fe80::9a9a:299:43ee:e723 Jul 19 20:47:23 volumio-7 dhcpcd[599]: ipv6_addaddr1: Permission denied Jul 19 20:47:23 volumio-7 bluetoothd[784]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 19 20:47:23 volumio-7 ifplugd[749]: Network Interface Plugging Daemon...start eth0...done. Jul 19 20:47:23 volumio-7 systemd[1]: Started LSB: Brings up/down network automatically. Jul 19 20:47:23 volumio-7 systemd[1]: Started Bluetooth service. Jul 19 20:47:23 volumio-7 systemd[1]: Reached target Bluetooth. Jul 19 20:47:23 volumio-7 ntpd[810]: proto: precision = 2.037 usec (-19) Jul 19 20:47:24 volumio-7 bluetoothd[784]: Starting SDP server Jul 19 20:47:24 volumio-7 bluetoothd[784]: Excluding (cli) sap Jul 19 20:47:24 volumio-7 ntpd[810]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 19 20:47:24 volumio-7 ntpd[810]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 19 20:47:24 volumio-7 ntpd[810]: Listen and drop on 0 v6wildcard [::]:123 Jul 19 20:47:24 volumio-7 ntpd[810]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 19 20:47:24 volumio-7 ntpd[810]: Listen normally on 2 lo 127.0.0.1:123 Jul 19 20:47:24 volumio-7 ntpd[810]: Listening on routing socket on fd #19 for interface updates Jul 19 20:47:24 volumio-7 ntpd[810]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 19 20:47:24 volumio-7 ntpd[810]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 19 20:47:24 volumio-7 dbus-daemon[701]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=784 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jul 19 20:47:24 volumio-7 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jul 19 20:47:24 volumio-7 kernel: Bluetooth: BNEP filters: protocol multicast Jul 19 20:47:24 volumio-7 kernel: Bluetooth: BNEP socket layer initialized Jul 19 20:47:24 volumio-7 kernel: Bluetooth: MGMT ver 1.22 Jul 19 20:47:24 volumio-7 bluetoothd[784]: Bluetooth management interface 1.22 initialized Jul 19 20:47:24 volumio-7 cpufrequtils[796]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jul 19 20:47:24 volumio-7 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jul 19 20:47:24 volumio-7 sshd[826]: Server listening on 0.0.0.0 port 22. Jul 19 20:47:24 volumio-7 sshd[826]: Server listening on :: port 22. Jul 19 20:47:24 volumio-7 bluetoothd[784]: Failed to set privacy: Rejected (0x0b) Jul 19 20:47:24 volumio-7 systemd[1]: Started OpenBSD Secure Shell server. Jul 19 20:47:24 volumio-7 systemd[1]: Starting Hostname Service... Jul 19 20:47:24 volumio-7 dbus-daemon[701]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 19 20:47:24 volumio-7 systemd[1]: Started Hostname Service. Jul 19 20:47:24 volumio-7 sh[558]: eth0: rebinding lease of 192.168.0.109 Jul 19 20:47:24 volumio-7 dhcpcd[599]: eth0: rebinding lease of 192.168.0.109 Jul 19 20:47:24 volumio-7 nmbd[790]: [2024/07/19 20:47:24.581181, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 19 20:47:24 volumio-7 nmbd[790]: started asyncdns process 832 Jul 19 20:47:24 volumio-7 nmbd[790]: [2024/07/19 20:47:24.584772, 0] ../lib/util/become_daemon.c:149(daemon_status) Jul 19 20:47:24 volumio-7 nmbd[790]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 19 20:47:24 volumio-7 nmbd[790]: [2024/07/19 20:47:24.585254, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jul 19 20:47:24 volumio-7 nmbd[790]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 19 20:47:24 volumio-7 sh[558]: eth0: soliciting an IPv6 router Jul 19 20:47:24 volumio-7 dhcpcd[599]: eth0: soliciting an IPv6 router Jul 19 20:47:24 volumio-7 sh[558]: eth0: probing address 192.168.0.109/24 Jul 19 20:47:24 volumio-7 dhcpcd[599]: eth0: probing address 192.168.0.109/24 Jul 19 20:47:24 volumio-7 haveged[621]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jul 19 20:47:24 volumio-7 haveged[621]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jul 19 20:47:24 volumio-7 haveged[621]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00098 Jul 19 20:47:24 volumio-7 haveged[621]: haveged: fills: 0, generated: 0 Jul 19 20:47:24 volumio-7 wireless.js[673]: Cleaning previous... Jul 19 20:47:24 volumio-7 sudo[840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 19 20:47:24 volumio-7 sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:24 volumio-7 sudo[840]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:24 volumio-7 sudo[842]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 19 20:47:24 volumio-7 sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:24 volumio-7 ntpd[810]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 19 20:47:25 volumio-7 ifplugd(eth0)[807]: Link beat detected. Jul 19 20:47:25 volumio-7 sudo[842]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:25 volumio-7 wireless.js[673]: Stopped aP Jul 19 20:47:25 volumio-7 sudo[849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 19 20:47:25 volumio-7 sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:25 volumio-7 ifplugd(eth0)[807]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 19 20:47:25 volumio-7 sudo[849]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:25 volumio-7 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 19 20:47:25 volumio-7 ifplugd(eth0)[807]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jul 19 20:47:25 volumio-7 sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 19 20:47:25 volumio-7 sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:25 volumio-7 sudo[854]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:25 volumio-7 sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 19 20:47:25 volumio-7 sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:25 volumio-7 sudo[863]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:25 volumio-7 sudo[865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 19 20:47:25 volumio-7 sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:25 volumio-7 systemd[1]: systemd-rfkill.service: Succeeded. Jul 19 20:47:25 volumio-7 ntpd[810]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 19 20:47:26 volumio-7 sudo[865]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:26 volumio-7 wireless.js[673]: SETTING APPROPRIATE REG DOMAIN: CN Jul 19 20:47:26 volumio-7 sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 19 20:47:26 volumio-7 sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:26 volumio-7 sudo[871]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:26 volumio-7 sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Jul 19 20:47:26 volumio-7 sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:26 volumio-7 sudo[873]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:26 volumio-7 wireless.js[673]: SUCCESSFULLY SET NEW REGDOMAIN: CN Jul 19 20:47:26 volumio-7 wireless.js[673]: Start wireless flow Jul 19 20:47:26 volumio-7 wireless.js[673]: Stopped hotspot (if there).. Jul 19 20:47:26 volumio-7 sudo[878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 19 20:47:26 volumio-7 sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:26 volumio-7 sudo[878]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:26 volumio-7 sudo[880]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 19 20:47:26 volumio-7 sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:26 volumio-7 ntpd[810]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 19 20:47:27 volumio-7 sudo[880]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:27 volumio-7 wireless.js[673]: DHCP IP Jul 19 20:47:27 volumio-7 wireless.js[673]: Start ap Jul 19 20:47:27 volumio-7 wpa_supplicant[882]: Successfully initialized wpa_supplicant Jul 19 20:47:27 volumio-7 sudo[883]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 19 20:47:27 volumio-7 sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:27 volumio-7 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 19 20:47:27 volumio-7 dhcpcd[884]: dev: loaded udev Jul 19 20:47:27 volumio-7 dhcpcd-run-hooks[893]: wlan0: starting wpa_supplicant Jul 19 20:47:27 volumio-7 dhcpcd-run-hooks[899]: wlan0: failed to start wpa_supplicant Jul 19 20:47:27 volumio-7 bthelper[708]: Changing power off succeeded Jul 19 20:47:27 volumio-7 bthelper[708]: [CHG] Controller D8:3A:DD:88:2D:6F Powered: no Jul 19 20:47:27 volumio-7 bthelper[708]: [CHG] Controller D8:3A:DD:88:2D:6F Discovering: no Jul 19 20:47:27 volumio-7 bthelper[708]: [CHG] Controller D8:3A:DD:88:2D:6F Class: 0x00000000 Jul 19 20:47:27 volumio-7 dhcpcd-run-hooks[900]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jul 19 20:47:27 volumio-7 dhcpcd[884]: wlan0: connected to Access Point `' Jul 19 20:47:27 volumio-7 dhcpcd[884]: DUID 00:01:00:01:2d:e8:6d:6e:dc:a6:32:de:93:e3 Jul 19 20:47:27 volumio-7 dhcpcd[884]: eth0: IAID dd:88:2d:6d Jul 19 20:47:27 volumio-7 dhcpcd[884]: eth0: adding address fe80::9a9a:299:43ee:e723 Jul 19 20:47:27 volumio-7 dhcpcd[884]: ipv6_addaddr1: Permission denied Jul 19 20:47:27 volumio-7 dhcpcd[884]: wlan0: waiting for carrier Jul 19 20:47:27 volumio-7 bthelper[708]: [CHG] Controller D8:3A:DD:88:2D:6F Class: 0x0000041c Jul 19 20:47:27 volumio-7 bthelper[708]: Changing power on succeeded Jul 19 20:47:27 volumio-7 bthelper[708]: [CHG] Controller D8:3A:DD:88:2D:6F Powered: yes Jul 19 20:47:27 volumio-7 ntpd[810]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 19 20:47:28 volumio-7 wireless.js[673]: trying... Jul 19 20:47:28 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:28] [info] asio async_connect error: system:111 (Connection refused) Jul 19 20:47:28 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:28] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 19 20:47:28 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:28] [error] handle_connect error: Underlying Transport Error Jul 19 20:47:28 volumio-7 sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:28 volumio-7 sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:28 volumio-7 sudo[917]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:28 volumio-7 dhcpcd[884]: eth0: rebinding lease of 192.168.0.109 Jul 19 20:47:28 volumio-7 dhcpcd[884]: eth0: soliciting an IPv6 router Jul 19 20:47:29 volumio-7 wireless.js[673]: trying... Jul 19 20:47:29 volumio-7 sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:29 volumio-7 sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:29 volumio-7 sudo[920]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:30 volumio-7 sh[558]: eth0: leased 192.168.0.109 for 7200 seconds Jul 19 20:47:30 volumio-7 sh[558]: eth0: adding route to 192.168.0.0/24 Jul 19 20:47:30 volumio-7 sh[558]: eth0: adding default route via 192.168.0.1 Jul 19 20:47:30 volumio-7 dhcpcd[599]: eth0: leased 192.168.0.109 for 7200 seconds Jul 19 20:47:30 volumio-7 avahi-daemon[696]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.109. Jul 19 20:47:30 volumio-7 avahi-daemon[696]: New relevant interface eth0.IPv4 for mDNS. Jul 19 20:47:30 volumio-7 dhcpcd[599]: eth0: adding route to 192.168.0.0/24 Jul 19 20:47:30 volumio-7 avahi-daemon[696]: Registering new address record for 192.168.0.109 on eth0.IPv4. Jul 19 20:47:30 volumio-7 dhcpcd[599]: eth0: adding default route via 192.168.0.1 Jul 19 20:47:30 volumio-7 sh[558]: forked to background, child pid 942 Jul 19 20:47:30 volumio-7 dhcpcd[599]: forked to background, child pid 942 Jul 19 20:47:30 volumio-7 wireless.js[673]: trying... Jul 19 20:47:30 volumio-7 sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:30 volumio-7 sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:30 volumio-7 sudo[962]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:30 volumio-7 ifplugd(eth0)[807]: client: ifup: interface eth0 already configured Jul 19 20:47:30 volumio-7 sh[558]: eth0=eth0 Jul 19 20:47:30 volumio-7 ifplugd(eth0)[807]: Program executed successfully. Jul 19 20:47:31 volumio-7 wireless.js[673]: trying... Jul 19 20:47:31 volumio-7 sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:31 volumio-7 sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:31 volumio-7 sudo[972]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:31 volumio-7 ntpd[810]: Listen normally on 3 eth0 192.168.0.109:123 Jul 19 20:47:31 volumio-7 ntpd[810]: new interface(s) found: waking up resolver Jul 19 20:47:32 volumio-7 wireless.js[673]: trying... Jul 19 20:47:32 volumio-7 sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:32 volumio-7 sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:32 volumio-7 sudo[975]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:33 volumio-7 wireless.js[673]: trying... Jul 19 20:47:33 volumio-7 sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:33 volumio-7 sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:33 volumio-7 sudo[978]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:33 volumio-7 dhcpcd[884]: eth0: probing for an IPv4LL address Jul 19 20:47:33 volumio-7 dhcpcd[884]: eth0: DHCP lease expired Jul 19 20:47:33 volumio-7 dhcpcd[884]: eth0: soliciting a DHCP lease Jul 19 20:47:33 volumio-7 dhcpcd[884]: eth0: offered 192.168.0.109 from 192.168.0.1 Jul 19 20:47:33 volumio-7 dhcpcd[884]: eth0: leased 192.168.0.109 for 7200 seconds Jul 19 20:47:33 volumio-7 dhcpcd[884]: eth0: adding route to 192.168.0.0/24 Jul 19 20:47:33 volumio-7 dhcpcd[884]: eth0: adding default route via 192.168.0.1 Jul 19 20:47:33 volumio-7 dhcpcd[884]: forked to background, child pid 1014 Jul 19 20:47:33 volumio-7 sudo[883]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:34 volumio-7 wireless.js[673]: trying... Jul 19 20:47:34 volumio-7 sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:34 volumio-7 sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:34 volumio-7 sudo[1016]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:34 volumio-7 nmbd[790]: [2024/07/19 20:47:34.591900, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 19 20:47:34 volumio-7 nmbd[790]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 19 20:47:34 volumio-7 systemd[1]: Started Samba NMB Daemon. Jul 19 20:47:34 volumio-7 systemd[1]: Starting Samba Winbind Daemon... Jul 19 20:47:34 volumio-7 winbindd[1018]: [2024/07/19 20:47:34.839959, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 19 20:47:34 volumio-7 winbindd[1018]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 19 20:47:34 volumio-7 winbindd[1018]: [2024/07/19 20:47:34.856918, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 19 20:47:34 volumio-7 winbindd[1018]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 19 20:47:34 volumio-7 systemd[1]: Started Samba Winbind Daemon. Jul 19 20:47:34 volumio-7 systemd[1]: Starting Samba SMB Daemon... Jul 19 20:47:35 volumio-7 smbd[1023]: [2024/07/19 20:47:35.240706, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 19 20:47:35 volumio-7 smbd[1023]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 19 20:47:35 volumio-7 systemd[1]: Started Samba SMB Daemon. Jul 19 20:47:35 volumio-7 wireless.js[673]: trying... Jul 19 20:47:35 volumio-7 sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:35 volumio-7 sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:35 volumio-7 sudo[1032]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:35 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:35] [info] asio async_connect error: system:111 (Connection refused) Jul 19 20:47:35 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:35] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 19 20:47:35 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:35] [error] handle_connect error: Underlying Transport Error Jul 19 20:47:36 volumio-7 wireless.js[673]: trying... Jul 19 20:47:36 volumio-7 sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:36 volumio-7 sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:36 volumio-7 sudo[1035]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:37 volumio-7 wireless.js[673]: trying... Jul 19 20:47:37 volumio-7 sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:37 volumio-7 sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:37 volumio-7 sudo[1038]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:38 volumio-7 wireless.js[673]: trying... Jul 19 20:47:38 volumio-7 sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:38 volumio-7 sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:38 volumio-7 sudo[1041]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:38 volumio-7 dhcpcd[1014]: eth0: using IPv4LL address 169.254.183.198 Jul 19 20:47:38 volumio-7 avahi-daemon[696]: Registering new address record for 169.254.183.198 on eth0.IPv4. Jul 19 20:47:38 volumio-7 dhcpcd[1014]: eth0: adding route to 169.254.0.0/16 Jul 19 20:47:39 volumio-7 wireless.js[673]: trying... Jul 19 20:47:39 volumio-7 sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:39 volumio-7 sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:39 volumio-7 sudo[1062]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:39 volumio-7 ntpd[810]: Listen normally on 4 eth0 169.254.183.198:123 Jul 19 20:47:39 volumio-7 ntpd[810]: new interface(s) found: waking up resolver Jul 19 20:47:40 volumio-7 wireless.js[673]: trying... Jul 19 20:47:40 volumio-7 sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:40 volumio-7 sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:40 volumio-7 sudo[1065]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:40 volumio-7 wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 19 20:47:41 volumio-7 wireless.js[673]: trying... Jul 19 20:47:41 volumio-7 sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:41 volumio-7 sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:41 volumio-7 sudo[1068]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:42 volumio-7 wireless.js[673]: trying... Jul 19 20:47:42 volumio-7 sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:42 volumio-7 sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:42 volumio-7 sudo[1071]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:43 volumio-7 wireless.js[673]: trying... Jul 19 20:47:43 volumio-7 sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:43 volumio-7 sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:43 volumio-7 sudo[1074]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:44 volumio-7 wireless.js[673]: trying... Jul 19 20:47:44 volumio-7 sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:44 volumio-7 sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:44 volumio-7 sudo[1077]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:45 volumio-7 wireless.js[673]: trying... Jul 19 20:47:45 volumio-7 sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:45 volumio-7 sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:45 volumio-7 sudo[1080]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:45 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:45] [info] asio async_connect error: system:111 (Connection refused) Jul 19 20:47:45 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 19 20:47:45 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:45] [error] handle_connect error: Underlying Transport Error Jul 19 20:47:46 volumio-7 wireless.js[673]: trying... Jul 19 20:47:46 volumio-7 sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:46 volumio-7 sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:46 volumio-7 sudo[1083]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:46 volumio-7 wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 19 20:47:47 volumio-7 wireless.js[673]: trying... Jul 19 20:47:47 volumio-7 sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 19 20:47:47 volumio-7 sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:47 volumio-7 sudo[1086]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:48 volumio-7 wireless.js[673]: Overtime, starting plan B Jul 19 20:47:48 volumio-7 systemd[1]: Started Wireless Services. Jul 19 20:47:48 volumio-7 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 19 20:47:48 volumio-7 systemd[1]: Started Volumio Backend Module. Jul 19 20:47:48 volumio-7 systemd[1]: Started Volumio Cpu Tweaker. Jul 19 20:47:48 volumio-7 systemd[1]: Reached target Multi-User System. Jul 19 20:47:48 volumio-7 systemd[1]: Reached target Graphical Interface. Jul 19 20:47:48 volumio-7 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 19 20:47:48 volumio-7 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 19 20:47:48 volumio-7 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 19 20:47:48 volumio-7 systemd[1]: Startup finished in 17.218s (kernel) + 33.241s (userspace) = 50.459s. Jul 19 20:47:48 volumio-7 volumio-cpu-tweak[1092]: Setting RT Priority for mpd Jul 19 20:47:48 volumio-7 volumio-cpu-tweak[1092]: pid 35's current scheduling policy: SCHED_OTHER Jul 19 20:47:48 volumio-7 volumio-cpu-tweak[1092]: pid 35's current scheduling priority: 0 Jul 19 20:47:48 volumio-7 volumio-cpu-tweak[1092]: Setting MPD Affinity Jul 19 20:47:48 volumio-7 volumio-cpu-tweak[1092]: pid 3's current affinity mask: f Jul 19 20:47:48 volumio-7 volumio-cpu-tweak[1092]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 19 20:47:48 volumio-7 volumio-cpu-tweak[1092]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 19 20:47:48 volumio-7 systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 19 20:47:50 volumio-7 volumio[1091]: info: ------------------------------------------- Jul 19 20:47:50 volumio-7 volumio[1091]: info: ----- Volumio3 ---- Jul 19 20:47:50 volumio-7 volumio[1091]: info: ------------------------------------------- Jul 19 20:47:50 volumio-7 volumio[1091]: info: ----- System startup ---- Jul 19 20:47:50 volumio-7 volumio[1091]: info: ------------------------------------------- Jul 19 20:47:50 volumio-7 volumio[1091]: info: MYVOLUMIO Environment detected Jul 19 20:47:50 volumio-7 volumio[1091]: info: Plugin folders cleanup Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning into folder /volumio/app/plugins/ Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category audio_interface Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category miscellanea Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category music_service Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category plugins.json Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category system_controller Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category user_interface Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning into folder /data/plugins/ Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category music_service Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category system_controller Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category system_hardware Jul 19 20:47:50 volumio-7 volumio[1091]: info: Scanning category user_interface Jul 19 20:47:50 volumio-7 volumio[1091]: info: Plugin folders cleanup completed Jul 19 20:47:50 volumio-7 volumio[1091]: info: ------------------------------------------- Jul 19 20:47:50 volumio-7 volumio[1091]: info: ----- Core plugins startup ---- Jul 19 20:47:50 volumio-7 volumio[1091]: info: ------------------------------------------- Jul 19 20:47:50 volumio-7 volumio[1091]: info: Loading plugins from folder /volumio/app/plugins/ Jul 19 20:47:50 volumio-7 volumio[1091]: info: Adding plugin upnp to MyMusic Plugins Jul 19 20:47:50 volumio-7 volumio[1091]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 19 20:47:50 volumio-7 volumio[1091]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 19 20:47:50 volumio-7 volumio[1091]: info: Loading plugins from folder /data/plugins/ Jul 19 20:47:50 volumio-7 volumio[1091]: info: Loading plugin "system"... Jul 19 20:47:50 volumio-7 systemd[1]: systemd-fsckd.service: Succeeded. Jul 19 20:47:50 volumio-7 volumio[1091]: info: Loading plugin "appearance"... Jul 19 20:47:51 volumio-7 volumio[1091]: info: Loading plugin "network"... Jul 19 20:47:51 volumio-7 volumio[1091]: info: Refreshing Cached IP Addresses Jul 19 20:47:51 volumio-7 sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 20:47:51 volumio-7 sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:51 volumio-7 volumio[1091]: info: Loading plugin "services"... Jul 19 20:47:51 volumio-7 sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 20:47:51 volumio-7 sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:51 volumio-7 sudo[1123]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:51 volumio-7 volumio[1091]: info: Loading plugin "alsa_controller"... Jul 19 20:47:51 volumio-7 sudo[1125]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:51 volumio-7 sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 19 20:47:51 volumio-7 sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:51 volumio-7 sudo[1128]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:51 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:47:51 volumio-7 volumio[1091]: info: Loading plugin "wizard"... Jul 19 20:47:51 volumio-7 volumio[1091]: info: Loading plugin "networkfs"... Jul 19 20:47:52 volumio-7 volumio[1091]: info: Starting Udev Watcher for removable devices Jul 19 20:47:52 volumio-7 sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Jul 19 20:47:52 volumio-7 sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:52 volumio-7 volumio[1091]: info: Ignoring mount for partition: boot Jul 19 20:47:52 volumio-7 volumio[1091]: info: Ignoring mount for partition: volumio Jul 19 20:47:52 volumio-7 volumio[1091]: info: Ignoring mount for partition: volumio_data Jul 19 20:47:52 volumio-7 volumio[1091]: info: Mounting Device 1BF9-4112 Jul 19 20:47:52 volumio-7 sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/1BF9-4112 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 19 20:47:52 volumio-7 sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:52 volumio-7 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 19 20:47:52 volumio-7 sudo[1145]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:52 volumio-7 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 19 20:47:52 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "volumio_command_line_client"... Jul 19 20:47:52 volumio-7 kernel: Key type cifs.spnego registered Jul 19 20:47:52 volumio-7 kernel: Key type cifs.idmap registered Jul 19 20:47:52 volumio-7 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. Jul 19 20:47:52 volumio-7 kernel: CIFS: Attempting to mount \\127.0.0.1\Internal Storage Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "upnp"... Jul 19 20:47:52 volumio-7 volumio[1091]: info: [1721393272213] Starting Upmpd Daemon Jul 19 20:47:52 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "my_music"... Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "mpd"... Jul 19 20:47:52 volumio-7 sudo[1134]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:52 volumio-7 wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "upnp_browser"... Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "alarm-clock"... Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "airplay_emulation"... Jul 19 20:47:52 volumio-7 volumio[1091]: info: Starting Shairport Sync Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "last_100"... Jul 19 20:47:52 volumio-7 volumio[1091]: info: Loading plugin "webradio"... Jul 19 20:47:53 volumio-7 volumio[1091]: info: Loading plugin "i2s_dacs"... Jul 19 20:47:53 volumio-7 volumio[1091]: info: Loading plugin "volumiodiscovery"... Jul 19 20:47:53 volumio-7 volumio[1091]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 20:47:53 volumio-7 volumio[1091]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 20:47:53 volumio-7 node[1091]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 20:47:53 volumio-7 volumio[1091]: *** WARNING *** For more information see Jul 19 20:47:53 volumio-7 volumio[1091]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 20:47:53 volumio-7 volumio[1091]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 20:47:53 volumio-7 volumio[1091]: *** WARNING *** For more information see Jul 19 20:47:53 volumio-7 node[1091]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 20:47:53 volumio-7 node[1091]: *** WARNING *** For more information see Jul 19 20:47:53 volumio-7 node[1091]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 20:47:53 volumio-7 node[1091]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 20:47:53 volumio-7 node[1091]: *** WARNING *** For more information see Jul 19 20:47:53 volumio-7 volumio[1091]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 19 20:47:53 volumio-7 volumio[1091]: info: Discovery: Started advertising with name: Volumio-7 Jul 19 20:47:53 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 20:47:53 volumio-7 volumio[1091]: info: Loading plugin "autostart"... Jul 19 20:47:53 volumio-7 volumio[1091]: info: Applying required configuration parameters for plugin autostart Jul 19 20:47:53 volumio-7 volumio[1091]: info: AutoStart - onVolumioStart - read config.json Jul 19 20:47:53 volumio-7 volumio[1091]: info: Loading plugin "peppy_screensaver"... Jul 19 20:47:53 volumio-7 volumio[1091]: info: Applying required configuration parameters for plugin peppy_screensaver Jul 19 20:47:53 volumio-7 volumio[1091]: info: Loading plugin "outputs"... Jul 19 20:47:53 volumio-7 volumio[1091]: info: Loading plugin "albumart"... Jul 19 20:47:53 volumio-7 volumio[1091]: info: Plugin example_plugin is not enabled Jul 19 20:47:53 volumio-7 volumio[1091]: info: Loading plugin "inputs"... Jul 19 20:47:53 volumio-7 volumio[1091]: info: Loading plugin "updater_comm"... Jul 19 20:47:54 volumio-7 volumio[1091]: info: Plugin mpdemulation is not enabled Jul 19 20:47:54 volumio-7 volumio[1091]: info: Loading plugin "rest_api"... Jul 19 20:47:54 volumio-7 volumio[1091]: info: Loading plugin "websocket"... Jul 19 20:47:54 volumio-7 volumio[1091]: info: Starting Socket.io Server version 2.3.0 Jul 19 20:47:54 volumio-7 volumio[1091]: info: Loading plugin "RoonBridge"... Jul 19 20:47:54 volumio-7 volumio[1091]: Forking 3 albumart workers Jul 19 20:47:54 volumio-7 volumio[1091]: info: Applying required configuration parameters for plugin RoonBridge Jul 19 20:47:54 volumio-7 volumio[1091]: info: Loading plugin "ir_controller"... Jul 19 20:47:54 volumio-7 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 19 20:47:54 volumio-7 volumio[1091]: info: Applying required configuration parameters for plugin ir_controller Jul 19 20:47:54 volumio-7 volumio[1091]: info: Loading plugin "Systeminfo"... Jul 19 20:47:54 volumio-7 volumio[1091]: Starting albumart workers Jul 19 20:47:54 volumio-7 volumio[1091]: Starting albumart workers Jul 19 20:47:54 volumio-7 volumio[1091]: Starting albumart workers Jul 19 20:47:54 volumio-7 volumio[1091]: info: Loading plugin "touch_display"... Jul 19 20:47:55 volumio-7 volumio[1091]: info: Applying required configuration parameters for plugin touch_display Jul 19 20:47:55 volumio-7 volumio[1091]: info: Loading i18n strings for locale zh Jul 19 20:47:55 volumio-7 volumio[1091]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 19 20:47:55 volumio-7 volumio[1091]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Jul 19 20:47:55 volumio-7 volumio[1091]: Updating browse sources language Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::initPlayerControls Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:47:55 volumio-7 volumio[1091]: Express server listening on port 3000 Jul 19 20:47:55 volumio-7 volumio[1091]: [Metrics] WebUI: 6s 41.72ms Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreStateMachine::resetVolumioState Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreStateMachine::getcurrentVolume Jul 19 20:47:55 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 20:47:55 volumio-7 volumio[1091]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 19 20:47:55 volumio-7 volumio[1091]: wlan0 Interface doesn't support scanning : Device or resource busy Jul 19 20:47:55 volumio-7 volumio[1091]: info: Cannot use regular scanning, forcing with ap-force Jul 19 20:47:55 volumio-7 sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 19 20:47:55 volumio-7 sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:55 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:55] [connect] Successful connection Jul 19 20:47:56 volumio-7 sudo[1232]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreStateMachine::pushState Jul 19 20:47:56 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioPushState Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreStateMachine::updateTrackBlock Jul 19 20:47:56 volumio-7 volumio[1091]: info: CorePlayQueue::getTrackBlock Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 20:47:56 volumio-7 volumio[1091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 19 20:47:56 volumio-7 volumio[1091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 19 20:47:56 volumio-7 volumio-remote-updater[687]: [2024-07-19 20:47:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721393275 101 Jul 19 20:47:56 volumio-7 volumio[1091]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:56 volumio-7 volumio[1091]: info: Reloading queue from file Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreStateMachine::pushState Jul 19 20:47:56 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioPushState Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreStateMachine::setRepeat true single undefined Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreStateMachine::pushState Jul 19 20:47:56 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioPushState Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreStateMachine::setRandom null Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreStateMachine::pushState Jul 19 20:47:56 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioPushState Jul 19 20:47:56 volumio-7 volumio[1091]: info: Setting Device type: Raspberry PI Jul 19 20:47:56 volumio-7 volumio[1091]: info: Completed loading Core Plugins Jul 19 20:47:56 volumio-7 volumio[1091]: info: Preparing to generate the ALSA configuration file Jul 19 20:47:56 volumio-7 volumio[1091]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 19 20:47:56 volumio-7 volumio[1091]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Jul 19 20:47:56 volumio-7 volumio[1091]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 19 20:47:56 volumio-7 volumio[1091]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 19 20:47:56 volumio-7 volumio[1091]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 19 20:47:56 volumio-7 volumio[1091]: info: Reading ALSA contributions from plugins. Jul 19 20:47:56 volumio-7 volumio[1091]: info: Asound.conf file unchanged, so no further update is needed Jul 19 20:47:56 volumio-7 volumio[1091]: info: Output device has changed, restarting MPD Jul 19 20:47:56 volumio-7 sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:47:56 volumio-7 volumio[1091]: info: Output device has changed, restarting Shairport Sync Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:56 volumio-7 sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:56 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:56 volumio-7 sudo[1246]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:56 volumio-7 sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:47:56 volumio-7 sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:56 volumio-7 volumio[1091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:47:56 volumio-7 volumio[1091]: info: ___________ START PLUGINS ___________ Jul 19 20:47:56 volumio-7 volumio[1091]: info: ControllerMpd::onStart: Initializing MPD Jul 19 20:47:56 volumio-7 volumio[1091]: info: Creating MPD Configuration file Jul 19 20:47:57 volumio-7 sudo[1256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:47:57 volumio-7 sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 20:47:57 volumio-7 volumio[1091]: info: [1721393277018] CoreMusicLibrary::Adding element 媒体服务器 Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:47:57 volumio-7 sudo[1256]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:57 volumio-7 sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:47:57 volumio-7 sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 systemd[1]: Listening on mpd.socket. Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:57 volumio-7 systemd[1]: Starting Music Player Daemon... Jul 19 20:47:57 volumio-7 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 19 20:47:57 volumio-7 systemd[1]: mpd.service: Succeeded. Jul 19 20:47:57 volumio-7 systemd[1]: Stopped Music Player Daemon. Jul 19 20:47:57 volumio-7 systemd[1]: Starting Music Player Daemon... Jul 19 20:47:57 volumio-7 volumio[1091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 20:47:57 volumio-7 volumio[1091]: info: [1721393277112] CoreMusicLibrary::Adding element Last_100 Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 20:47:57 volumio-7 volumio[1091]: info: [1721393277116] CoreMusicLibrary::Adding element Webradio Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:47:57 volumio-7 volumio[1091]: info: Initializing BBC Radios Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: AutoStart - onStart Jul 19 20:47:57 volumio-7 volumio[1091]: info: Loading i18n strings for locale zh Jul 19 20:47:57 volumio-7 volumio[1091]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 19 20:47:57 volumio-7 volumio[1091]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Jul 19 20:47:57 volumio-7 volumio[1091]: info: /tmp/myfifo created Jul 19 20:47:57 volumio-7 volumio[1091]: info: /tmp/myfifosa created Jul 19 20:47:57 volumio-7 sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jul 19 20:47:57 volumio-7 sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 sudo[1284]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:57 volumio-7 volumio[1091]: info: snd-dummy loaded Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:57 volumio-7 sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl Jul 19 20:47:57 volumio-7 sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 sudo[1299]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:57 volumio-7 volumio[1091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:47:57 volumio-7 volumio[1091]: info: Loading i18n strings for locale zh Jul 19 20:47:57 volumio-7 volumio[1091]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 19 20:47:57 volumio-7 volumio[1091]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:47:57 volumio-7 sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jul 19 20:47:57 volumio-7 sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 volumio[1091]: info: Loading i18n strings for locale zh Jul 19 20:47:57 volumio-7 volumio[1091]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 19 20:47:57 volumio-7 volumio[1091]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:47:57 volumio-7 volumio[1091]: info: Volumio Calling Home Jul 19 20:47:57 volumio-7 systemd[1]: Started RoonBridge. Jul 19 20:47:57 volumio-7 sudo[1304]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:57 volumio-7 sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jul 19 20:47:57 volumio-7 sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 sudo[1320]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:57 volumio-7 sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 19 20:47:57 volumio-7 sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 19 20:47:57 volumio-7 sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 19 20:47:57 volumio-7 sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:57 volumio-7 volumio[1091]: info: Discovery: adding ce84126f-0948-432e-924f-6c8c6c682d9c Jul 19 20:47:57 volumio-7 volumio[1091]: info: Discovery: Found device Volumio-7 Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:47:57 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:57 volumio-7 volumio[1091]: info: touch_display: No backlight interface detected. Jul 19 20:47:57 volumio-7 volumio[1091]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jul 19 20:47:57 volumio-7 volumio[1091]: info: MPD Permissions set Jul 19 20:47:57 volumio-7 volumio[1091]: info: MPD Permissions set Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:47:57 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:57 volumio-7 volumio[1091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:57 volumio-7 systemd[1]: Reloading. Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:57 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jul 19 20:47:58 volumio-7 sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 volumio[1091]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 19 20:47:58 volumio-7 sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 19 20:47:58 volumio-7 volumio[1091]: info: Starting Shairport Sync Jul 19 20:47:58 volumio-7 sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 sudo[1358]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:58 volumio-7 volumio[1091]: info: Starting Shairport Sync Jul 19 20:47:58 volumio-7 volumio[1091]: info: Starting Shairport Sync Jul 19 20:47:58 volumio-7 sudo[1361]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:58 volumio-7 sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:47:58 volumio-7 sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 volumio[1091]: info: Starting Shairport Sync Jul 19 20:47:58 volumio-7 wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 19 20:47:58 volumio-7 sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:47:58 volumio-7 sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:47:58 volumio-7 sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:47:58 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:58 volumio-7 sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:47:58 volumio-7 sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 volumio[1091]: info: ir_controller: LIRC correctly updated. Jul 19 20:47:58 volumio-7 volumio[1091]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Jul 19 20:47:58 volumio-7 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 19 20:47:58 volumio-7 volumio[1091]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jul 19 20:47:58 volumio-7 volumio[1091]: info: ir_controller: Raspberry Pi revision code: d03115 Jul 19 20:47:58 volumio-7 sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:47:58 volumio-7 sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 volumio[1091]: info: Volumio called home Jul 19 20:47:58 volumio-7 sudo[1378]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:58 volumio-7 volumio[1091]: info: MPD Permissions set Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:47:58 volumio-7 sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Jul 19 20:47:58 volumio-7 sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 sudo[1387]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:58 volumio-7 sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Jul 19 20:47:58 volumio-7 sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 kernel: Registered IR keymap rc-rc6-mce Jul 19 20:47:58 volumio-7 sudo[1394]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:58 volumio-7 volumio[1091]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Jul 19 20:47:58 volumio-7 kernel: IR RC6 protocol handler initialized Jul 19 20:47:58 volumio-7 sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 19 20:47:58 volumio-7 kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Jul 19 20:47:58 volumio-7 kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Jul 19 20:47:58 volumio-7 kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input17 Jul 19 20:47:58 volumio-7 sudo[1328]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:58 volumio-7 volumio[1091]: info: touch_display: systemctl daemon-reload succeeded. Jul 19 20:47:58 volumio-7 sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:58 volumio-7 sudo[1322]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:58 volumio-7 systemd[1]: Reloading. Jul 19 20:47:58 volumio-7 volumio[1091]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 19 20:47:58 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 19 20:47:59 volumio-7 sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 19 20:47:59 volumio-7 sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.017 Warn: get lock file path: /tmp/.rnbgem0- Jul 19 20:47:59 volumio-7 sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:47:59 volumio-7 sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:59 volumio-7 systemd-udevd[1288]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 20:47:59 volumio-7 systemd-logind[689]: Watching system buttons on /dev/input/event2 (gpio_ir_recv) Jul 19 20:47:59 volumio-7 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 19 20:47:59 volumio-7 sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 19 20:47:59 volumio-7 sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.529 Trace: [childprocess] using unix child process Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.597 Debug: PathForResource, filename: ../.update Jul 19 20:47:59 volumio-7 mpd[1265]: Jul 19 20:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.663 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.670 Debug: PathForResource, filename: RAATServer Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.670 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.671 Debug: PathForResource, filename: RoonBridgeHelper Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.671 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jul 19 20:47:59 volumio-7 RoonBridge[1306]: Initializing Jul 19 20:47:59 volumio-7 sudo[1325]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:59 volumio-7 volumio[1091]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.727 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jul 19 20:47:59 volumio-7 systemd[1]: Started Music Player Daemon. Jul 19 20:47:59 volumio-7 sudo[1258]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:59 volumio-7 sudo[1248]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:59 volumio-7 RoonBridge[1306]: 00:00:00.764 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jul 19 20:47:59 volumio-7 systemd[1]: Stopping Music Player Daemon... Jul 19 20:47:59 volumio-7 RoonBridge[1306]: Not Running (.o) Jul 19 20:47:59 volumio-7 systemd[1]: Started Volumio Kiosk. Jul 19 20:47:59 volumio-7 sudo[1409]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:59 volumio-7 volumio[1091]: error: MPD error: The expression evaluated to a falsy value: Jul 19 20:47:59 volumio-7 volumio[1091]: assert.ok(self.idling) Jul 19 20:47:59 volumio-7 volumio[1091]: error: The expression evaluated to a falsy value: Jul 19 20:47:59 volumio-7 volumio[1091]: assert.ok(self.idling) Jul 19 20:47:59 volumio-7 systemd[1]: Starting lircd(8) initialization helper tool... Jul 19 20:47:59 volumio-7 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:47:59 volumio-7 volumio[1091]: info: MPD running with PID1265 Jul 19 20:47:59 volumio-7 volumio[1091]: ,establishing connection Jul 19 20:47:59 volumio-7 volumio[1091]: error: MPD error: Error: write EPIPE Jul 19 20:47:59 volumio-7 volumio[1091]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 19 20:47:59 volumio-7 volumio[1091]: error: MPD error: The expression evaluated to a falsy value: Jul 19 20:47:59 volumio-7 volumio[1091]: assert.ok(self.idling) Jul 19 20:47:59 volumio-7 systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:47:59 volumio-7 volumio[1091]: error: The expression evaluated to a falsy value: Jul 19 20:47:59 volumio-7 volumio[1091]: assert.ok(self.idling) Jul 19 20:47:59 volumio-7 volumio[1091]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 19 20:47:59 volumio-7 volumio[1091]: info: touch_display: Volumio Kiosk started. Jul 19 20:47:59 volumio-7 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:47:59 volumio-7 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:47:59 volumio-7 sudo[1367]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:59 volumio-7 sudo[1369]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:59 volumio-7 systemd[1]: mpd.service: Succeeded. Jul 19 20:47:59 volumio-7 sudo[1373]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:59 volumio-7 systemd[1]: Stopped Music Player Daemon. Jul 19 20:47:59 volumio-7 sudo[1365]: pam_unix(sudo:session): session closed for user root Jul 19 20:47:59 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:47:59 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:59 volumio-7 volumio[1091]: info: Completed starting Core Plugins Jul 19 20:47:59 volumio-7 volumio[1091]: info: ------------------------------------------- Jul 19 20:47:59 volumio-7 volumio[1091]: info: ----- MyVolumio plugins startup ---- Jul 19 20:47:59 volumio-7 volumio[1091]: info: ------------------------------------------- Jul 19 20:47:59 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 19 20:47:59 volumio-7 systemd[1]: Starting Music Player Daemon... Jul 19 20:47:59 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:47:59 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:47:59 volumio-7 volumio[1091]: info: touch_display: X display number found: Jul 19 20:47:59 volumio-7 volumio[1091]: info: Shairport-Sync Started Jul 19 20:47:59 volumio-7 volumio[1091]: Error adding Membership: Error: addMembership EINVAL Jul 19 20:47:59 volumio-7 volumio[1091]: info: Shairport-Sync Started Jul 19 20:47:59 volumio-7 volumio[1091]: info: Shairport-Sync Started Jul 19 20:47:59 volumio-7 volumio[1091]: info: Shairport-Sync Started Jul 19 20:48:00 volumio-7 startx[1445]: X.Org X Server 1.20.4 Jul 19 20:48:00 volumio-7 startx[1445]: X Protocol Version 11, Revision 0 Jul 19 20:48:00 volumio-7 startx[1445]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 19 20:48:00 volumio-7 startx[1445]: Current Operating System: Linux volumio-7 6.1.77-v7l+ #1730 SMP Thu Feb 8 15:22:02 GMT 2024 armv7l Jul 19 20:48:00 volumio-7 startx[1445]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:88:2D:6D 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=ttyS0,115200 console=tty1 imgpart=UUID=b03999d3-62d1-465b-bc18-4eadea228d52 imgfile=/volumio_current.sqsh bootpart=UUID=F38E-0C3B datapart=UUID=4d34e5fc-a979-41a5-b19d-9e67ec9e687a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 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 nodebug use_kmsg=no Jul 19 20:48:00 volumio-7 startx[1445]: Build Date: 04 April 2023 07:50:56AM Jul 19 20:48:00 volumio-7 startx[1445]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 19 20:48:00 volumio-7 startx[1445]: Current version of pixman: 0.36.0 Jul 19 20:48:00 volumio-7 startx[1445]: Before reporting problems, check http://wiki.x.org Jul 19 20:48:00 volumio-7 startx[1445]: to make sure that you have the latest version. Jul 19 20:48:00 volumio-7 startx[1445]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 19 20:48:00 volumio-7 startx[1445]: (++) from command line, (!!) notice, (II) informational, Jul 19 20:48:00 volumio-7 startx[1445]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 19 20:48:00 volumio-7 startx[1445]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Jul 19 20:48:00 2024 Jul 19 20:48:00 volumio-7 startx[1445]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 19 20:48:00 volumio-7 startx[1445]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 19 20:48:00 volumio-7 volumio[1091]: info: touch_display: X display number found: 0 Jul 19 20:48:00 volumio-7 volumio[1091]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 19 20:48:00 volumio-7 RoonBridge[1306]: Running Jul 19 20:48:00 volumio-7 volumio[1091]: info: touch_display: X display number found: 0 Jul 19 20:48:00 volumio-7 lircd-setup[1451]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jul 19 20:48:00 volumio-7 lircd-setup[1451]: parser = configparser.SafeConfigParser() Jul 19 20:48:00 volumio-7 systemd[1]: lircd-setup.service: Succeeded. Jul 19 20:48:00 volumio-7 systemd[1]: Started lircd(8) initialization helper tool. Jul 19 20:48:00 volumio-7 systemd[1]: Starting Flexible IR remote input/output application support... Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: lircd: Opening log, level: Info Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Version: lircd 0.10.1 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: System info: Linux volumio-7 6.1.77-v7l+ #1730 SMP Thu Feb 8 15:22:02 GMT 2024 armv7l GNU/Linux Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: Initial device: /dev/lirc0 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: Initial device: /dev/lirc0 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: [lirc] protocol is enabled Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: driver: default Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: output: /var/run/lirc/lircd Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Info: lircd: Opening log, level: Info Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: nodaemon: 1 Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Notice: Using systemd fd Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Warning: Running as root Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Info: Using remote: lircd.conf. Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 19 20:48:00 volumio-7 systemd[1]: Started Flexible IR remote input/output application support. Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: logfile: syslog Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: immediate-init: 0 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: permission: 666 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: driver-options: Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: listen: 0 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: connect: (null) Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: userelease: 0 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: effective_user: (null) Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: release_suffix: _EVUP Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: allow_simulate: 0 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: repeat_max: 600 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: configfile: /etc/lirc/lircd.conf Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Options: dynamic_codes: (null) Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Current driver: default Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Driver API version: 3 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Driver version: 0.10.0 Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: lircd: Opening log, level: Info Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: Using systemd fd Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Warning: Running as root Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: Using remote: lircd.conf. Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 19 20:48:00 volumio-7 sudo[1431]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:00 volumio-7 sudo[1404]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:00 volumio-7 volumio[1091]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 19 20:48:00 volumio-7 volumio[1091]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 19 20:48:00 volumio-7 sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 19 20:48:00 volumio-7 sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:48:00 volumio-7 sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 19 20:48:00 volumio-7 sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:48:00 volumio-7 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Notice: accepted new client on /var/run/lirc/lircd Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: accepted new client on /var/run/lirc/lircd Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Info: [lirc] protocol is enabled Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: [lirc] protocol is enabled Jul 19 20:48:00 volumio-7 sudo[1499]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Info: removed client Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: removed client Jul 19 20:48:00 volumio-7 volumio[1091]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 19 20:48:00 volumio-7 systemd[1]: Stopping Handle events from IR remotes decoded by lircd(8)... Jul 19 20:48:00 volumio-7 systemd[1]: irexec.service: Main process exited, code=killed, status=15/TERM Jul 19 20:48:00 volumio-7 systemd[1]: irexec.service: Succeeded. Jul 19 20:48:00 volumio-7 systemd[1]: Stopped Handle events from IR remotes decoded by lircd(8). Jul 19 20:48:00 volumio-7 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Notice: accepted new client on /var/run/lirc/lircd Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Notice: accepted new client on /var/run/lirc/lircd Jul 19 20:48:00 volumio-7 lircd[1492]: lircd-0.10.1[1492]: Info: [lirc] protocol is enabled Jul 19 20:48:00 volumio-7 lircd-0.10.1[1492]: Info: [lirc] protocol is enabled Jul 19 20:48:00 volumio-7 sudo[1501]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:00 volumio-7 volumio[1091]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 19 20:48:00 volumio-7 RoonBridge[1306]: 00:00:00.054 Warn: get lock file path: /tmp/.rnbhgem0- Jul 19 20:48:01 volumio-7 mpd[1471]: Jul 19 20:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 20:48:01 volumio-7 systemd[1]: Started Music Player Daemon. Jul 19 20:48:01 volumio-7 sudo[1418]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:01 volumio-7 volumio[1091]: error: updateQueue error: null Jul 19 20:48:01 volumio-7 volumio[1091]: error: updateQueue error: null Jul 19 20:48:01 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:48:01 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:48:01 volumio-7 volumio[1091]: info: touch_display: Setting screensaver timeout to 60 seconds. Jul 19 20:48:02 volumio-7 sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 20:48:02 volumio-7 sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:48:02 volumio-7 sudo[1565]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:02 volumio-7 sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 20:48:02 volumio-7 sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:48:02 volumio-7 sudo[1569]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:02 volumio-7 sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 19 20:48:02 volumio-7 sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:48:02 volumio-7 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 19 20:48:02 volumio-7 sudo[1579]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:02 volumio-7 volumio[1091]: info: Upmpdcli Daemon Started Jul 19 20:48:02 volumio-7 volumio[1582]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 19 20:48:04 volumio-7 wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 19 20:48:09 volumio-7 volumio[1091]: info: Adding plugin bluetooth to MyMusic Plugins Jul 19 20:48:09 volumio-7 volumio[1091]: info: Adding plugin multiroom to MyMusic Plugins Jul 19 20:48:09 volumio-7 volumio[1091]: info: Adding plugin metavolumio to MyMusic Plugins Jul 19 20:48:09 volumio-7 volumio[1091]: info: Adding plugin cd_controller to MyMusic Plugins Jul 19 20:48:09 volumio-7 volumio[1091]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 19 20:48:09 volumio-7 volumio[1091]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 19 20:48:09 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 19 20:48:09 volumio-7 volumio[1582]: ..........................................................................++++ Jul 19 20:48:10 volumio-7 wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 19 20:48:10 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 19 20:48:10 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 19 20:48:10 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:48:10 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:48:10 volumio-7 volumio[1091]: info: Starting MyVolumio Remote Streaming Endpoints Jul 19 20:48:10 volumio-7 volumio[1091]: info: MyVolumio login type: Token Jul 19 20:48:10 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 19 20:48:10 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 19 20:48:11 volumio-7 volumio[1091]: info: Starting Streaming Service Transparent Proxy Jul 19 20:48:11 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 19 20:48:11 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 19 20:48:11 volumio-7 volumio[1091]: info: Streaming services startup Jul 19 20:48:11 volumio-7 volumio[1091]: info: Starting Streaming Daemon Jul 19 20:48:11 volumio-7 sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 19 20:48:11 volumio-7 sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:48:11 volumio-7 volumio[1091]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 19 20:48:11 volumio-7 sudo[1762]: pam_unix(sudo:session): session closed for user root Jul 19 20:48:11 volumio-7 volumio[1091]: error: Cannot start Volumio Streaming Daemon Jul 19 20:48:11 volumio-7 volumio[1091]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 19 20:48:11 volumio-7 volumio[1091]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 19 20:48:12 volumio-7 volumio[1091]: STREAMING PROXY: Starting server on port 3245 Jul 19 20:48:12 volumio-7 volumio[1091]: Node JS runtime: 14 Jul 19 20:48:12 volumio-7 volumio[1091]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 19 20:48:13 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 20:48:13 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 19 20:48:13 volumio-7 volumio[1091]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:48:14 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 20:48:14 volumio-7 volumio[1091]: info: Received Get System Info Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:48:14 volumio-7 volumio[1091]: info: Discovery: Getting this device information Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:48:14 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:48:14 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:48:14 volumio-7 volumio[1091]: info: Listing playlists Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 19 20:48:14 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:48:14 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:48:15 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 20:48:15 volumio-7 volumio[1091]: info: Received Get System Info Jul 19 20:48:15 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:48:15 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:48:15 volumio-7 volumio[1091]: info: Discovery: Getting this device information Jul 19 20:48:15 volumio-7 volumio[1091]: info: CoreCommandRouter::volumioGetState Jul 19 20:48:15 volumio-7 volumio[1091]: info: CorePlayQueue::getTrack 0 Jul 19 20:48:15 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:48:15 volumio-7 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 19 20:48:16 volumio-7 wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 19 20:48:16 volumio-7 volumio[1582]: .......................................................................++++ Jul 19 20:48:16 volumio-7 volumio[1582]: e is 65537 (0x010001) Jul 19 20:48:16 volumio-7 volumio[1582]: writing RSA key Jul 19 20:48:16 volumio-7 volumio[1091]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 20:48:16 volumio-7 volumio[1091]: Error: connect ECONNREFUSED 162.125.32.13:443 Jul 19 20:48:16 volumio-7 volumio[1091]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 19 20:48:16 volumio-7 volumio[1091]: errno: -111, Jul 19 20:48:16 volumio-7 volumio[1091]: code: 'ECONNREFUSED', Jul 19 20:48:16 volumio-7 volumio[1091]: syscall: 'connect', Jul 19 20:48:16 volumio-7 volumio[1091]: address: '162.125.32.13', Jul 19 20:48:16 volumio-7 volumio[1091]: port: 443 Jul 19 20:48:16 volumio-7 volumio[1091]: } Jul 19 20:48:16 volumio-7 volumio[1091]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 20:48:17 volumio-7 sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-19 20:47 Jul 19 20:48:17 volumio-7 sudo[1793]: 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="ef589a0572b08c3d1e9a984ec6a655ef3698e415" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 28 May 2024 12:17:12 PM CEST" VOLUMIO_VERSION="3.695" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f37ff40efaaf3752fac55d6a95d61cb"