-- Logs begin at Wed 2024-02-14 03:54:57 CET, end at Wed 2024-02-14 03:56:06 CET. -- Feb 14 03:55:00 volumio-freebar kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Feb 14 03:55:00 volumio-freebar systemd-udevd[238]: Using default interface naming scheme 'v240'. Feb 14 03:55:00 volumio-freebar systemd-udevd[238]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 14 03:55:00 volumio-freebar kernel: Bluetooth: hci0: BCM: features 0x2f Feb 14 03:55:00 volumio-freebar kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Feb 14 03:55:00 volumio-freebar kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Feb 14 03:55:00 volumio-freebar kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Feb 14 03:55:00 volumio-freebar systemd-udevd[230]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 17. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in 7800 being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Created slice system-bthelper.slice. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in FUSE Control File System being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in Huge Pages File System being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 14 03:55:01 volumio-freebar systemd[1]: Mounting /boot... Feb 14 03:55:01 volumio-freebar systemd[1]: Started File System Check Daemon to report status. Feb 14 03:55:01 volumio-freebar systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 14 03:55:01 volumio-freebar systemd[1]: Mounted /boot. Feb 14 03:55:01 volumio-freebar systemd[1]: Reached target Local File Systems. Feb 14 03:55:01 volumio-freebar systemd[1]: Starting Create Volatile Files and Directories... Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Started ifup for wlan0. Feb 14 03:55:01 volumio-freebar systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 14 03:55:01 volumio-freebar systemd[1]: Started ifup for eth0. Feb 14 03:55:01 volumio-freebar systemd[1]: Starting Raise network interfaces... Feb 14 03:55:01 volumio-freebar systemd[1]: Starting Preprocess NFS configuration... Feb 14 03:55:01 volumio-freebar systemd[1]: Started Load/Save RF Kill Switch Status. Feb 14 03:55:01 volumio-freebar systemd[1]: plymouth-read-write.service: Succeeded. Feb 14 03:55:01 volumio-freebar systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 14 03:55:01 volumio-freebar systemd[1]: Received SIGRTMIN+20 from PID 241 (plymouthd). Feb 14 03:55:01 volumio-freebar systemd[1]: nfs-config.service: Succeeded. Feb 14 03:55:01 volumio-freebar systemd[1]: Started Preprocess NFS configuration. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 14 03:55:01 volumio-freebar systemd[1]: Reached target NFS client services. Feb 14 03:55:01 volumio-freebar systemd[1]: Started Create Volatile Files and Directories. Feb 14 03:55:01 volumio-freebar systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 14 03:55:01 volumio-freebar systemd[1]: Starting RPC bind portmap service... Feb 14 03:55:02 volumio-freebar systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 14 03:55:02 volumio-freebar systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Feb 14 03:55:02 volumio-freebar systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 14 03:55:02 volumio-freebar kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 03:55:02 volumio-freebar systemd[1]: Reached target System Initialization. Feb 14 03:55:02 volumio-freebar systemd[1]: Listening on triggerhappy.socket. Feb 14 03:55:02 volumio-freebar systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 14 03:55:02 volumio-freebar systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 14 03:55:02 volumio-freebar kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 14 03:55:02 volumio-freebar systemd[1]: Started Volumio Splash. Feb 14 03:55:02 volumio-freebar systemd[469]: volumiosplash.service: Failed to execute command: No such file or directory Feb 14 03:55:02 volumio-freebar systemd[469]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Feb 14 03:55:02 volumio-freebar systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 14 03:55:02 volumio-freebar systemd[1]: Reached target Sockets. Feb 14 03:55:02 volumio-freebar systemd[1]: Reached target Basic System. Feb 14 03:55:02 volumio-freebar systemd[1]: Starting triggerhappy global hotkey daemon... Feb 14 03:55:02 volumio-freebar systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 14 03:55:02 volumio-freebar systemd[1]: Started D-Bus System Message Bus. Feb 14 03:55:02 volumio-freebar systemd[1]: Started Volumio Log Rotation Service. Feb 14 03:55:02 volumio-freebar systemd[1]: Starting dhcpcd on all interfaces... Feb 14 03:55:02 volumio-freebar systemd[1]: Started Volumio Iptables Module. Feb 14 03:55:02 volumio-freebar thd[485]: Unable to parse trigger line: Feb 14 03:55:02 volumio-freebar systemd[1]: Starting WPA supplicant... Feb 14 03:55:02 volumio-freebar thd[485]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 14 03:55:02 volumio-freebar thd[485]: Unable to parse trigger line: Feb 14 03:55:02 volumio-freebar thd[485]: Found socket passed from systemd Feb 14 03:55:02 volumio-freebar systemd[1]: Started Manage Sound Card State (restore and store). Feb 14 03:55:02 volumio-freebar systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 14 03:55:02 volumio-freebar systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 14 03:55:02 volumio-freebar systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 14 03:55:02 volumio-freebar systemd[1]: Starting Save/Restore Sound Card State... Feb 14 03:55:02 volumio-freebar systemd[1]: Starting Wireless Services... Feb 14 03:55:02 volumio-freebar alsactl[493]: alsactl 1.1.8 daemon started Feb 14 03:55:02 volumio-freebar systemd[1]: Started volumio-remote-updater.service. Feb 14 03:55:02 volumio-freebar dhcpcd[489]: Not running dhcpcd because /etc/network/interfaces Feb 14 03:55:02 volumio-freebar dhcpcd[489]: defines some interfaces that will use a Feb 14 03:55:02 volumio-freebar dhcpcd[489]: DHCP client or static address Feb 14 03:55:02 volumio-freebar systemd[1]: Starting Login Service... Feb 14 03:55:02 volumio-freebar systemd[1]: Started UPnP Renderer front-end to MPD. Feb 14 03:55:02 volumio-freebar systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 14 03:55:02 volumio-freebar systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2024-04-09 11:27:56 CEST as it is in the future. Feb 14 03:55:02 volumio-freebar systemd[1]: Started Daily apt download activities. Feb 14 03:55:02 volumio-freebar systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2024-04-09 11:27:56 CEST as it is in the future. Feb 14 03:55:02 volumio-freebar systemd[1]: Started Daily apt upgrade and clean activities. Feb 14 03:55:02 volumio-freebar systemd[1]: Reached target Timers. Feb 14 03:55:02 volumio-freebar systemd[1]: Started triggerhappy global hotkey daemon. Feb 14 03:55:02 volumio-freebar systemd[1]: Started RPC bind portmap service. Feb 14 03:55:02 volumio-freebar systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Feb 14 03:55:02 volumio-freebar systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Feb 14 03:55:02 volumio-freebar systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Feb 14 03:55:02 volumio-freebar systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Feb 14 03:55:02 volumio-freebar systemd[1]: Failed to start dhcpcd on all interfaces. Feb 14 03:55:02 volumio-freebar systemd[1]: Started Save/Restore Sound Card State. Feb 14 03:55:02 volumio-freebar systemd[1]: Reached target Sound Card. Feb 14 03:55:02 volumio-freebar systemd[1]: Reached target RPC Port Mapper. Feb 14 03:55:02 volumio-freebar systemd[1]: Reached target Remote File Systems (Pre). Feb 14 03:55:02 volumio-freebar systemd[1]: Reached target Remote File Systems. Feb 14 03:55:02 volumio-freebar systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 14 03:55:02 volumio-freebar sh[389]: wlan0=wlan0 Feb 14 03:55:02 volumio-freebar sh[393]: eth0: waiting for carrier Feb 14 03:55:02 volumio-freebar dhcpcd[439]: eth0: waiting for carrier Feb 14 03:55:02 volumio-freebar kernel: lan78xx 1-1.1.1:1.0 eth0: kevent 4 may have been dropped Feb 14 03:55:02 volumio-freebar kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Feb 14 03:55:02 volumio-freebar kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Feb 14 03:55:02 volumio-freebar dhcpcd[439]: eth0: carrier acquired Feb 14 03:55:02 volumio-freebar sh[393]: eth0: carrier acquired Feb 14 03:55:02 volumio-freebar systemd-logind[503]: New seat seat0. Feb 14 03:55:03 volumio-freebar sh[393]: DUID 00:01:00:01:2c:c5:41:ce:b8:27:eb:65:6b:77 Feb 14 03:55:03 volumio-freebar sh[393]: eth0: IAID eb:30:3e:22 Feb 14 03:55:03 volumio-freebar dhcpcd[439]: DUID 00:01:00:01:2c:c5:41:ce:b8:27:eb:65:6b:77 Feb 14 03:55:03 volumio-freebar dhcpcd[439]: eth0: IAID eb:30:3e:22 Feb 14 03:55:03 volumio-freebar sh[393]: eth0: adding address fe80::96bd:7966:365:5e7d Feb 14 03:55:03 volumio-freebar dhcpcd[439]: eth0: adding address fe80::96bd:7966:365:5e7d Feb 14 03:55:03 volumio-freebar sh[393]: ipv6_addaddr1: Permission denied Feb 14 03:55:03 volumio-freebar dhcpcd[439]: ipv6_addaddr1: Permission denied Feb 14 03:55:03 volumio-freebar sh[393]: eth0: rebinding lease of 192.168.1.118 Feb 14 03:55:03 volumio-freebar dhcpcd[439]: eth0: rebinding lease of 192.168.1.118 Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: Successfully dropped root privileges. Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: avahi-daemon 0.7 starting up. Feb 14 03:55:03 volumio-freebar sh[393]: eth0: soliciting an IPv6 router Feb 14 03:55:03 volumio-freebar dhcpcd[439]: eth0: soliciting an IPv6 router Feb 14 03:55:03 volumio-freebar sh[393]: eth0: probing address 192.168.1.118/24 Feb 14 03:55:03 volumio-freebar dhcpcd[439]: eth0: probing address 192.168.1.118/24 Feb 14 03:55:03 volumio-freebar systemd[1]: Started Login Service. Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: Successfully called chroot(). Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: Successfully dropped remaining capabilities. Feb 14 03:55:03 volumio-freebar systemd[1]: hciuart.service: Succeeded. Feb 14 03:55:03 volumio-freebar systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: Loading service file /services/volumio.service. Feb 14 03:55:03 volumio-freebar wpa_supplicant[491]: Successfully initialized wpa_supplicant Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: Network interface enumeration completed. Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: Server startup complete. Host name is volumio-freebar.local. Local service cookie is 2128761388. Feb 14 03:55:03 volumio-freebar avahi-daemon[509]: Service "Volumio FreeBar" (/services/volumio.service) successfully established. Feb 14 03:55:03 volumio-freebar systemd[1]: Started Raise network interfaces. Feb 14 03:55:03 volumio-freebar systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 14 03:55:03 volumio-freebar systemd[1]: Started WPA supplicant. Feb 14 03:55:03 volumio-freebar systemd[1]: Reached target Network. Feb 14 03:55:03 volumio-freebar systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 14 03:55:03 volumio-freebar systemd[1]: Starting Permit User Sessions... Feb 14 03:55:03 volumio-freebar systemd[1]: Starting Network Time Service... Feb 14 03:55:03 volumio-freebar systemd[1]: Reached target Network is Online. Feb 14 03:55:04 volumio-freebar systemd[1]: Starting Samba NMB Daemon... Feb 14 03:55:04 volumio-freebar systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 14 03:55:04 volumio-freebar systemd[1]: Starting LSB: Brings up/down network automatically... Feb 14 03:55:04 volumio-freebar systemd[1]: Starting /etc/rc.local Compatibility... Feb 14 03:55:04 volumio-freebar systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 14 03:55:04 volumio-freebar systemd[1]: Started Permit User Sessions. Feb 14 03:55:04 volumio-freebar systemd[1]: Started /etc/rc.local Compatibility. Feb 14 03:55:04 volumio-freebar bthelper[596]: Raspberry Pi BDADDR already set Feb 14 03:55:04 volumio-freebar systemd[1]: Starting Hold until boot process finishes up... Feb 14 03:55:04 volumio-freebar systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 14 03:55:04 volumio-freebar volumio-remote-updater[500]: Error: No active session Feb 14 03:55:04 volumio-freebar systemd[1]: Started Raspberry Pi bluetooth helper. Feb 14 03:55:04 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:04] [info] asio async_connect error: system:111 (Connection refused) Feb 14 03:55:04 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 03:55:04 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:04] [error] handle_connect error: Underlying Transport Error Feb 14 03:55:04 volumio-freebar systemd[1]: Received SIGRTMIN+21 from PID 241 (plymouthd). Feb 14 03:55:04 volumio-freebar loadcpufreq[532]: Loading cpufreq kernel modules...done (none). Feb 14 03:55:04 volumio-freebar systemd[1]: plymouth-start.service: Succeeded. Feb 14 03:55:04 volumio-freebar systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 14 03:55:04 volumio-freebar volumio[506]: Could not open config: /tmp/upmpdcli.conf Feb 14 03:55:04 volumio-freebar systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 14 03:55:04 volumio-freebar systemd[1]: Started Hold until boot process finishes up. Feb 14 03:55:04 volumio-freebar ntpd[585]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 14 03:55:04 volumio-freebar ntpd[585]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 14 03:55:04 volumio-freebar ntpd[641]: proto: precision = 1.458 usec (-19) Feb 14 03:55:04 volumio-freebar systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 14 03:55:04 volumio-freebar systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 14 03:55:04 volumio-freebar systemd[1]: Started Network Time Service. Feb 14 03:55:04 volumio-freebar ifplugd(eth0)[657]: ifplugd 0.28 initializing. Feb 14 03:55:04 volumio-freebar ifplugd(eth0)[657]: Using interface eth0/B8:27:EB:30:3E:22 with driver (version: 6.1.69-v7+) Feb 14 03:55:04 volumio-freebar systemd[1]: plymouth-quit.service: Succeeded. Feb 14 03:55:04 volumio-freebar systemd[1]: Started Terminate Plymouth Boot Screen. Feb 14 03:55:04 volumio-freebar ifplugd(eth0)[657]: Using detection mode: SIOCETHTOOL Feb 14 03:55:04 volumio-freebar systemd[1]: iptables.service: Succeeded. Feb 14 03:55:04 volumio-freebar ifplugd(eth0)[657]: Initialization complete, link beat detected. Feb 14 03:55:04 volumio-freebar systemd[1]: Received SIGRTMIN+21 from PID 241 (n/a). Feb 14 03:55:04 volumio-freebar ntpd[641]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 14 03:55:04 volumio-freebar ntpd[641]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 14 03:55:04 volumio-freebar ntpd[641]: Listen and drop on 0 v6wildcard [::]:123 Feb 14 03:55:04 volumio-freebar ntpd[641]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 14 03:55:04 volumio-freebar ntpd[641]: Listen normally on 2 lo 127.0.0.1:123 Feb 14 03:55:04 volumio-freebar ntpd[641]: Listening on routing socket on fd #19 for interface updates Feb 14 03:55:04 volumio-freebar ntpd[641]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 03:55:04 volumio-freebar ntpd[641]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 03:55:04 volumio-freebar systemd[1]: Started Getty on tty1. Feb 14 03:55:04 volumio-freebar systemd[1]: Reached target Login Prompts. Feb 14 03:55:04 volumio-freebar systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 14 03:55:04 volumio-freebar systemd[1]: Starting Bluetooth service... Feb 14 03:55:04 volumio-freebar ifplugd(eth0)[657]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 14 03:55:05 volumio-freebar ifplugd(eth0)[657]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Feb 14 03:55:05 volumio-freebar cpufrequtils[661]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 14 03:55:05 volumio-freebar systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 14 03:55:05 volumio-freebar bluetoothd[662]: Bluetooth daemon 5.50 Feb 14 03:55:05 volumio-freebar bluetoothd[662]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 14 03:55:05 volumio-freebar systemd[1]: Started Bluetooth service. Feb 14 03:55:05 volumio-freebar systemd[1]: Reached target Bluetooth. Feb 14 03:55:05 volumio-freebar bluetoothd[662]: Starting SDP server Feb 14 03:55:05 volumio-freebar bluetoothd[662]: Excluding (cli) sap Feb 14 03:55:05 volumio-freebar haveged[444]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 14 03:55:05 volumio-freebar haveged[444]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Feb 14 03:55:05 volumio-freebar haveged[444]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00077 Feb 14 03:55:05 volumio-freebar haveged[444]: haveged: fills: 0, generated: 0 Feb 14 03:55:05 volumio-freebar bluetoothd[662]: Bluetooth management interface 1.22 initialized Feb 14 03:55:05 volumio-freebar dbus-daemon[486]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=662 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 14 03:55:05 volumio-freebar kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 14 03:55:05 volumio-freebar kernel: Bluetooth: BNEP filters: protocol multicast Feb 14 03:55:05 volumio-freebar kernel: Bluetooth: BNEP socket layer initialized Feb 14 03:55:05 volumio-freebar kernel: Bluetooth: MGMT ver 1.22 Feb 14 03:55:05 volumio-freebar bluetoothd[662]: Failed to set privacy: Rejected (0x0b) Feb 14 03:55:05 volumio-freebar systemd[1]: Starting Hostname Service... Feb 14 03:55:05 volumio-freebar dbus-daemon[486]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 14 03:55:05 volumio-freebar systemd[1]: Started Hostname Service. Feb 14 03:55:05 volumio-freebar ntpd[641]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 03:55:05 volumio-freebar nmbd[604]: [2024/02/14 03:55:05.865927, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 14 03:55:05 volumio-freebar nmbd[604]: started asyncdns process 687 Feb 14 03:55:05 volumio-freebar nmbd[604]: [2024/02/14 03:55:05.868209, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 14 03:55:05 volumio-freebar nmbd[604]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 14 03:55:05 volumio-freebar nmbd[604]: [2024/02/14 03:55:05.868420, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 14 03:55:05 volumio-freebar nmbd[604]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 14 03:55:06 volumio-freebar wireless.js[499]: Cleaning previous... Feb 14 03:55:06 volumio-freebar sudo[695]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 03:55:06 volumio-freebar sudo[695]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:06 volumio-freebar sudo[695]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:06 volumio-freebar sudo[697]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 03:55:06 volumio-freebar sudo[697]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:06 volumio-freebar ntpd[641]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 03:55:06 volumio-freebar systemd[1]: systemd-rfkill.service: Succeeded. Feb 14 03:55:06 volumio-freebar sudo[697]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:07 volumio-freebar wireless.js[499]: Stopped aP Feb 14 03:55:07 volumio-freebar sudo[704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 03:55:07 volumio-freebar sudo[704]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:07 volumio-freebar sudo[704]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:07 volumio-freebar kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 03:55:07 volumio-freebar sudo[706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 14 03:55:07 volumio-freebar sudo[706]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:07 volumio-freebar sudo[706]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:07 volumio-freebar sudo[713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 03:55:07 volumio-freebar sudo[713]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:07 volumio-freebar sudo[713]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:07 volumio-freebar sudo[715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 14 03:55:07 volumio-freebar sudo[715]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:07 volumio-freebar ntpd[641]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 03:55:08 volumio-freebar sh[393]: eth0: leased 192.168.1.118 for 43200 seconds Feb 14 03:55:08 volumio-freebar dhcpcd[439]: eth0: leased 192.168.1.118 for 43200 seconds Feb 14 03:55:08 volumio-freebar sh[393]: eth0: adding route to 192.168.1.0/24 Feb 14 03:55:08 volumio-freebar sh[393]: eth0: adding default route via 192.168.1.1 Feb 14 03:55:08 volumio-freebar sh[393]: eth0: dhcp_envoption 125.0/0: malformed embedded option Feb 14 03:55:08 volumio-freebar avahi-daemon[509]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.118. Feb 14 03:55:08 volumio-freebar dhcpcd[439]: eth0: adding route to 192.168.1.0/24 Feb 14 03:55:08 volumio-freebar avahi-daemon[509]: New relevant interface eth0.IPv4 for mDNS. Feb 14 03:55:08 volumio-freebar dhcpcd[439]: eth0: adding default route via 192.168.1.1 Feb 14 03:55:08 volumio-freebar avahi-daemon[509]: Registering new address record for 192.168.1.118 on eth0.IPv4. Feb 14 03:55:08 volumio-freebar dhcpcd[439]: eth0: dhcp_envoption 125.0/0: malformed embedded option Feb 14 03:55:08 volumio-freebar sh[393]: eth0: Invalid domain name: .home Feb 14 03:55:08 volumio-freebar dhcpcd-run-hooks[720]: eth0: Invalid domain name: .home Feb 14 03:55:08 volumio-freebar sh[393]: forked to background, child pid 740 Feb 14 03:55:08 volumio-freebar dhcpcd[439]: forked to background, child pid 740 Feb 14 03:55:08 volumio-freebar ifplugd(eth0)[657]: client: ifup: interface eth0 already configured Feb 14 03:55:08 volumio-freebar sh[393]: eth0=eth0 Feb 14 03:55:08 volumio-freebar ifplugd(eth0)[657]: Program executed successfully. Feb 14 03:55:08 volumio-freebar ifplugd[592]: Network Interface Plugging Daemon...start eth0...done. Feb 14 03:55:08 volumio-freebar systemd[1]: Started LSB: Brings up/down network automatically. Feb 14 03:55:08 volumio-freebar ntpd[641]: Soliciting pool server 178.215.228.24 Feb 14 03:55:09 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:09] [info] asio async_connect error: system:111 (Connection refused) Feb 14 03:55:09 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 03:55:09 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:09] [error] handle_connect error: Underlying Transport Error Feb 14 03:55:09 volumio-freebar bthelper[596]: Changing power off succeeded Feb 14 03:55:09 volumio-freebar ntpd[641]: Listen normally on 3 eth0 192.168.1.118:123 Feb 14 03:55:09 volumio-freebar ntpd[641]: new interface(s) found: waking up resolver Feb 14 03:55:09 volumio-freebar bthelper[596]: [CHG] Controller B8:27:EB:9A:94:88 Class: 0x0000041c Feb 14 03:55:09 volumio-freebar bthelper[596]: Changing power on succeeded Feb 14 03:55:09 volumio-freebar sudo[715]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:09 volumio-freebar wireless.js[499]: SETTING APPROPRIATE REG DOMAIN: FR Feb 14 03:55:09 volumio-freebar sudo[774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 03:55:09 volumio-freebar sudo[774]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:09 volumio-freebar sudo[774]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:09 volumio-freebar sudo[776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Feb 14 03:55:09 volumio-freebar sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:09 volumio-freebar sudo[776]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:09 volumio-freebar wireless.js[499]: SUCCESSFULLY SET NEW REGDOMAIN: FR Feb 14 03:55:09 volumio-freebar wireless.js[499]: Start wireless flow Feb 14 03:55:09 volumio-freebar wireless.js[499]: Stopped hotspot (if there).. Feb 14 03:55:09 volumio-freebar sudo[781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 03:55:09 volumio-freebar sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:09 volumio-freebar sudo[781]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:09 volumio-freebar sudo[783]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 03:55:09 volumio-freebar sudo[783]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:10 volumio-freebar sudo[783]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:10 volumio-freebar wireless.js[499]: DHCP IP Feb 14 03:55:10 volumio-freebar wireless.js[499]: Start ap Feb 14 03:55:10 volumio-freebar wpa_supplicant[785]: Successfully initialized wpa_supplicant Feb 14 03:55:10 volumio-freebar sudo[786]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 14 03:55:10 volumio-freebar sudo[786]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:10 volumio-freebar dhcpcd[787]: dev: loaded udev Feb 14 03:55:10 volumio-freebar kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 03:55:10 volumio-freebar dhcpcd-run-hooks[796]: wlan0: starting wpa_supplicant Feb 14 03:55:10 volumio-freebar dhcpcd-run-hooks[801]: wlan0: failed to start wpa_supplicant Feb 14 03:55:10 volumio-freebar dhcpcd-run-hooks[802]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Feb 14 03:55:10 volumio-freebar dhcpcd[787]: wlan0: connected to Access Point `' Feb 14 03:55:10 volumio-freebar nmbd[604]: [2024/02/14 03:55:10.873520, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 14 03:55:10 volumio-freebar nmbd[604]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 14 03:55:10 volumio-freebar systemd[1]: Started Samba NMB Daemon. Feb 14 03:55:10 volumio-freebar dhcpcd[787]: DUID 00:01:00:01:2c:c5:41:ce:b8:27:eb:65:6b:77 Feb 14 03:55:10 volumio-freebar dhcpcd[787]: eth0: IAID eb:30:3e:22 Feb 14 03:55:10 volumio-freebar dhcpcd[787]: eth0: adding address fe80::96bd:7966:365:5e7d Feb 14 03:55:10 volumio-freebar dhcpcd[787]: ipv6_addaddr1: Permission denied Feb 14 03:55:10 volumio-freebar dhcpcd[787]: wlan0: waiting for carrier Feb 14 03:55:10 volumio-freebar systemd[1]: Starting Samba Winbind Daemon... Feb 14 03:55:11 volumio-freebar dhcpcd[787]: eth0: soliciting an IPv6 router Feb 14 03:55:11 volumio-freebar winbindd[804]: [2024/02/14 03:55:11.258796, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 14 03:55:11 volumio-freebar winbindd[804]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 14 03:55:11 volumio-freebar winbindd[804]: [2024/02/14 03:55:11.286854, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 14 03:55:11 volumio-freebar winbindd[804]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 14 03:55:11 volumio-freebar systemd[1]: Started Samba Winbind Daemon. Feb 14 03:55:11 volumio-freebar systemd[1]: Starting Samba SMB Daemon... Feb 14 03:55:11 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:11 volumio-freebar sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:11 volumio-freebar sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:11 volumio-freebar sudo[826]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:11 volumio-freebar dhcpcd[787]: eth0: rebinding lease of 192.168.1.118 Feb 14 03:55:11 volumio-freebar dhcpcd[787]: eth0: leased 192.168.1.118 for 43200 seconds Feb 14 03:55:11 volumio-freebar dhcpcd[787]: eth0: adding route to 192.168.1.0/24 Feb 14 03:55:11 volumio-freebar dhcpcd[787]: eth0: adding default route via 192.168.1.1 Feb 14 03:55:11 volumio-freebar dhcpcd[787]: eth0: dhcp_envoption 125.0/0: malformed embedded option Feb 14 03:55:11 volumio-freebar dhcpcd[787]: eth0: dhcp_envoption 125.0/0: malformed embedded option Feb 14 03:55:11 volumio-freebar dhcpcd-run-hooks[830]: eth0: Invalid domain name: .home Feb 14 03:55:11 volumio-freebar dhcpcd[787]: forked to background, child pid 853 Feb 14 03:55:11 volumio-freebar winbindd[804]: [2024/02/14 03:55:11.787460, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Feb 14 03:55:11 volumio-freebar winbindd[804]: res_names->count = 1, expected 2 Feb 14 03:55:11 volumio-freebar smbd[824]: [2024/02/14 03:55:11.815004, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 14 03:55:11 volumio-freebar smbd[824]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 14 03:55:11 volumio-freebar systemd[1]: Started Samba SMB Daemon. Feb 14 03:55:11 volumio-freebar sudo[786]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:12 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:12 volumio-freebar sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:12 volumio-freebar sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:12 volumio-freebar sudo[858]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:13 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:13 volumio-freebar sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:13 volumio-freebar sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:13 volumio-freebar sudo[861]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:14 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:14 volumio-freebar sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:14 volumio-freebar sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:14 volumio-freebar sudo[864]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:15 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:15 volumio-freebar sudo[867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:15 volumio-freebar sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:15 volumio-freebar sudo[867]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:16 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:16 volumio-freebar sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:16 volumio-freebar sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:16 volumio-freebar sudo[870]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:17 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:17] [info] asio async_connect error: system:111 (Connection refused) Feb 14 03:55:17 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 03:55:17 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:17] [error] handle_connect error: Underlying Transport Error Feb 14 03:55:17 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:17 volumio-freebar sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:17 volumio-freebar sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:17 volumio-freebar sudo[873]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:18 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:18 volumio-freebar sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:18 volumio-freebar sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:18 volumio-freebar sudo[876]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:19 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:19 volumio-freebar sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:19 volumio-freebar sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:19 volumio-freebar sudo[879]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:20 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:20 volumio-freebar sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:20 volumio-freebar sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:20 volumio-freebar sudo[882]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:21 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:21 volumio-freebar sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:21 volumio-freebar sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:21 volumio-freebar sudo[885]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:22 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:22 volumio-freebar sudo[888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:22 volumio-freebar sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:22 volumio-freebar sudo[888]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:23 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:23 volumio-freebar sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:23 volumio-freebar sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:23 volumio-freebar sudo[891]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:24 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:24 volumio-freebar sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:24 volumio-freebar sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:24 volumio-freebar sudo[894]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:25 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:25 volumio-freebar sudo[897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:25 volumio-freebar sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:25 volumio-freebar sudo[897]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:26 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:26 volumio-freebar sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:26 volumio-freebar sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:26 volumio-freebar sudo[900]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:27 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:27] [info] asio async_connect error: system:111 (Connection refused) Feb 14 03:55:27 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 03:55:27 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:27] [error] handle_connect error: Underlying Transport Error Feb 14 03:55:27 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:27 volumio-freebar sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:27 volumio-freebar sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:27 volumio-freebar sudo[903]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:28 volumio-freebar wpa_supplicant[800]: wlan0: Failed to initiate sched scan Feb 14 03:55:28 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:28 volumio-freebar sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:28 volumio-freebar sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:28 volumio-freebar sudo[906]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:29 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:29 volumio-freebar sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:29 volumio-freebar sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:29 volumio-freebar sudo[909]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:30 volumio-freebar wireless.js[499]: trying... Feb 14 03:55:30 volumio-freebar sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 03:55:30 volumio-freebar sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:30 volumio-freebar sudo[912]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:31 volumio-freebar wireless.js[499]: Overtime, starting plan B Feb 14 03:55:31 volumio-freebar kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 03:55:31 volumio-freebar systemd[1]: Started Wireless Services. Feb 14 03:55:31 volumio-freebar systemd[1]: Started Volumio Backend Module. Feb 14 03:55:31 volumio-freebar systemd[1]: Started Volumio Cpu Tweaker. Feb 14 03:55:31 volumio-freebar systemd[1]: Reached target Multi-User System. Feb 14 03:55:31 volumio-freebar systemd[1]: Reached target Graphical Interface. Feb 14 03:55:31 volumio-freebar systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 14 03:55:31 volumio-freebar volumio-cpu-tweak[918]: Setting RT Priority for mpd Feb 14 03:55:31 volumio-freebar systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 14 03:55:31 volumio-freebar systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 14 03:55:31 volumio-freebar systemd[1]: Startup finished in 10.491s (kernel) + 36.689s (userspace) = 47.181s. Feb 14 03:55:31 volumio-freebar volumio-cpu-tweak[918]: pid 35's current scheduling policy: SCHED_OTHER Feb 14 03:55:31 volumio-freebar volumio-cpu-tweak[918]: pid 35's current scheduling priority: 0 Feb 14 03:55:31 volumio-freebar volumio-cpu-tweak[918]: Setting MPD Affinity Feb 14 03:55:31 volumio-freebar volumio-cpu-tweak[918]: pid 3's current affinity mask: f Feb 14 03:55:31 volumio-freebar volumio-cpu-tweak[918]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 14 03:55:31 volumio-freebar volumio-cpu-tweak[918]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 14 03:55:31 volumio-freebar systemd[1]: systemd-fsckd.service: Succeeded. Feb 14 03:55:31 volumio-freebar systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 14 03:55:34 volumio-freebar volumio[917]: info: ------------------------------------------- Feb 14 03:55:34 volumio-freebar volumio[917]: info: ----- Volumio3 ---- Feb 14 03:55:34 volumio-freebar volumio[917]: info: ------------------------------------------- Feb 14 03:55:34 volumio-freebar volumio[917]: info: ----- System startup ---- Feb 14 03:55:34 volumio-freebar volumio[917]: info: ------------------------------------------- Feb 14 03:55:35 volumio-freebar volumio[917]: info: MYVOLUMIO Environment detected Feb 14 03:55:35 volumio-freebar volumio[917]: info: Plugin folders cleanup Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning into folder /volumio/app/plugins/ Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning category audio_interface Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning category miscellanea Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning category music_service Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning category plugins.json Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning category system_controller Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning category user_interface Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning into folder /data/plugins/ Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning category music_service Feb 14 03:55:35 volumio-freebar volumio[917]: info: Scanning category user_interface Feb 14 03:55:35 volumio-freebar volumio[917]: info: Plugin folders cleanup completed Feb 14 03:55:35 volumio-freebar volumio[917]: info: ------------------------------------------- Feb 14 03:55:35 volumio-freebar volumio[917]: info: ----- Core plugins startup ---- Feb 14 03:55:35 volumio-freebar volumio[917]: info: ------------------------------------------- Feb 14 03:55:35 volumio-freebar volumio[917]: info: Loading plugins from folder /volumio/app/plugins/ Feb 14 03:55:35 volumio-freebar volumio[917]: info: Adding plugin upnp to MyMusic Plugins Feb 14 03:55:35 volumio-freebar volumio[917]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 14 03:55:35 volumio-freebar volumio[917]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 14 03:55:35 volumio-freebar volumio[917]: info: Loading plugins from folder /data/plugins/ Feb 14 03:55:35 volumio-freebar volumio[917]: info: Loading plugin "system"... Feb 14 03:55:35 volumio-freebar volumio[917]: info: Loading plugin "appearance"... Feb 14 03:55:35 volumio-freebar systemd[1]: systemd-hostnamed.service: Succeeded. Feb 14 03:55:36 volumio-freebar wpa_supplicant[800]: wlan0: Failed to initiate sched scan Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "network"... Feb 14 03:55:37 volumio-freebar volumio[917]: info: Refreshing Cached IP Addresses Feb 14 03:55:37 volumio-freebar sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 14 03:55:37 volumio-freebar sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:37 volumio-freebar sudo[948]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "services"... Feb 14 03:55:37 volumio-freebar sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 03:55:37 volumio-freebar sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "alsa_controller"... Feb 14 03:55:37 volumio-freebar sudo[950]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:37 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:37] [connect] Successful connection Feb 14 03:55:37 volumio-freebar sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 14 03:55:37 volumio-freebar sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:37 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "wizard"... Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "networkfs"... Feb 14 03:55:37 volumio-freebar volumio[917]: info: Starting Udev Watcher for removable devices Feb 14 03:55:37 volumio-freebar sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.2.156:/volume1/music /mnt/NAS/Diskstation Feb 14 03:55:37 volumio-freebar sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:37 volumio-freebar volumio[917]: info: Ignoring mount for partition: boot Feb 14 03:55:37 volumio-freebar volumio[917]: info: Ignoring mount for partition: volumio Feb 14 03:55:37 volumio-freebar volumio[917]: info: Ignoring mount for partition: volumio_data Feb 14 03:55:37 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "volumio_command_line_client"... Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "upnp"... Feb 14 03:55:37 volumio-freebar volumio[917]: info: [1707879337329] Starting Upmpd Daemon Feb 14 03:55:37 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "my_music"... Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "mpd"... Feb 14 03:55:37 volumio-freebar volumio[917]: info: Loading plugin "upnp_browser"... Feb 14 03:55:38 volumio-freebar volumio[917]: info: Loading plugin "alarm-clock"... Feb 14 03:55:38 volumio-freebar volumio[917]: info: Loading plugin "airplay_emulation"... Feb 14 03:55:38 volumio-freebar volumio[917]: info: Starting Shairport Sync Feb 14 03:55:38 volumio-freebar volumio[917]: info: Loading plugin "last_100"... Feb 14 03:55:38 volumio-freebar volumio[917]: info: Loading plugin "webradio"... Feb 14 03:55:38 volumio-freebar volumio[917]: info: Loading plugin "i2s_dacs"... Feb 14 03:55:38 volumio-freebar volumio[917]: info: Loading plugin "volumiodiscovery"... Feb 14 03:55:38 volumio-freebar volumio[917]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 14 03:55:38 volumio-freebar volumio[917]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 03:55:38 volumio-freebar volumio[917]: *** WARNING *** For more information see Feb 14 03:55:38 volumio-freebar node[917]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 14 03:55:38 volumio-freebar volumio[917]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 14 03:55:38 volumio-freebar volumio[917]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 03:55:38 volumio-freebar volumio[917]: *** WARNING *** For more information see Feb 14 03:55:38 volumio-freebar node[917]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 03:55:38 volumio-freebar node[917]: *** WARNING *** For more information see Feb 14 03:55:38 volumio-freebar node[917]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 14 03:55:38 volumio-freebar node[917]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 03:55:38 volumio-freebar node[917]: *** WARNING *** For more information see Feb 14 03:55:38 volumio-freebar volumio[917]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 14 03:55:38 volumio-freebar volumio[917]: info: Discovery: Started advertising with name: Volumio FreeBar Feb 14 03:55:38 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 03:55:38 volumio-freebar volumio[917]: info: Loading plugin "soundcloud"... Feb 14 03:55:39 volumio-freebar volumio[917]: info: Loading plugin "spop"... Feb 14 03:55:39 volumio-freebar sudo[953]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:40 volumio-freebar volumio[917]: info: Loading plugin "outputs"... Feb 14 03:55:40 volumio-freebar volumio[917]: info: Loading plugin "albumart"... Feb 14 03:55:40 volumio-freebar volumio[917]: info: Plugin example_plugin is not enabled Feb 14 03:55:40 volumio-freebar volumio[917]: info: Loading plugin "inputs"... Feb 14 03:55:40 volumio-freebar volumio[917]: info: Loading plugin "updater_comm"... Feb 14 03:55:41 volumio-freebar volumio[917]: info: Plugin mpdemulation is not enabled Feb 14 03:55:41 volumio-freebar volumio[917]: info: Loading plugin "rest_api"... Feb 14 03:55:41 volumio-freebar volumio[917]: info: Loading plugin "websocket"... Feb 14 03:55:41 volumio-freebar volumio[917]: info: Loading plugin "radio_paradise"... Feb 14 03:55:41 volumio-freebar volumio[917]: Forking 3 albumart workers Feb 14 03:55:41 volumio-freebar volumio[917]: info: Applying required configuration parameters for plugin radio_paradise Feb 14 03:55:41 volumio-freebar volumio[917]: info: [1707879341611] [RadioParadise] API delay: 5 Feb 14 03:55:41 volumio-freebar volumio[917]: info: Loading plugin "randomizer"... Feb 14 03:55:42 volumio-freebar volumio[917]: Starting albumart workers Feb 14 03:55:42 volumio-freebar volumio[917]: Starting albumart workers Feb 14 03:55:42 volumio-freebar volumio[917]: Starting albumart workers Feb 14 03:55:42 volumio-freebar volumio[917]: info: Applying required configuration parameters for plugin randomizer Feb 14 03:55:42 volumio-freebar volumio[917]: info: Loading i18n strings for locale en Feb 14 03:55:42 volumio-freebar volumio[917]: Updating browse sources language Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::initPlayerControls Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 03:55:42 volumio-freebar volumio[917]: Express server listening on port 3000 Feb 14 03:55:42 volumio-freebar volumio[917]: [Metrics] WebUI: 9s 598.24ms Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::resetVolumioState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::getcurrentVolume Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 14 03:55:43 volumio-freebar volumio[917]: info: Discovery: Getting this device information Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 03:55:43 volumio-freebar volumio[917]: info: Reloading queue from file Feb 14 03:55:43 volumio-freebar volumio[917]: info: VolumeController:: Volume=100 Mute =false Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::pushState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioPushState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::updateTrackBlock Feb 14 03:55:43 volumio-freebar volumio[917]: info: CorePlayQueue::getTrackBlock Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 03:55:43 volumio-freebar volumio[917]: info: Setting Device type: Raspberry PI Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::setRepeat null single undefined Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::pushState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioPushState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::setRandom null Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::pushState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioPushState Feb 14 03:55:43 volumio-freebar volumio[917]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.158 UA: okhttp/4.9.2 Total Clients: 1 Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 14 03:55:43 volumio-freebar volumio[917]: info: VolumeController:: Volume=100 Mute =false Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreStateMachine::pushState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioPushState Feb 14 03:55:43 volumio-freebar volumio[917]: info: Discovery: adding f09939b6-2be7-4dcb-bed8-521eb8926b65 Feb 14 03:55:43 volumio-freebar volumio[917]: info: Discovery: Found device Volumio FreeBar Feb 14 03:55:43 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:55:43 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:43 volumio-freebar wpa_supplicant[800]: wlan0: Failed to initiate sched scan Feb 14 03:55:46 volumio-freebar sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 14 03:55:46 volumio-freebar sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:46 volumio-freebar sudo[1035]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:46 volumio-freebar sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 03:55:46 volumio-freebar sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:46 volumio-freebar sudo[1037]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:46 volumio-freebar volumio[917]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.158 UA: okhttp/4.9.2 Total Clients: 2 Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetVisibleSources Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:55:46 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 14 03:55:46 volumio-freebar volumio[917]: info: Received Get System Info Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 14 03:55:46 volumio-freebar volumio[917]: info: Discovery: Getting this device information Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:55:46 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 14 03:55:46 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:55:46 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:47 volumio-freebar volumio[917]: info: Listing playlists Feb 14 03:55:47 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 14 03:55:47 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 14 03:55:47 volumio-freebar sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 14 03:55:47 volumio-freebar sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:47 volumio-freebar sudo[1041]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:47 volumio-freebar sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 03:55:47 volumio-freebar sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:47 volumio-freebar sudo[1043]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:47 volumio-freebar sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 14 03:55:47 volumio-freebar sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 03:55:47 volumio-freebar systemd[1]: Started UPnP Renderer front-end to MPD. Feb 14 03:55:47 volumio-freebar sudo[1047]: pam_unix(sudo:session): session closed for user root Feb 14 03:55:47 volumio-freebar volumio[917]: info: Upmpdcli Daemon Started Feb 14 03:55:47 volumio-freebar volumio[917]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 14 03:55:48 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 14 03:55:48 volumio-freebar volumio[917]: info: Received Get System Info Feb 14 03:55:48 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 14 03:55:48 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 14 03:55:48 volumio-freebar volumio[917]: info: Discovery: Getting this device information Feb 14 03:55:48 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:55:48 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:48 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 14 03:55:49 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 14 03:55:49 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 14 03:55:49 volumio-freebar volumio[917]: info: Received Get System Info Feb 14 03:55:49 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 14 03:55:49 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 14 03:55:49 volumio-freebar volumio[917]: info: Discovery: Getting this device information Feb 14 03:55:49 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:55:49 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:55:49 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 14 03:55:50 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 14 03:55:51 volumio-freebar wpa_supplicant[800]: wlan0: Failed to initiate sched scan Feb 14 03:55:51 volumio-freebar volumio[917]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 14 03:55:52 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:52] [connect] Successful connection Feb 14 03:55:52 volumio-freebar volumio-remote-updater[500]: [2024-02-14 03:55:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1707879352 101 Feb 14 03:55:52 volumio-freebar volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Feb 14 03:55:53 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetQueue Feb 14 03:55:53 volumio-freebar volumio[917]: info: CoreStateMachine::getQueue Feb 14 03:55:53 volumio-freebar volumio[917]: info: CorePlayQueue::getQueue Feb 14 03:55:56 volumio-freebar volumio[917]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jakob/64754fe5-e53e-47a2-a54f-a9813fdaf389.jpg' Feb 14 03:55:56 volumio-freebar volumio[917]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Riverside/796cc72e-df9b-406d-a0f3-ca484d532e85.jpg' Feb 14 03:55:57 volumio-freebar volumio[917]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 14 03:55:58 volumio-freebar wpa_supplicant[800]: wlan0: Failed to initiate sched scan Feb 14 03:56:03 volumio-freebar volumiologrotate[488]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 14 03:56:03 volumio-freebar volumiologrotate[488]: ls: cannot access 'FREEBAR': No such file or directory Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetBrowseSources Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetBrowseSources Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetBrowseSources Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 14 03:56:04 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 14 03:56:05 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 14 03:56:05 volumio-freebar volumio[917]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 14 03:56:05 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 14 03:56:05 volumio-freebar volumio[917]: info: Received Get System Version Feb 14 03:56:05 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 03:56:05 volumio-freebar volumio[917]: info: Received Get System Info Feb 14 03:56:05 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 14 03:56:05 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 14 03:56:05 volumio-freebar volumio[917]: info: Discovery: Getting this device information Feb 14 03:56:05 volumio-freebar volumio[917]: info: CoreCommandRouter::volumioGetState Feb 14 03:56:05 volumio-freebar volumio[917]: info: CorePlayQueue::getTrack 0 Feb 14 03:56:05 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 14 03:56:06 volumio-freebar volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 14 03:56:06 volumio-freebar volumio[917]: verbose: ControllerMpd::sendMpdCommand update Feb 14 03:56:06 volumio-freebar volumio[917]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 03:56:06 volumio-freebar volumio[917]: TypeError: Cannot read property 'then' of undefined Feb 14 03:56:06 volumio-freebar volumio[917]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Feb 14 03:56:06 volumio-freebar volumio[917]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) Feb 14 03:56:06 volumio-freebar volumio[917]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Feb 14 03:56:06 volumio-freebar volumio[917]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26) Feb 14 03:56:06 volumio-freebar volumio[917]: at Socket.emit (events.js:315:20) Feb 14 03:56:06 volumio-freebar volumio[917]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 14 03:56:06 volumio-freebar volumio[917]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 14 03:56:06 volumio-freebar volumio[917]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 03:56:06 volumio-freebar wpa_supplicant[800]: wlan0: Failed to initiate sched scan Feb 14 03:56:06 volumio-freebar sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-02-14 03:55 Feb 14 03:56:06 volumio-freebar sudo[1100]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"