-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-07-10 19:47:34 CEST. -- Jul 10 19:46:46 volumio-tv fake-hwclock[315]: Wed Jul 10 17:46:46 UTC 2024 Jul 10 19:46:46 volumio-tv systemd[1]: Starting Create System Users... Jul 10 19:46:46 volumio-tv systemd[1]: Starting Load/Save Random Seed... Jul 10 19:46:46 volumio-tv systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 10 19:46:46 volumio-tv systemd[1]: dynamicswap.service: Succeeded. Jul 10 19:46:46 volumio-tv systemd[1]: Started Restore / save the current clock. Jul 10 19:46:46 volumio-tv systemd[1]: Started Apply Kernel Variables. Jul 10 19:46:46 volumio-tv systemd[1]: Started Load/Save Random Seed. Jul 10 19:46:46 volumio-tv systemd[1]: Started Create System Users. Jul 10 19:46:46 volumio-tv systemd[1]: Starting Create Static Device Nodes in /dev... Jul 10 19:46:46 volumio-tv systemd[1]: Started udev Coldplug all Devices. Jul 10 19:46:46 volumio-tv systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jul 10 19:46:46 volumio-tv systemd[1]: Started Create Static Device Nodes in /dev. Jul 10 19:46:46 volumio-tv systemd[1]: Reached target Local File Systems (Pre). Jul 10 19:46:46 volumio-tv systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 10 19:46:46 volumio-tv systemd[1]: Mounting /tmp... Jul 10 19:46:46 volumio-tv systemd[1]: Mounting /var/spool/cups... Jul 10 19:46:46 volumio-tv systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 10 19:46:46 volumio-tv systemd[1]: Mounting /var/log... Jul 10 19:46:46 volumio-tv systemd[1]: Starting udev Kernel Device Manager... Jul 10 19:46:46 volumio-tv systemd[1]: Mounted /tmp. Jul 10 19:46:46 volumio-tv systemd[1]: Mounted /var/spool/cups. Jul 10 19:46:46 volumio-tv systemd[1]: Mounting /var/spool/cups/tmp... Jul 10 19:46:46 volumio-tv systemd[1]: Mounted /var/log. Jul 10 19:46:46 volumio-tv systemd[1]: Starting Flush Journal to Persistent Storage... Jul 10 19:46:46 volumio-tv systemd[1]: Mounted /var/spool/cups/tmp. Jul 10 19:46:46 volumio-tv systemd-udevd[353]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jul 10 19:46:46 volumio-tv systemd-journald[306]: Runtime journal (/run/log/journal/c1d531165394c79721acb5a765d65e88) is 7.5M, max 30.0M, 22.5M free. Jul 10 19:46:46 volumio-tv systemd[1]: Started Flush Journal to Persistent Storage. Jul 10 19:46:46 volumio-tv systemd[1]: Started udev Kernel Device Manager. Jul 10 19:46:46 volumio-tv systemd[1]: Starting Show Plymouth Boot Screen... Jul 10 19:46:46 volumio-tv systemd[1]: plymouth-start.service: Succeeded. Jul 10 19:46:46 volumio-tv systemd[1]: Started Show Plymouth Boot Screen. Jul 10 19:46:46 volumio-tv systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 10 19:46:46 volumio-tv systemd[1]: Reached target Paths. Jul 10 19:46:46 volumio-tv systemd[1]: Reached target Local Encrypted Volumes. Jul 10 19:46:46 volumio-tv systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jul 10 19:46:46 volumio-tv kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 19:46:46 volumio-tv kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jul 10 19:46:46 volumio-tv kernel: [vc_sm_connected_init]: start Jul 10 19:46:46 volumio-tv kernel: [vc_sm_connected_init]: installed successfully Jul 10 19:46:47 volumio-tv kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Jul 10 19:46:47 volumio-tv kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Jul 10 19:46:47 volumio-tv kernel: mc: Linux media interface: v0.10 Jul 10 19:46:47 volumio-tv kernel: videodev: Linux video capture interface: v2.00 Jul 10 19:46:47 volumio-tv kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 19:46:47 volumio-tv kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 19:46:47 volumio-tv kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 19:46:47 volumio-tv systemd[1]: Found device /dev/disk/by-uuid/F102-DE29. Jul 10 19:46:47 volumio-tv kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 19:46:47 volumio-tv kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 10 19:46:47 volumio-tv kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 10 19:46:47 volumio-tv kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jul 10 19:46:47 volumio-tv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jul 10 19:46:47 volumio-tv kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 10 19:46:48 volumio-tv systemd[1]: Starting File System Check on /dev/disk/by-uuid/F102-DE29... Jul 10 19:46:48 volumio-tv systemd[1]: Started File System Check Daemon to report status. Jul 10 19:46:48 volumio-tv kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 19:46:48 volumio-tv kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jul 10 19:46:48 volumio-tv kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 10 19:46:48 volumio-tv kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 10 19:46:48 volumio-tv kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jul 10 19:46:48 volumio-tv kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jul 10 19:46:48 volumio-tv kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jul 10 19:46:48 volumio-tv kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 10 19:46:48 volumio-tv systemd-udevd[363]: Using default interface naming scheme 'v240'. Jul 10 19:46:48 volumio-tv systemd-fsck[413]: fsck.fat 4.1 (2017-01-24) Jul 10 19:46:48 volumio-tv systemd-fsck[413]: /dev/mmcblk0p1: 371 files, 33665/46774 clusters Jul 10 19:46:48 volumio-tv systemd[1]: Started File System Check on /dev/disk/by-uuid/F102-DE29. Jul 10 19:46:48 volumio-tv systemd-udevd[363]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 19:46:48 volumio-tv systemd[1]: Mounting /boot... Jul 10 19:46:48 volumio-tv systemd[1]: Mounted /boot. Jul 10 19:46:48 volumio-tv systemd[1]: Reached target Local File Systems. Jul 10 19:46:48 volumio-tv systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 10 19:46:48 volumio-tv kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jul 10 19:46:48 volumio-tv systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 10 19:46:48 volumio-tv systemd[1]: Starting Preprocess NFS configuration... Jul 10 19:46:48 volumio-tv systemd[1]: Starting Create Volatile Files and Directories... Jul 10 19:46:48 volumio-tv systemd[1]: Received SIGRTMIN+20 from PID 197 (plymouthd). Jul 10 19:46:48 volumio-tv systemd[1]: plymouth-read-write.service: Succeeded. Jul 10 19:46:48 volumio-tv systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 10 19:46:48 volumio-tv systemd[1]: nfs-config.service: Succeeded. Jul 10 19:46:48 volumio-tv systemd[1]: Started Preprocess NFS configuration. Jul 10 19:46:48 volumio-tv kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jul 10 19:46:48 volumio-tv kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jul 10 19:46:48 volumio-tv kernel: usbcore: registered new interface driver brcmfmac Jul 10 19:46:48 volumio-tv kernel: Bluetooth: Core ver 2.22 Jul 10 19:46:48 volumio-tv kernel: NET: Registered PF_BLUETOOTH protocol family Jul 10 19:46:48 volumio-tv kernel: Bluetooth: HCI device and connection manager initialized Jul 10 19:46:48 volumio-tv kernel: Bluetooth: HCI socket layer initialized Jul 10 19:46:48 volumio-tv kernel: Bluetooth: L2CAP socket layer initialized Jul 10 19:46:48 volumio-tv kernel: Bluetooth: SCO socket layer initialized Jul 10 19:46:48 volumio-tv kernel: Bluetooth: HCI UART driver ver 2.3 Jul 10 19:46:48 volumio-tv kernel: Bluetooth: HCI UART protocol H4 registered Jul 10 19:46:48 volumio-tv kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jul 10 19:46:48 volumio-tv kernel: Bluetooth: HCI UART protocol Broadcom registered Jul 10 19:46:48 volumio-tv kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jul 10 19:46:48 volumio-tv kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jul 10 19:46:49 volumio-tv systemd[1]: Started Create Volatile Files and Directories. Jul 10 19:46:49 volumio-tv kernel: uart-pl011 3f201000.serial: no DMA platform data Jul 10 19:46:49 volumio-tv 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 10 19:46:49 volumio-tv systemd-udevd[357]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 19:46:49 volumio-tv kernel: Bluetooth: hci0: BCM: chip id 107 Jul 10 19:46:49 volumio-tv kernel: Bluetooth: hci0: BCM: features 0x2f Jul 10 19:46:49 volumio-tv kernel: Bluetooth: hci0: BCM4345C0 Jul 10 19:46:49 volumio-tv kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jul 10 19:46:49 volumio-tv kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jul 10 19:46:49 volumio-tv kernel: usb 1-1.3: 9:1: bogus dB values (-12800/-12700), disabling dB reporting Jul 10 19:46:49 volumio-tv kernel: usbcore: registered new interface driver snd-usb-audio Jul 10 19:46:49 volumio-tv systemd-udevd[360]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 10 19:46:49 volumio-tv systemd-udevd[366]: Using default interface naming scheme 'v240'. Jul 10 19:46:49 volumio-tv systemd-udevd[366]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 19:46:50 volumio-tv kernel: Bluetooth: hci0: BCM: features 0x2f Jul 10 19:46:50 volumio-tv kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jul 10 19:46:50 volumio-tv kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jul 10 19:46:50 volumio-tv kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Jul 10 19:46:50 volumio-tv systemd[1]: Condition check resulted in 7800 being skipped. Jul 10 19:46:50 volumio-tv systemd[1]: Created slice system-bthelper.slice. Jul 10 19:46:50 volumio-tv systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jul 10 19:46:50 volumio-tv systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jul 10 19:46:50 volumio-tv systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jul 10 19:46:50 volumio-tv systemd[1]: Starting Show Plymouth Boot Screen... Jul 10 19:46:50 volumio-tv systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 10 19:46:50 volumio-tv systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 10 19:46:50 volumio-tv systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 10 19:46:50 volumio-tv systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 10 19:46:50 volumio-tv systemd[1]: Started ifup for eth0. Jul 10 19:46:50 volumio-tv systemd-udevd[365]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. Jul 10 19:46:50 volumio-tv systemd-udevd[357]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jul 10 19:46:50 volumio-tv systemd[1]: Starting RPC bind portmap service... Jul 10 19:46:50 volumio-tv systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jul 10 19:46:50 volumio-tv systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jul 10 19:46:50 volumio-tv systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jul 10 19:46:50 volumio-tv systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jul 10 19:46:50 volumio-tv systemd[1]: Reached target NFS client services. Jul 10 19:46:51 volumio-tv systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jul 10 19:46:51 volumio-tv systemd[1]: Started RPC bind portmap service. Jul 10 19:46:51 volumio-tv systemd[1]: Started Helper to synchronize boot up for ifupdown. Jul 10 19:46:51 volumio-tv systemd[1]: plymouth-read-write.service: Succeeded. Jul 10 19:46:51 volumio-tv systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 10 19:46:51 volumio-tv systemd[1]: plymouth-start.service: Succeeded. Jul 10 19:46:51 volumio-tv systemd[1]: Started Show Plymouth Boot Screen. Jul 10 19:46:51 volumio-tv systemd[1]: Received SIGRTMIN+20 from PID 197 (plymouthd). Jul 10 19:46:51 volumio-tv systemd[1]: Started Update UTMP about System Boot/Shutdown. Jul 10 19:46:51 volumio-tv systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 10 19:46:51 volumio-tv systemd[1]: Reached target System Initialization. Jul 10 19:46:51 volumio-tv systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 10 19:46:51 volumio-tv systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 10 19:46:51 volumio-tv systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 10 19:46:51 volumio-tv systemd[1]: Listening on triggerhappy.socket. Jul 10 19:46:51 volumio-tv systemd[1]: Reached target Sockets. Jul 10 19:46:51 volumio-tv systemd[1]: Reached target Basic System. Jul 10 19:46:51 volumio-tv kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jul 10 19:46:51 volumio-tv systemd[1]: Starting triggerhappy global hotkey daemon... Jul 10 19:46:51 volumio-tv systemd[1]: Started Volumio Iptables Module. Jul 10 19:46:51 volumio-tv systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 10 19:46:51 volumio-tv systemd[1]: Started D-Bus System Message Bus. Jul 10 19:46:51 volumio-tv systemd[1]: Started Manage Sound Card State (restore and store). Jul 10 19:46:51 volumio-tv systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jul 10 19:46:51 volumio-tv thd[529]: Unable to parse trigger line: Jul 10 19:46:51 volumio-tv thd[529]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 10 19:46:51 volumio-tv thd[529]: Unable to parse trigger line: Jul 10 19:46:51 volumio-tv thd[529]: Found socket passed from systemd Jul 10 19:46:51 volumio-tv systemd[1]: Starting Login Service... Jul 10 19:46:51 volumio-tv systemd[1]: Starting Wireless Services... Jul 10 19:46:51 volumio-tv systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 10 19:46:51 volumio-tv alsactl[542]: alsactl 1.1.8 daemon started Jul 10 19:46:51 volumio-tv systemd[1]: Started volumio-remote-updater.service. Jul 10 19:46:51 volumio-tv systemd[1]: Starting Save/Restore Sound Card State... Jul 10 19:46:51 volumio-tv systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jul 10 19:46:51 volumio-tv avahi-daemon[537]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jul 10 19:46:51 volumio-tv avahi-daemon[537]: Successfully dropped root privileges. Jul 10 19:46:51 volumio-tv avahi-daemon[537]: avahi-daemon 0.7 starting up. Jul 10 19:46:51 volumio-tv systemd[1]: Starting WPA supplicant... Jul 10 19:46:51 volumio-tv systemd[1]: Started UPnP Renderer front-end to MPD. Jul 10 19:46:51 volumio-tv systemd[1]: Started Daily apt download activities. Jul 10 19:46:51 volumio-tv systemd[1]: Started Daily apt upgrade and clean activities. Jul 10 19:46:51 volumio-tv systemd[1]: Reached target Timers. Jul 10 19:46:51 volumio-tv systemd[1]: Started Volumio Log Rotation Service. Jul 10 19:46:51 volumio-tv systemd[1]: Starting Raise network interfaces... Jul 10 19:46:51 volumio-tv systemd[1]: Reached target RPC Port Mapper. Jul 10 19:46:51 volumio-tv systemd[1]: Reached target Remote File Systems (Pre). Jul 10 19:46:51 volumio-tv systemd[1]: Reached target Remote File Systems. Jul 10 19:46:51 volumio-tv systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jul 10 19:46:51 volumio-tv systemd[1]: Started ifup for wlan0. Jul 10 19:46:51 volumio-tv systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 10 19:46:51 volumio-tv systemd[1]: Started triggerhappy global hotkey daemon. Jul 10 19:46:51 volumio-tv systemd[1]: Started Load/Save RF Kill Switch Status. Jul 10 19:46:51 volumio-tv systemd[1]: Started Save/Restore Sound Card State. Jul 10 19:46:51 volumio-tv kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 19:46:51 volumio-tv systemd-logind[544]: New seat seat0. Jul 10 19:46:52 volumio-tv systemd[1]: Reached target Sound Card. Jul 10 19:46:52 volumio-tv sh[506]: eth0=eth0 Jul 10 19:46:52 volumio-tv systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jul 10 19:46:52 volumio-tv systemd[1]: Starting dhcpcd on all interfaces... Jul 10 19:46:52 volumio-tv systemd[1]: hciuart.service: Succeeded. Jul 10 19:46:52 volumio-tv systemd[1]: Started Configure Bluetooth Modems connected by UART. Jul 10 19:46:52 volumio-tv volumio-remote-updater[548]: Error: No active session Jul 10 19:46:52 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:46:52] [info] asio async_connect error: system:111 (Connection refused) Jul 10 19:46:52 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:46:52] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 19:46:52 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:46:52] [error] handle_connect error: Underlying Transport Error Jul 10 19:46:52 volumio-tv dhcpcd[631]: dev: loaded udev Jul 10 19:46:52 volumio-tv avahi-daemon[537]: Successfully called chroot(). Jul 10 19:46:52 volumio-tv avahi-daemon[537]: Successfully dropped remaining capabilities. Jul 10 19:46:52 volumio-tv dbus-daemon[538]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 10 19:46:52 volumio-tv wpa_supplicant[555]: Successfully initialized wpa_supplicant Jul 10 19:46:52 volumio-tv systemd[1]: Starting Raspberry Pi bluetooth helper... Jul 10 19:46:52 volumio-tv avahi-daemon[537]: Loading service file /services/volumio.service. Jul 10 19:46:52 volumio-tv sh[571]: wlan0=wlan0 Jul 10 19:46:52 volumio-tv systemd[1]: Started WPA supplicant. Jul 10 19:46:52 volumio-tv systemd[1]: Started Login Service. Jul 10 19:46:52 volumio-tv avahi-daemon[537]: Network interface enumeration completed. Jul 10 19:46:52 volumio-tv avahi-daemon[537]: Server startup complete. Host name is volumio-tv.local. Local service cookie is 3987293025. Jul 10 19:46:52 volumio-tv avahi-daemon[537]: Service "Volumio TV" (/services/volumio.service) successfully established. Jul 10 19:46:52 volumio-tv systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 10 19:46:52 volumio-tv kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 10 19:46:52 volumio-tv kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Jul 10 19:46:52 volumio-tv dhcpcd[631]: forked to background, child pid 701 Jul 10 19:46:52 volumio-tv systemd[1]: Started dhcpcd on all interfaces. Jul 10 19:46:52 volumio-tv bthelper[674]: Raspberry Pi BDADDR already set Jul 10 19:46:52 volumio-tv systemd[1]: Started Raspberry Pi bluetooth helper. Jul 10 19:46:52 volumio-tv volumio[556]: Could not open config: /tmp/upmpdcli.conf Jul 10 19:46:52 volumio-tv systemd[1]: Starting Bluetooth service... Jul 10 19:46:52 volumio-tv systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 10 19:46:52 volumio-tv systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 10 19:46:53 volumio-tv systemd[1]: Started Raise network interfaces. Jul 10 19:46:53 volumio-tv systemd[1]: Reached target Network. Jul 10 19:46:53 volumio-tv systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jul 10 19:46:53 volumio-tv systemd[1]: Starting Permit User Sessions... Jul 10 19:46:53 volumio-tv systemd[1]: Reached target Network is Online. Jul 10 19:46:53 volumio-tv systemd[1]: Starting /etc/rc.local Compatibility... Jul 10 19:46:53 volumio-tv systemd[1]: Starting Samba NMB Daemon... Jul 10 19:46:53 volumio-tv systemd[1]: Starting LSB: Brings up/down network automatically... Jul 10 19:46:53 volumio-tv dhcpcd-run-hooks[755]: wlan0: starting wpa_supplicant Jul 10 19:46:53 volumio-tv systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 19:46:53 volumio-tv systemd[1]: Starting Network Time Service... Jul 10 19:46:53 volumio-tv bluetoothd[721]: Bluetooth daemon 5.50 Jul 10 19:46:53 volumio-tv loadcpufreq[564]: Loading cpufreq kernel modules...done (none). Jul 10 19:46:53 volumio-tv systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jul 10 19:46:53 volumio-tv systemd[1]: Started /etc/rc.local Compatibility. Jul 10 19:46:53 volumio-tv bluetoothd[721]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 10 19:46:53 volumio-tv systemd[1]: Started Permit User Sessions. Jul 10 19:46:53 volumio-tv systemd[1]: iptables.service: Succeeded. Jul 10 19:46:53 volumio-tv systemd[1]: Started Bluetooth service. Jul 10 19:46:53 volumio-tv systemd[1]: Reached target Bluetooth. Jul 10 19:46:53 volumio-tv bluetoothd[721]: Starting SDP server Jul 10 19:46:53 volumio-tv bluetoothd[721]: Excluding (cli) sap Jul 10 19:46:53 volumio-tv systemd[1]: Starting Hold until boot process finishes up... Jul 10 19:46:53 volumio-tv systemd[1]: Starting Terminate Plymouth Boot Screen... Jul 10 19:46:53 volumio-tv systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jul 10 19:46:53 volumio-tv dhcpcd[701]: wlan0: connected to Access Point `' Jul 10 19:46:53 volumio-tv systemd[1]: Received SIGRTMIN+21 from PID 197 (plymouthd). Jul 10 19:46:53 volumio-tv kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jul 10 19:46:53 volumio-tv kernel: Bluetooth: BNEP filters: protocol multicast Jul 10 19:46:53 volumio-tv kernel: Bluetooth: BNEP socket layer initialized Jul 10 19:46:53 volumio-tv bluetoothd[721]: Bluetooth management interface 1.22 initialized Jul 10 19:46:53 volumio-tv dbus-daemon[538]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=721 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jul 10 19:46:53 volumio-tv kernel: Bluetooth: MGMT ver 1.22 Jul 10 19:46:53 volumio-tv bluetoothd[721]: Failed to set privacy: Rejected (0x0b) Jul 10 19:46:53 volumio-tv ifplugd(eth0)[802]: ifplugd 0.28 initializing. Jul 10 19:46:53 volumio-tv ifplugd(eth0)[802]: Using interface eth0/B8:27:EB:EC:EE:2E with driver (version: 6.1.77-v7+) Jul 10 19:46:53 volumio-tv ifplugd(eth0)[802]: Using detection mode: SIOCETHTOOL Jul 10 19:46:53 volumio-tv ifplugd(eth0)[802]: Initialization complete, link beat detected. Jul 10 19:46:53 volumio-tv ntpd[762]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 10 19:46:53 volumio-tv ntpd[762]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jul 10 19:46:54 volumio-tv ntpd[803]: proto: precision = 1.302 usec (-19) Jul 10 19:46:54 volumio-tv ifplugd(eth0)[802]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 10 19:46:54 volumio-tv ntpd[803]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 10 19:46:54 volumio-tv ntpd[803]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 10 19:46:54 volumio-tv ntpd[803]: Listen and drop on 0 v6wildcard [::]:123 Jul 10 19:46:54 volumio-tv ntpd[803]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 10 19:46:54 volumio-tv ntpd[803]: Listen normally on 2 lo 127.0.0.1:123 Jul 10 19:46:54 volumio-tv ntpd[803]: Listening on routing socket on fd #19 for interface updates Jul 10 19:46:54 volumio-tv ntpd[803]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 19:46:54 volumio-tv ntpd[803]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 19:46:54 volumio-tv dhcpcd[701]: DUID 00:01:00:01:2d:69:1c:cd:b8:27:eb:ec:ee:2e Jul 10 19:46:54 volumio-tv dhcpcd[701]: eth0: IAID eb:ec:ee:2e Jul 10 19:46:54 volumio-tv dhcpcd[701]: eth0: adding address fe80::ba27:ebff:feec:ee2e Jul 10 19:46:54 volumio-tv dhcpcd[701]: ipv6_addaddr1: Permission denied Jul 10 19:46:54 volumio-tv dhcpcd[701]: eth0: probing address 192.168.1.11/24 Jul 10 19:46:54 volumio-tv systemd[1]: Started Network Time Service. Jul 10 19:46:54 volumio-tv systemd[1]: plymouth-quit-wait.service: Succeeded. Jul 10 19:46:54 volumio-tv systemd[1]: Started Hold until boot process finishes up. Jul 10 19:46:54 volumio-tv dhcpcd[701]: wlan0: waiting for carrier Jul 10 19:46:54 volumio-tv systemd[1]: plymouth-quit.service: Succeeded. Jul 10 19:46:54 volumio-tv systemd[1]: Started Terminate Plymouth Boot Screen. Jul 10 19:46:54 volumio-tv systemd[1]: Received SIGRTMIN+21 from PID 197 (n/a). Jul 10 19:46:54 volumio-tv ifplugd(eth0)[802]: client: ifup: interface eth0 already configured Jul 10 19:46:54 volumio-tv systemd[1]: Started Getty on tty1. Jul 10 19:46:54 volumio-tv systemd[1]: Reached target Login Prompts. Jul 10 19:46:54 volumio-tv systemd[1]: Starting Hostname Service... Jul 10 19:46:54 volumio-tv ifplugd(eth0)[802]: Program executed successfully. Jul 10 19:46:54 volumio-tv ifplugd[754]: Network Interface Plugging Daemon...start eth0...done. Jul 10 19:46:54 volumio-tv systemd[1]: Started LSB: Brings up/down network automatically. Jul 10 19:46:54 volumio-tv cpufrequtils[776]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jul 10 19:46:54 volumio-tv systemd[1]: Started LSB: set CPUFreq kernel parameters. Jul 10 19:46:54 volumio-tv dhcpcd[701]: eth0: soliciting an IPv6 router Jul 10 19:46:54 volumio-tv haveged[515]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jul 10 19:46:54 volumio-tv haveged[515]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jul 10 19:46:54 volumio-tv haveged[515]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99922 Jul 10 19:46:54 volumio-tv haveged[515]: haveged: fills: 0, generated: 0 Jul 10 19:46:54 volumio-tv dbus-daemon[538]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 10 19:46:54 volumio-tv systemd[1]: Started Hostname Service. Jul 10 19:46:54 volumio-tv nmbd[764]: [2024/07/10 19:46:54.885679, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 10 19:46:54 volumio-tv nmbd[764]: started asyncdns process 832 Jul 10 19:46:54 volumio-tv nmbd[764]: [2024/07/10 19:46:54.887775, 0] ../lib/util/become_daemon.c:149(daemon_status) Jul 10 19:46:54 volumio-tv nmbd[764]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 10 19:46:54 volumio-tv nmbd[764]: [2024/07/10 19:46:54.887953, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jul 10 19:46:54 volumio-tv nmbd[764]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 10 19:46:54 volumio-tv ntpd[803]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 10 19:46:55 volumio-tv wireless.js[545]: Cleaning previous... Jul 10 19:46:55 volumio-tv sudo[840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 10 19:46:55 volumio-tv sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:55 volumio-tv sudo[840]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:55 volumio-tv sudo[842]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 10 19:46:55 volumio-tv sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:55 volumio-tv ntpd[803]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 10 19:46:56 volumio-tv sudo[842]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:56 volumio-tv wireless.js[545]: Stopped aP Jul 10 19:46:56 volumio-tv sudo[849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 10 19:46:56 volumio-tv sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:56 volumio-tv sudo[849]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:56 volumio-tv kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 19:46:56 volumio-tv sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 10 19:46:56 volumio-tv sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:56 volumio-tv sudo[851]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:56 volumio-tv sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 10 19:46:56 volumio-tv sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:56 volumio-tv sudo[858]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:56 volumio-tv sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 10 19:46:56 volumio-tv sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:56 volumio-tv systemd[1]: systemd-rfkill.service: Succeeded. Jul 10 19:46:56 volumio-tv ntpd[803]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 10 19:46:57 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:46:57] [info] asio async_connect error: system:111 (Connection refused) Jul 10 19:46:57 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:46:57] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 19:46:57 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:46:57] [error] handle_connect error: Underlying Transport Error Jul 10 19:46:57 volumio-tv bthelper[674]: Changing power off succeeded Jul 10 19:46:57 volumio-tv ntpd[803]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 10 19:46:58 volumio-tv bthelper[674]: [CHG] Controller B8:27:EB:46:44:84 Class: 0x0000041c Jul 10 19:46:58 volumio-tv bthelper[674]: Changing power on succeeded Jul 10 19:46:59 volumio-tv sudo[860]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:59 volumio-tv wireless.js[545]: Start wireless flow Jul 10 19:46:59 volumio-tv wireless.js[545]: Stopped hotspot (if there).. Jul 10 19:46:59 volumio-tv sudo[872]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 10 19:46:59 volumio-tv sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:59 volumio-tv sudo[872]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:59 volumio-tv sudo[874]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 10 19:46:59 volumio-tv sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:59 volumio-tv dhcpcd[701]: eth0: using static address 192.168.1.11/24 Jul 10 19:46:59 volumio-tv avahi-daemon[537]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.11. Jul 10 19:46:59 volumio-tv avahi-daemon[537]: New relevant interface eth0.IPv4 for mDNS. Jul 10 19:46:59 volumio-tv dhcpcd[701]: eth0: adding route to 192.168.1.0/24 Jul 10 19:46:59 volumio-tv avahi-daemon[537]: Registering new address record for 192.168.1.11 on eth0.IPv4. Jul 10 19:46:59 volumio-tv dhcpcd[701]: eth0: adding default route via 192.168.1.5 Jul 10 19:46:59 volumio-tv sudo[874]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:59 volumio-tv wireless.js[545]: DHCP IP Jul 10 19:46:59 volumio-tv wireless.js[545]: Start ap Jul 10 19:46:59 volumio-tv wpa_supplicant[880]: Successfully initialized wpa_supplicant Jul 10 19:46:59 volumio-tv sudo[883]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 10 19:46:59 volumio-tv sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:46:59 volumio-tv kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 19:46:59 volumio-tv dhcpcd[895]: sending commands to master dhcpcd process Jul 10 19:46:59 volumio-tv sudo[883]: pam_unix(sudo:session): session closed for user root Jul 10 19:46:59 volumio-tv dhcpcd[701]: control command: /sbin/dhcpcd Jul 10 19:46:59 volumio-tv dhcpcd[701]: lo: carrier acquired Jul 10 19:46:59 volumio-tv dhcpcd[701]: lo: IAID 6c:6f:00:00 Jul 10 19:46:59 volumio-tv dhcpcd[701]: lo: ipv6_start: Operation not supported Jul 10 19:46:59 volumio-tv dhcpcd[701]: lo: using static address 127.0.0.1/8 Jul 10 19:46:59 volumio-tv dhcpcd[701]: lo: adding route to 127.0.0.0/8 Jul 10 19:46:59 volumio-tv dhcpcd[701]: arp_announce: Invalid argument Jul 10 19:46:59 volumio-tv nmbd[764]: [2024/07/10 19:46:59.895720, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 19:46:59 volumio-tv systemd[1]: Started Samba NMB Daemon. Jul 10 19:46:59 volumio-tv nmbd[764]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 10 19:46:59 volumio-tv nmbd[764]: [2024/07/10 19:46:59.918027, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 10 19:46:59 volumio-tv systemd[1]: Starting Samba Winbind Daemon... Jul 10 19:46:59 volumio-tv nmbd[764]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.11 for name WORKGROUP<1d>. Jul 10 19:46:59 volumio-tv nmbd[764]: This response was from IP 192.168.1.16, reporting an IP address of 192.168.1.16. Jul 10 19:47:00 volumio-tv winbindd[912]: [2024/07/10 19:47:00.263470, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 10 19:47:00 volumio-tv winbindd[912]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 10 19:47:00 volumio-tv winbindd[912]: [2024/07/10 19:47:00.289364, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 19:47:00 volumio-tv winbindd[912]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 10 19:47:00 volumio-tv systemd[1]: Started Samba Winbind Daemon. Jul 10 19:47:00 volumio-tv systemd[1]: Starting Samba SMB Daemon... Jul 10 19:47:00 volumio-tv wireless.js[545]: trying... Jul 10 19:47:00 volumio-tv sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:00 volumio-tv sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:00 volumio-tv sudo[968]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:00 volumio-tv smbd[966]: [2024/07/10 19:47:00.910986, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 19:47:00 volumio-tv smbd[966]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 10 19:47:00 volumio-tv systemd[1]: Started Samba SMB Daemon. Jul 10 19:47:00 volumio-tv ntpd[803]: Listen normally on 3 eth0 192.168.1.11:123 Jul 10 19:47:00 volumio-tv ntpd[803]: new interface(s) found: waking up resolver Jul 10 19:47:01 volumio-tv wireless.js[545]: trying... Jul 10 19:47:01 volumio-tv sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:01 volumio-tv sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:01 volumio-tv sudo[978]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:02 volumio-tv wpa_supplicant[911]: wlan0: Trying to associate with SSID 'TP-Link_A5AA' Jul 10 19:47:02 volumio-tv wireless.js[545]: trying... Jul 10 19:47:02 volumio-tv sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:02 volumio-tv sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:02 volumio-tv sudo[981]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:03 volumio-tv wireless.js[545]: trying... Jul 10 19:47:03 volumio-tv sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:03 volumio-tv sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:03 volumio-tv sudo[984]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:04 volumio-tv wireless.js[545]: trying... Jul 10 19:47:04 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:47:04] [info] asio async_connect error: system:111 (Connection refused) Jul 10 19:47:04 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:47:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 19:47:04 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:47:04] [error] handle_connect error: Underlying Transport Error Jul 10 19:47:04 volumio-tv sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:04 volumio-tv sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:04 volumio-tv sudo[987]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:05 volumio-tv wpa_supplicant[911]: wlan0: Associated with b0:be:76:a3:a5:aa Jul 10 19:47:05 volumio-tv wpa_supplicant[911]: wlan0: CTRL-EVENT-CONNECTED - Connection to b0:be:76:a3:a5:aa completed [id=0 id_str=] Jul 10 19:47:05 volumio-tv dhcpcd[701]: wlan0: carrier acquired Jul 10 19:47:05 volumio-tv wpa_supplicant[911]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 10 19:47:05 volumio-tv dhcpcd[701]: wlan0: connected to Access Point `TP-Link_A5AA' Jul 10 19:47:05 volumio-tv dhcpcd[701]: wlan0: IAID eb:b9:bb:7b Jul 10 19:47:05 volumio-tv dhcpcd[701]: wlan0: adding address fe80::ba27:ebff:feb9:bb7b Jul 10 19:47:05 volumio-tv dhcpcd[701]: ipv6_addaddr1: Permission denied Jul 10 19:47:05 volumio-tv dhcpcd[701]: wlan0: probing address 192.168.1.12/24 Jul 10 19:47:05 volumio-tv wireless.js[545]: trying... Jul 10 19:47:05 volumio-tv sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:05 volumio-tv sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:05 volumio-tv sudo[991]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:06 volumio-tv dhcpcd[701]: wlan0: soliciting an IPv6 router Jul 10 19:47:06 volumio-tv wireless.js[545]: trying... Jul 10 19:47:06 volumio-tv sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:06 volumio-tv sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:06 volumio-tv sudo[994]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:07 volumio-tv wireless.js[545]: trying... Jul 10 19:47:07 volumio-tv sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:07 volumio-tv sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:07 volumio-tv sudo[997]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:08 volumio-tv wireless.js[545]: trying... Jul 10 19:47:08 volumio-tv sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:08 volumio-tv sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:08 volumio-tv sudo[1000]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:09 volumio-tv wireless.js[545]: trying... Jul 10 19:47:09 volumio-tv sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:09 volumio-tv sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:09 volumio-tv sudo[1003]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:10 volumio-tv wireless.js[545]: trying... Jul 10 19:47:10 volumio-tv sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:10 volumio-tv sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:10 volumio-tv sudo[1006]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:10 volumio-tv dhcpcd[701]: wlan0: using static address 192.168.1.12/24 Jul 10 19:47:10 volumio-tv avahi-daemon[537]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.12. Jul 10 19:47:10 volumio-tv avahi-daemon[537]: New relevant interface wlan0.IPv4 for mDNS. Jul 10 19:47:10 volumio-tv avahi-daemon[537]: Registering new address record for 192.168.1.12 on wlan0.IPv4. Jul 10 19:47:10 volumio-tv dhcpcd[701]: wlan0: adding route to 192.168.1.0/24 Jul 10 19:47:10 volumio-tv dhcpcd[701]: wlan0: adding default route via 192.168.1.5 Jul 10 19:47:11 volumio-tv wireless.js[545]: trying... Jul 10 19:47:11 volumio-tv sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 19:47:11 volumio-tv sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:11 volumio-tv sudo[1029]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:11 volumio-tv wireless.js[545]: Connected to: ----TP-Link_A5AA Jul 10 19:47:11 volumio-tv wireless.js[545]: ---- Jul 10 19:47:11 volumio-tv sudo[1032]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 19:47:11 volumio-tv sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:11 volumio-tv sudo[1032]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:11 volumio-tv wireless.js[545]: ... joined AP, wlan0 IPv4 is 192.168.1.12, ipV6 is undefined Jul 10 19:47:11 volumio-tv wireless.js[545]: It's done! AP Jul 10 19:47:11 volumio-tv kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 19:47:11 volumio-tv systemd[1]: Started Wireless Services. Jul 10 19:47:11 volumio-tv systemd[1]: Started Volumio Backend Module. Jul 10 19:47:11 volumio-tv systemd[1]: Started Volumio Cpu Tweaker. Jul 10 19:47:11 volumio-tv systemd[1]: Reached target Multi-User System. Jul 10 19:47:11 volumio-tv systemd[1]: Reached target Graphical Interface. Jul 10 19:47:11 volumio-tv systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 10 19:47:11 volumio-tv ntpd[803]: Listen normally on 4 wlan0 192.168.1.12:123 Jul 10 19:47:11 volumio-tv ntpd[803]: new interface(s) found: waking up resolver Jul 10 19:47:11 volumio-tv volumio-cpu-tweak[1038]: Setting RT Priority for mpd Jul 10 19:47:11 volumio-tv systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 10 19:47:11 volumio-tv volumio-cpu-tweak[1038]: pid 35's current scheduling policy: SCHED_OTHER Jul 10 19:47:11 volumio-tv volumio-cpu-tweak[1038]: pid 35's current scheduling priority: 0 Jul 10 19:47:11 volumio-tv systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 10 19:47:11 volumio-tv volumio-cpu-tweak[1038]: Setting MPD Affinity Jul 10 19:47:11 volumio-tv systemd[1]: Startup finished in 15.593s (kernel) + 28.860s (userspace) = 44.453s. Jul 10 19:47:12 volumio-tv volumio-cpu-tweak[1038]: pid 3's current affinity mask: f Jul 10 19:47:12 volumio-tv volumio-cpu-tweak[1038]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 10 19:47:12 volumio-tv volumio-cpu-tweak[1038]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 10 19:47:12 volumio-tv systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 10 19:47:14 volumio-tv volumio[1037]: info: ------------------------------------------- Jul 10 19:47:14 volumio-tv volumio[1037]: info: ----- Volumio3 ---- Jul 10 19:47:14 volumio-tv volumio[1037]: info: ------------------------------------------- Jul 10 19:47:14 volumio-tv volumio[1037]: info: ----- System startup ---- Jul 10 19:47:14 volumio-tv volumio[1037]: info: ------------------------------------------- Jul 10 19:47:14 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:47:14] [connect] Successful connection Jul 10 19:47:15 volumio-tv volumio[1037]: info: MYVOLUMIO Environment detected Jul 10 19:47:15 volumio-tv volumio[1037]: info: Plugin folders cleanup Jul 10 19:47:15 volumio-tv volumio[1037]: info: Scanning into folder /volumio/app/plugins/ Jul 10 19:47:15 volumio-tv volumio[1037]: info: Scanning category audio_interface Jul 10 19:47:15 volumio-tv volumio[1037]: info: Scanning category miscellanea Jul 10 19:47:15 volumio-tv volumio[1037]: info: Scanning category music_service Jul 10 19:47:15 volumio-tv volumio[1037]: info: Scanning category plugins.json Jul 10 19:47:15 volumio-tv volumio[1037]: info: Scanning category system_controller Jul 10 19:47:15 volumio-tv volumio[1037]: info: Scanning category user_interface Jul 10 19:47:15 volumio-tv volumio[1037]: info: Scanning into folder /data/plugins/ Jul 10 19:47:15 volumio-tv volumio[1037]: info: Plugin folders cleanup completed Jul 10 19:47:15 volumio-tv volumio[1037]: info: ------------------------------------------- Jul 10 19:47:15 volumio-tv volumio[1037]: info: ----- Core plugins startup ---- Jul 10 19:47:15 volumio-tv volumio[1037]: info: ------------------------------------------- Jul 10 19:47:15 volumio-tv volumio[1037]: info: Loading plugins from folder /volumio/app/plugins/ Jul 10 19:47:15 volumio-tv volumio[1037]: info: Adding plugin upnp to MyMusic Plugins Jul 10 19:47:15 volumio-tv volumio[1037]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 10 19:47:15 volumio-tv volumio[1037]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 10 19:47:15 volumio-tv volumio[1037]: info: Loading plugins from folder /data/plugins/ Jul 10 19:47:15 volumio-tv volumio[1037]: info: Loading plugin "system"... Jul 10 19:47:15 volumio-tv volumio[1037]: info: Loading plugin "appearance"... Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "network"... Jul 10 19:47:17 volumio-tv volumio[1037]: info: Refreshing Cached IP Addresses Jul 10 19:47:17 volumio-tv sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 19:47:17 volumio-tv sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:17 volumio-tv sudo[1069]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:17 volumio-tv sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "services"... Jul 10 19:47:17 volumio-tv sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:17 volumio-tv sudo[1071]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "alsa_controller"... Jul 10 19:47:17 volumio-tv sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 10 19:47:17 volumio-tv sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:17 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "wizard"... Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "networkfs"... Jul 10 19:47:17 volumio-tv volumio[1037]: info: Starting Udev Watcher for removable devices Jul 10 19:47:17 volumio-tv sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tomato,password=spray001,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.153/MUSICA_2.5 /mnt/NAS/MUSICA_2.5 Jul 10 19:47:17 volumio-tv sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:17 volumio-tv sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tomato,password=spray001,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.16/MUSICA_2.5 /mnt/NAS/MUSICA_2.5 Jul 10 19:47:17 volumio-tv sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:17 volumio-tv volumio[1037]: info: Ignoring mount for partition: boot Jul 10 19:47:17 volumio-tv volumio[1037]: info: Ignoring mount for partition: volumio Jul 10 19:47:17 volumio-tv volumio[1037]: info: Ignoring mount for partition: volumio_data Jul 10 19:47:17 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "volumio_command_line_client"... Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "upnp"... Jul 10 19:47:17 volumio-tv volumio[1037]: info: [1720633637585] Starting Upmpd Daemon Jul 10 19:47:17 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "my_music"... Jul 10 19:47:17 volumio-tv volumio[1037]: info: Loading plugin "mpd"... Jul 10 19:47:17 volumio-tv kernel: Key type cifs.spnego registered Jul 10 19:47:17 volumio-tv kernel: Key type cifs.idmap registered Jul 10 19:47:17 volumio-tv 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 10 19:47:17 volumio-tv kernel: CIFS: Attempting to mount \\192.168.1.16\MUSICA_2.5 Jul 10 19:47:17 volumio-tv sudo[1082]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:17 volumio-tv kernel: CIFS: Attempting to mount \\192.168.1.153\MUSICA_2.5 Jul 10 19:47:18 volumio-tv volumio[1037]: info: Loading plugin "upnp_browser"... Jul 10 19:47:18 volumio-tv systemd[1]: systemd-fsckd.service: Succeeded. Jul 10 19:47:18 volumio-tv volumio[1037]: info: Loading plugin "alarm-clock"... Jul 10 19:47:18 volumio-tv volumio[1037]: info: Loading plugin "airplay_emulation"... Jul 10 19:47:18 volumio-tv volumio[1037]: info: Starting Shairport Sync Jul 10 19:47:18 volumio-tv volumio[1037]: info: Loading plugin "last_100"... Jul 10 19:47:18 volumio-tv volumio[1037]: info: Loading plugin "webradio"... Jul 10 19:47:18 volumio-tv volumio[1037]: info: Loading plugin "i2s_dacs"... Jul 10 19:47:18 volumio-tv volumio[1037]: info: I2S DAC not set, start Auto-detection Jul 10 19:47:18 volumio-tv volumio[1037]: info: Loading plugin "volumiodiscovery"... Jul 10 19:47:19 volumio-tv volumio[1037]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 19:47:19 volumio-tv volumio[1037]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 19:47:19 volumio-tv volumio[1037]: *** WARNING *** For more information see Jul 10 19:47:19 volumio-tv volumio[1037]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 19:47:19 volumio-tv volumio[1037]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 19:47:19 volumio-tv node[1037]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 19:47:19 volumio-tv volumio[1037]: *** WARNING *** For more information see Jul 10 19:47:19 volumio-tv node[1037]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 19:47:19 volumio-tv node[1037]: *** WARNING *** For more information see Jul 10 19:47:19 volumio-tv node[1037]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 19:47:19 volumio-tv node[1037]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 19:47:19 volumio-tv node[1037]: *** WARNING *** For more information see Jul 10 19:47:19 volumio-tv volumio[1037]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 10 19:47:19 volumio-tv volumio[1037]: info: Discovery: Started advertising with name: Volumio TV Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 19:47:19 volumio-tv volumio[1037]: info: Loading plugin "outputs"... Jul 10 19:47:19 volumio-tv volumio[1037]: info: Loading plugin "albumart"... Jul 10 19:47:19 volumio-tv volumio[1037]: info: Plugin example_plugin is not enabled Jul 10 19:47:19 volumio-tv volumio[1037]: info: Loading plugin "inputs"... Jul 10 19:47:19 volumio-tv volumio[1037]: info: Loading plugin "updater_comm"... Jul 10 19:47:19 volumio-tv volumio[1037]: info: Plugin mpdemulation is not enabled Jul 10 19:47:19 volumio-tv volumio[1037]: info: Loading plugin "rest_api"... Jul 10 19:47:19 volumio-tv volumio[1037]: info: Loading plugin "websocket"... Jul 10 19:47:19 volumio-tv volumio[1037]: info: Starting Socket.io Server version 2.3.0 Jul 10 19:47:19 volumio-tv volumio[1037]: info: Loading i18n strings for locale it Jul 10 19:47:19 volumio-tv volumio[1037]: Updating browse sources language Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 19:47:19 volumio-tv volumio[1037]: Forking 3 albumart workers Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::initPlayerControls Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 19:47:19 volumio-tv volumio[1037]: Express server listening on port 3000 Jul 10 19:47:19 volumio-tv volumio[1037]: [Metrics] WebUI: 6s 292.42ms Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreStateMachine::resetVolumioState Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreStateMachine::getcurrentVolume Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 1 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 2 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 5 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 7 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 9 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 10 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 11 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 12 Jul 10 19:47:20 volumio-tv volumio[1037]: Starting albumart workers Jul 10 19:47:20 volumio-tv volumio[1037]: Starting albumart workers Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 13 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 14 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 15 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 16 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 17 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 18 Jul 10 19:47:20 volumio-tv volumio[1037]: Starting albumart workers Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 19 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 20 Jul 10 19:47:20 volumio-tv volumio[1037]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 21 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 22 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 23 Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 24 Jul 10 19:47:20 volumio-tv volumio[1037]: info: Reloading queue from file Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 19:47:20 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 25 Jul 10 19:47:20 volumio-tv volumio[1037]: info: VolumeController:: Volume=undefined Mute =false Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:20 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreStateMachine::updateTrackBlock Jul 10 19:47:20 volumio-tv volumio[1037]: info: CorePlayQueue::getTrackBlock Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 19:47:20 volumio-tv volumio[1037]: info: Setting Device type: Raspberry PI Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreStateMachine::setRepeat null single undefined Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:20 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreStateMachine::setRandom null Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:20 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:20 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 10 19:47:21 volumio-tv volumio[1037]: info: Received Get System Info Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 19:47:21 volumio-tv volumio[1037]: info: Discovery: Getting this device information Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: Listing playlists Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetQueue Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreStateMachine::getQueue Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getQueue Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: Received Get System Info Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 19:47:21 volumio-tv volumio[1037]: info: Discovery: Getting this device information Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 10 19:47:21 volumio-tv volumio[1037]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.40 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 26 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: VolumeController:: Volume=undefined Mute =false Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:21 volumio-tv sudo[1074]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:21 volumio-tv volumio[1037]: info: Discovery: adding f31beda2-e693-4199-b9dc-15ce42af4021 Jul 10 19:47:21 volumio-tv volumio[1037]: info: Discovery: Found device Volumio TV Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: Discovery: this is already registered, f31beda2-e693-4199-b9dc-15ce42af4021 Jul 10 19:47:21 volumio-tv volumio[1037]: info: Discovery: Found device Volumio TV Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:21 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:21 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:22 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:23 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:23 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:23 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:23 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:23 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:23 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:23 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:23 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:24 volumio-tv sudo[1080]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:24 volumio-tv volumio[1037]: info: Cannot mount NAS MUSICA_2.5 at system boot, trial number 1 ,retrying in 5 seconds Jul 10 19:47:24 volumio-tv volumio[1037]: info: Completed loading Core Plugins Jul 10 19:47:24 volumio-tv volumio[1037]: info: Preparing to generate the ALSA configuration file Jul 10 19:47:24 volumio-tv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 10 19:47:24 volumio-tv kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 10 19:47:24 volumio-tv volumio[1037]: info: Asound.conf file unchanged, so no further update is needed Jul 10 19:47:24 volumio-tv volumio[1037]: info: Output device has changed, restarting MPD Jul 10 19:47:24 volumio-tv volumio[1037]: info: Output device has changed, restarting Shairport Sync Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 19:47:24 volumio-tv sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 19:47:24 volumio-tv sudo[1179]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:24 volumio-tv sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 19:47:24 volumio-tv sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:24 volumio-tv volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 19:47:24 volumio-tv volumio[1037]: info: ___________ START PLUGINS ___________ Jul 10 19:47:24 volumio-tv volumio[1037]: info: ControllerMpd::onStart: Initializing MPD Jul 10 19:47:24 volumio-tv volumio[1037]: info: Creating MPD Configuration file Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 19:47:24 volumio-tv sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 19:47:24 volumio-tv sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 19:47:24 volumio-tv volumio[1037]: info: [1720633644324] CoreMusicLibrary::Adding element Server multimediali Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 19:47:24 volumio-tv sudo[1189]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:24 volumio-tv sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 19:47:24 volumio-tv sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:24 volumio-tv systemd[1]: Listening on mpd.socket. Jul 10 19:47:24 volumio-tv systemd[1]: Starting Music Player Daemon... Jul 10 19:47:24 volumio-tv systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 10 19:47:24 volumio-tv systemd[1]: mpd.service: Succeeded. Jul 10 19:47:24 volumio-tv systemd[1]: Stopped Music Player Daemon. Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 19:47:24 volumio-tv systemd[1]: Starting Music Player Daemon... Jul 10 19:47:24 volumio-tv volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 19:47:24 volumio-tv volumio[1037]: info: [1720633644469] CoreMusicLibrary::Adding element Last_100 Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 19:47:24 volumio-tv volumio[1037]: info: [1720633644473] CoreMusicLibrary::Adding element Webradio Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 19:47:24 volumio-tv volumio[1037]: info: Initializing BBC Radios Jul 10 19:47:24 volumio-tv sudo[1195]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 19:47:24 volumio-tv sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:24 volumio-tv sudo[1195]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 10 19:47:24 volumio-tv sudo[1195]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: Volumio Calling Home Jul 10 19:47:24 volumio-tv systemd[1]: systemd-hostnamed.service: Succeeded. Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:24 volumio-tv volumio[1037]: info: MPD Permissions set Jul 10 19:47:24 volumio-tv volumio[1037]: info: MPD Permissions set Jul 10 19:47:24 volumio-tv volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Jul 10 19:47:24 volumio-tv volumio[1037]: info: Volumio called home Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 19:47:24 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 19:47:25 volumio-tv volumio[1037]: info: Starting Shairport Sync Jul 10 19:47:25 volumio-tv volumio[1037]: info: Starting Shairport Sync Jul 10 19:47:25 volumio-tv volumio[1037]: info: Starting Shairport Sync Jul 10 19:47:25 volumio-tv sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 19:47:25 volumio-tv sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:25 volumio-tv sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 19:47:25 volumio-tv sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:25 volumio-tv sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 19:47:25 volumio-tv systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 19:47:25 volumio-tv sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 19:47:25 volumio-tv volumio[1037]: info: Discovery: Getting this device information Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:25 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 19:47:25 volumio-tv systemd[1]: shairport-sync.service: Succeeded. Jul 10 19:47:25 volumio-tv systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 19:47:25 volumio-tv volumio[1037]: info: Discovery: Getting this device information Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:25 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 10 19:47:25 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:25 volumio-tv systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:25 volumio-tv sudo[1228]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:25 volumio-tv volumio[1037]: info: Shairport-Sync Started Jul 10 19:47:25 volumio-tv volumio[1037]: Error adding Membership: Error: addMembership EINVAL Jul 10 19:47:25 volumio-tv sudo[1230]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:25 volumio-tv sudo[1233]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:25 volumio-tv volumio[1037]: info: Shairport-Sync Started Jul 10 19:47:25 volumio-tv volumio[1037]: info: Shairport-Sync Started Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetBrowseSources Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetBrowseSources Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioGetBrowseSources Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 10 19:47:25 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:26 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:26 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:27 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:27 volumio-tv mpd[1205]: Jul 10 19:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 19:47:27 volumio-tv sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 19:47:27 volumio-tv sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:27 volumio-tv sudo[1248]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:27 volumio-tv sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 19:47:27 volumio-tv sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:27 volumio-tv sudo[1250]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:27 volumio-tv sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 10 19:47:27 volumio-tv sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:27 volumio-tv systemd[1]: Started UPnP Renderer front-end to MPD. Jul 10 19:47:27 volumio-tv sudo[1254]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:27 volumio-tv volumio[1037]: info: Upmpdcli Daemon Started Jul 10 19:47:27 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:28 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:28 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:29 volumio-tv volumio[1037]: info: Cannot mount NAS MUSICA_2.5 at system boot, trial number 2 ,retrying in 5 seconds Jul 10 19:47:29 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:29 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 10 19:47:29 volumio-tv sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/MUSICA_2.5 Jul 10 19:47:29 volumio-tv sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 19:47:29 volumio-tv systemd[1]: mnt-NAS-MUSICA_2.5.mount: Succeeded. Jul 10 19:47:29 volumio-tv sudo[1258]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:29 volumio-tv volumio[1037]: info: Share MUSICA_2.5 successfully unmounted Jul 10 19:47:29 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 19:47:29 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:47:29] [connect] Successful connection Jul 10 19:47:29 volumio-tv volumio-remote-updater[548]: [2024-07-10 19:47:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720633649 101 Jul 10 19:47:29 volumio-tv volumio[1037]: 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: 28 Jul 10 19:47:29 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:30 volumio-tv systemd[1]: Started Music Player Daemon. Jul 10 19:47:30 volumio-tv sudo[1191]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:30 volumio-tv volumio[1037]: Upnp client error: Error: This socket has been ended by the other party Jul 10 19:47:30 volumio-tv sudo[1181]: pam_unix(sudo:session): session closed for user root Jul 10 19:47:30 volumio-tv volumio[1037]: info: Completed starting Core Plugins Jul 10 19:47:30 volumio-tv volumio[1037]: info: ------------------------------------------- Jul 10 19:47:30 volumio-tv volumio[1037]: info: ----- MyVolumio plugins startup ---- Jul 10 19:47:30 volumio-tv volumio[1037]: info: ------------------------------------------- Jul 10 19:47:30 volumio-tv volumio[1037]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 10 19:47:30 volumio-tv volumio[1037]: info: MPD running with PID1205 Jul 10 19:47:30 volumio-tv volumio[1037]: ,establishing connection Jul 10 19:47:30 volumio-tv volumio[1037]: error: MPD error: The expression evaluated to a falsy value: Jul 10 19:47:30 volumio-tv volumio[1037]: assert.ok(self.idling) Jul 10 19:47:30 volumio-tv volumio[1037]: error: The expression evaluated to a falsy value: Jul 10 19:47:30 volumio-tv volumio[1037]: assert.ok(self.idling) Jul 10 19:47:30 volumio-tv volumio[1037]: error: MPD error: The expression evaluated to a falsy value: Jul 10 19:47:30 volumio-tv volumio[1037]: assert.ok(self.idling) Jul 10 19:47:30 volumio-tv volumio[1037]: error: The expression evaluated to a falsy value: Jul 10 19:47:30 volumio-tv volumio[1037]: assert.ok(self.idling) Jul 10 19:47:30 volumio-tv volumio[1037]: error: updateQueue error: null Jul 10 19:47:30 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 19:47:30 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 10 19:47:31 volumio-tv volumio[1256]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Jul 10 19:47:32 volumio-tv volumio[1037]: info: Jul 10 19:47:32 volumio-tv volumio[1037]: ---------------------------- MPD announces state update: update Jul 10 19:47:32 volumio-tv volumio[1037]: info: ControllerMpd::getState Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Jul 10 19:47:32 volumio-tv volumio[1037]: info: Jul 10 19:47:32 volumio-tv volumio[1037]: ---------------------------- MPD announces state update: update Jul 10 19:47:32 volumio-tv volumio[1037]: info: ControllerMpd::getState Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Jul 10 19:47:32 volumio-tv volumio[1037]: info: Jul 10 19:47:32 volumio-tv volumio[1037]: ---------------------------- MPD announces state update: update Jul 10 19:47:32 volumio-tv volumio[1037]: info: ControllerMpd::getState Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Jul 10 19:47:32 volumio-tv volumio[1037]: info: sendMpdCommand status took 13 milliseconds Jul 10 19:47:32 volumio-tv volumio[1037]: info: sendMpdCommand status took 10 milliseconds Jul 10 19:47:32 volumio-tv volumio[1037]: info: sendMpdCommand status took 10 milliseconds Jul 10 19:47:32 volumio-tv volumio[1037]: info: sendMpdCommand status took 8 milliseconds Jul 10 19:47:32 volumio-tv volumio[1037]: info: sendMpdCommand status took 8 milliseconds Jul 10 19:47:32 volumio-tv volumio[1037]: info: sendMpdCommand status took 6 milliseconds Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::parseState Jul 10 19:47:32 volumio-tv volumio[1037]: info: Command Router : Notfying DB Updatetrue Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::parseState Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::parseState Jul 10 19:47:32 volumio-tv volumio[1037]: info: Command Router : Notfying DB Updatetrue Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::Close All Modals sent Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::parseState Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::parseState Jul 10 19:47:32 volumio-tv volumio[1037]: info: Command Router : Notfying DB Updatetrue Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::Close All Modals sent Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: ControllerMpd::parseState Jul 10 19:47:32 volumio-tv volumio[1037]: info: ControllerMpd::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::servicePushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: CURRENT POSITION 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::syncState stateService stop Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: No code Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: ControllerMpd::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::servicePushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: CURRENT POSITION 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::syncState stateService stop Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: No code Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: ControllerMpd::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::servicePushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 10 19:47:32 volumio-tv volumio[1037]: verbose: CURRENT POSITION 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::syncState stateService stop Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: No code Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreStateMachine::pushState Jul 10 19:47:32 volumio-tv volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 10 19:47:32 volumio-tv volumio[1037]: info: CoreCommandRouter::volumioPushState Jul 10 19:47:33 volumio-tv volumio[1037]: info: ------------------------------ 543ms Jul 10 19:47:33 volumio-tv volumio[1037]: info: ------------------------------ 542ms Jul 10 19:47:33 volumio-tv volumio[1037]: info: ------------------------------ 541ms Jul 10 19:47:33 volumio-tv volumio[1037]: info: [1720633653070] Database update started Jul 10 19:47:33 volumio-tv volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 19:47:34 volumio-tv volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 19:47:34 volumio-tv volumio[1037]: TypeError: Cannot read property 'replace' of undefined Jul 10 19:47:34 volumio-tv volumio[1037]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jul 10 19:47:34 volumio-tv volumio[1037]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jul 10 19:47:34 volumio-tv volumio[1037]: at listOnTimeout (internal/timers.js:554:17) Jul 10 19:47:34 volumio-tv volumio[1037]: at processTimers (internal/timers.js:497:7) Jul 10 19:47:34 volumio-tv volumio[1037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 19:47:34 volumio-tv sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 19:46 Jul 10 19:47:34 volumio-tv sudo[1303]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"