-- Logs begin at Tue 2024-01-30 23:08:55 CST, end at Tue 2024-01-30 23:10:22 CST. -- Jan 30 23:09:00 famroom thd[659]: Unable to parse trigger line: Jan 30 23:09:00 famroom thd[659]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 30 23:09:00 famroom thd[659]: Unable to parse trigger line: Jan 30 23:09:00 famroom thd[659]: Unable to parse trigger line: Jan 30 23:09:00 famroom thd[659]: Unable to parse trigger line: Jan 30 23:09:00 famroom thd[659]: Unable to parse trigger line: Jan 30 23:09:00 famroom thd[659]: Unable to parse trigger line: Jan 30 23:09:00 famroom thd[659]: Found socket passed from systemd Jan 30 23:09:00 famroom systemd[1]: Started Save/Restore Sound Card State. Jan 30 23:09:00 famroom systemd-logind[642]: New seat seat0. Jan 30 23:09:00 famroom avahi-daemon[643]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 30 23:09:00 famroom systemd[1]: Started triggerhappy global hotkey daemon. Jan 30 23:09:00 famroom avahi-daemon[643]: Successfully dropped root privileges. Jan 30 23:09:00 famroom avahi-daemon[643]: avahi-daemon 0.7 starting up. Jan 30 23:09:00 famroom ifup[525]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jan 30 23:09:00 famroom sh[528]: wlan0=wlan0 Jan 30 23:09:00 famroom systemd[1]: Reached target Sound Card. Jan 30 23:09:00 famroom sh[526]: eth0=eth0 Jan 30 23:09:00 famroom systemd[1]: Reached target RPC Port Mapper. Jan 30 23:09:00 famroom systemd[1]: Reached target Remote File Systems (Pre). Jan 30 23:09:00 famroom systemd[1]: Reached target Remote File Systems. Jan 30 23:09:00 famroom systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 30 23:09:00 famroom kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 30 23:09:00 famroom dhcpcd[640]: forked to background, child pid 712 Jan 30 23:09:01 famroom dhcpcd-run-hooks[734]: wlan0: ctrl_interface not defined in /etc/wpa_supplicant/wpa_supplicant.conf Jan 30 23:09:01 famroom dhcpcd-run-hooks[735]: wlan0: not interacting with wpa_supplicant(8) Jan 30 23:09:01 famroom dhcpcd[712]: wlan0: connected to Access Point `' Jan 30 23:09:01 famroom systemd[1]: Started dhcpcd on all interfaces. Jan 30 23:09:01 famroom systemd[1]: hciuart.service: Succeeded. Jan 30 23:09:01 famroom systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 30 23:09:01 famroom avahi-daemon[643]: Successfully called chroot(). Jan 30 23:09:01 famroom avahi-daemon[643]: Successfully dropped remaining capabilities. Jan 30 23:09:01 famroom wpa_supplicant[647]: Successfully initialized wpa_supplicant Jan 30 23:09:01 famroom avahi-daemon[643]: Loading service file /services/volumio.service. Jan 30 23:09:01 famroom avahi-daemon[643]: Network interface enumeration completed. Jan 30 23:09:01 famroom avahi-daemon[643]: Server startup complete. Host name is famroom.local. Local service cookie is 3455867816. Jan 30 23:09:01 famroom avahi-daemon[643]: Service "FAMROOM" (/services/volumio.service) successfully established. Jan 30 23:09:01 famroom dhcpcd[712]: DUID 00:01:00:01:2d:4b:cc:68:b8:27:eb:c0:31:79 Jan 30 23:09:01 famroom dhcpcd[712]: eth0: IAID eb:c0:31:79 Jan 30 23:09:01 famroom dhcpcd[712]: eth0: adding address fe80::ba27:ebff:fec0:3179 Jan 30 23:09:01 famroom dhcpcd[712]: ipv6_addaddr1: Permission denied Jan 30 23:09:01 famroom dhcpcd[712]: eth0: probing address 192.168.68.209/24 Jan 30 23:09:01 famroom systemd[1]: Started Raise network interfaces. Jan 30 23:09:01 famroom dhcpcd[712]: wlan0: waiting for carrier Jan 30 23:09:01 famroom systemd[1]: Started Login Service. Jan 30 23:09:01 famroom systemd[1]: Started WPA supplicant. Jan 30 23:09:01 famroom volumio-remote-updater[655]: Error: No active session Jan 30 23:09:01 famroom systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 30 23:09:01 famroom volumio-remote-updater[655]: [2024-01-30 23:09:01] [info] asio async_connect error: system:111 (Connection refused) Jan 30 23:09:01 famroom volumio-remote-updater[655]: [2024-01-30 23:09:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 30 23:09:01 famroom volumio-remote-updater[655]: [2024-01-30 23:09:01] [error] handle_connect error: Underlying Transport Error Jan 30 23:09:01 famroom systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 30 23:09:01 famroom systemd[1]: Reached target Network. Jan 30 23:09:01 famroom systemd[1]: Starting Network Time Service... Jan 30 23:09:01 famroom systemd[1]: Reached target Network is Online. Jan 30 23:09:01 famroom systemd[1]: Starting Samba NMB Daemon... Jan 30 23:09:01 famroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 30 23:09:01 famroom systemd[1]: Starting LSB: Brings up/down network automatically... Jan 30 23:09:01 famroom volumio[648]: Could not open config: /tmp/upmpdcli.conf Jan 30 23:09:01 famroom systemd[1]: Starting Permit User Sessions... Jan 30 23:09:01 famroom systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 30 23:09:01 famroom systemd[1]: Starting /etc/rc.local Compatibility... Jan 30 23:09:01 famroom systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:01 famroom systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 30 23:09:01 famroom systemd[1]: Started Permit User Sessions. Jan 30 23:09:01 famroom systemd[1]: Started /etc/rc.local Compatibility. Jan 30 23:09:01 famroom bthelper[755]: Raspberry Pi BDADDR already set Jan 30 23:09:02 famroom systemd[1]: iptables.service: Succeeded. Jan 30 23:09:02 famroom dhcpcd[712]: eth0: soliciting an IPv6 router Jan 30 23:09:02 famroom systemd[1]: Started Raspberry Pi bluetooth helper. Jan 30 23:09:02 famroom systemd[1]: Starting Bluetooth service... Jan 30 23:09:02 famroom systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 30 23:09:02 famroom systemd[1]: Starting Hold until boot process finishes up... Jan 30 23:09:02 famroom loadcpufreq[698]: Loading cpufreq kernel modules...done (none). Jan 30 23:09:02 famroom systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 30 23:09:02 famroom systemd[1]: plymouth-quit.service: Succeeded. Jan 30 23:09:02 famroom systemd[1]: Started Terminate Plymouth Boot Screen. Jan 30 23:09:02 famroom systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 30 23:09:02 famroom systemd[1]: Started Hold until boot process finishes up. Jan 30 23:09:02 famroom systemd[1]: Received SIGRTMIN+21 from PID 211 (n/a). Jan 30 23:09:02 famroom systemd[1]: Received SIGRTMIN+21 from PID 211 (n/a). Jan 30 23:09:02 famroom ifplugd(eth0)[828]: ifplugd 0.28 initializing. Jan 30 23:09:02 famroom ifplugd(eth0)[828]: Using interface eth0/B8:27:EB:C0:31:79 with driver (version: 6.1.69-v7+) Jan 30 23:09:02 famroom systemd[1]: Started Getty on tty1. Jan 30 23:09:02 famroom ifplugd(eth0)[828]: Using detection mode: SIOCETHTOOL Jan 30 23:09:02 famroom systemd[1]: Reached target Login Prompts. Jan 30 23:09:02 famroom ifplugd(eth0)[828]: Initialization complete, link beat detected. Jan 30 23:09:02 famroom systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 30 23:09:02 famroom ntpd[762]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 30 23:09:02 famroom ntpd[762]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 30 23:09:02 famroom systemd[1]: Started Network Time Service. Jan 30 23:09:02 famroom haveged[583]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 30 23:09:02 famroom haveged[583]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 30 23:09:02 famroom haveged[583]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00034 Jan 30 23:09:02 famroom haveged[583]: haveged: fills: 0, generated: 0 Jan 30 23:09:02 famroom ntpd[831]: proto: precision = 1.406 usec (-19) Jan 30 23:09:02 famroom ifplugd(eth0)[828]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 30 23:09:02 famroom ntpd[831]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 30 23:09:02 famroom ntpd[831]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 30 23:09:02 famroom ntpd[831]: Listen and drop on 0 v6wildcard [::]:123 Jan 30 23:09:02 famroom ntpd[831]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 30 23:09:02 famroom ntpd[831]: Listen normally on 2 lo 127.0.0.1:123 Jan 30 23:09:02 famroom ntpd[831]: Listening on routing socket on fd #19 for interface updates Jan 30 23:09:02 famroom ntpd[831]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 30 23:09:02 famroom ntpd[831]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 30 23:09:02 famroom bluetoothd[797]: Bluetooth daemon 5.50 Jan 30 23:09:02 famroom ifplugd(eth0)[828]: client: ifup: interface eth0 already configured Jan 30 23:09:02 famroom cpufrequtils[829]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 30 23:09:02 famroom systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 30 23:09:02 famroom bluetoothd[797]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 30 23:09:02 famroom systemd[1]: Started Bluetooth service. Jan 30 23:09:02 famroom systemd[1]: Reached target Bluetooth. Jan 30 23:09:02 famroom bluetoothd[797]: Starting SDP server Jan 30 23:09:02 famroom bluetoothd[797]: Excluding (cli) sap Jan 30 23:09:02 famroom ifplugd(eth0)[828]: Program executed successfully. Jan 30 23:09:02 famroom ifplugd[767]: Network Interface Plugging Daemon...start eth0...done. Jan 30 23:09:02 famroom systemd[1]: Started LSB: Brings up/down network automatically. Jan 30 23:09:02 famroom kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 30 23:09:02 famroom kernel: Bluetooth: BNEP filters: protocol multicast Jan 30 23:09:02 famroom kernel: Bluetooth: BNEP socket layer initialized Jan 30 23:09:02 famroom dbus-daemon[645]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=797 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 30 23:09:02 famroom bluetoothd[797]: Bluetooth management interface 1.22 initialized Jan 30 23:09:02 famroom kernel: Bluetooth: MGMT ver 1.22 Jan 30 23:09:03 famroom bluetoothd[797]: Failed to set privacy: Rejected (0x0b) Jan 30 23:09:03 famroom systemd[1]: Starting Hostname Service... Jan 30 23:09:03 famroom dbus-daemon[645]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 30 23:09:03 famroom systemd[1]: Started Hostname Service. Jan 30 23:09:03 famroom ntpd[831]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 30 23:09:03 famroom nmbd[785]: [2024/01/30 23:09:03.559006, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 30 23:09:03 famroom nmbd[785]: started asyncdns process 858 Jan 30 23:09:03 famroom nmbd[785]: [2024/01/30 23:09:03.562014, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 30 23:09:03 famroom nmbd[785]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 30 23:09:03 famroom nmbd[785]: [2024/01/30 23:09:03.562234, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 30 23:09:03 famroom nmbd[785]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 30 23:09:03 famroom systemd[1]: systemd-rfkill.service: Succeeded. Jan 30 23:09:03 famroom wireless.js[651]: Cleaning previous... Jan 30 23:09:04 famroom sudo[866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 30 23:09:04 famroom sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:04 famroom sudo[866]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:04 famroom sudo[868]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 30 23:09:04 famroom sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:04 famroom ntpd[831]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 30 23:09:04 famroom sudo[868]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:04 famroom wireless.js[651]: Stopped aP Jan 30 23:09:04 famroom sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 30 23:09:04 famroom sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:04 famroom sudo[875]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:04 famroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 30 23:09:04 famroom sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 30 23:09:04 famroom sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:04 famroom sudo[877]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:04 famroom sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 30 23:09:04 famroom sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:04 famroom sudo[884]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:04 famroom sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 30 23:09:04 famroom sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:05 famroom ntpd[831]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 30 23:09:05 famroom sudo[886]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:05 famroom wireless.js[651]: SETTING APPROPRIATE REG DOMAIN: US Jan 30 23:09:05 famroom sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 30 23:09:05 famroom sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:05 famroom sudo[891]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:05 famroom sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Jan 30 23:09:05 famroom sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:05 famroom sudo[893]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:05 famroom wireless.js[651]: SUCCESSFULLY SET NEW REGDOMAIN: US Jan 30 23:09:05 famroom sudo[900]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 30 23:09:05 famroom sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:05 famroom sudo[900]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:05 famroom sudo[902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 30 23:09:05 famroom sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:06 famroom sudo[902]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:06 famroom wireless.js[651]: Hotspot is disabled, not starting it Jan 30 23:09:06 famroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 30 23:09:06 famroom systemd[1]: wireless.service: Succeeded. Jan 30 23:09:06 famroom systemd[1]: Started Wireless Services. Jan 30 23:09:06 famroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 30 23:09:06 famroom systemd[1]: Started Volumio Backend Module. Jan 30 23:09:06 famroom systemd[1]: Started Volumio Cpu Tweaker. Jan 30 23:09:06 famroom volumio-cpu-tweak[909]: Setting RT Priority for mpd Jan 30 23:09:06 famroom volumio-cpu-tweak[909]: pid 35's current scheduling policy: SCHED_OTHER Jan 30 23:09:06 famroom volumio-cpu-tweak[909]: pid 35's current scheduling priority: 0 Jan 30 23:09:06 famroom volumio-cpu-tweak[909]: Setting MPD Affinity Jan 30 23:09:06 famroom volumio-cpu-tweak[909]: pid 3's current affinity mask: f Jan 30 23:09:06 famroom volumio-cpu-tweak[909]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 30 23:09:06 famroom volumio-cpu-tweak[909]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 30 23:09:06 famroom ntpd[831]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 30 23:09:06 famroom systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 30 23:09:06 famroom dhcpcd[712]: eth0: using static address 192.168.68.209/24 Jan 30 23:09:06 famroom avahi-daemon[643]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.68.209. Jan 30 23:09:06 famroom avahi-daemon[643]: New relevant interface eth0.IPv4 for mDNS. Jan 30 23:09:06 famroom dhcpcd[712]: eth0: adding route to 192.168.68.0/24 Jan 30 23:09:06 famroom avahi-daemon[643]: Registering new address record for 192.168.68.209 on eth0.IPv4. Jan 30 23:09:06 famroom dhcpcd[712]: eth0: adding default route via 192.168.68.1 Jan 30 23:09:06 famroom volumio-remote-updater[655]: [2024-01-30 23:09:06] [info] asio async_connect error: system:111 (Connection refused) Jan 30 23:09:06 famroom volumio-remote-updater[655]: [2024-01-30 23:09:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 30 23:09:06 famroom volumio-remote-updater[655]: [2024-01-30 23:09:06] [error] handle_connect error: Underlying Transport Error Jan 30 23:09:07 famroom bthelper[755]: Changing power off succeeded Jan 30 23:09:07 famroom bthelper[755]: [CHG] Controller B8:27:EB:6A:9B:D3 Powered: no Jan 30 23:09:07 famroom bthelper[755]: [CHG] Controller B8:27:EB:6A:9B:D3 Discovering: no Jan 30 23:09:07 famroom bthelper[755]: [CHG] Controller B8:27:EB:6A:9B:D3 Class: 0x00000000 Jan 30 23:09:07 famroom bthelper[755]: [CHG] Controller B8:27:EB:6A:9B:D3 Class: 0x0000041c Jan 30 23:09:07 famroom bthelper[755]: Changing power on succeeded Jan 30 23:09:08 famroom ntpd[831]: Listen normally on 3 eth0 192.168.68.209:123 Jan 30 23:09:08 famroom ntpd[831]: new interface(s) found: waking up resolver Jan 30 23:09:08 famroom nmbd[785]: [2024/01/30 23:09:08.568109, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 30 23:09:08 famroom systemd[1]: Started Samba NMB Daemon. Jan 30 23:09:08 famroom nmbd[785]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 30 23:09:08 famroom systemd[1]: Starting Samba Winbind Daemon... Jan 30 23:09:09 famroom winbindd[951]: [2024/01/30 23:09:09.019682, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 30 23:09:09 famroom winbindd[951]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 30 23:09:09 famroom winbindd[951]: [2024/01/30 23:09:09.101747, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 30 23:09:09 famroom systemd[1]: Started Samba Winbind Daemon. Jan 30 23:09:09 famroom winbindd[951]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 30 23:09:09 famroom systemd[1]: Starting Samba SMB Daemon... Jan 30 23:09:09 famroom volumio[908]: info: ------------------------------------------- Jan 30 23:09:09 famroom volumio[908]: info: ----- Volumio3 ---- Jan 30 23:09:09 famroom volumio[908]: info: ------------------------------------------- Jan 30 23:09:09 famroom volumio[908]: info: ----- System startup ---- Jan 30 23:09:09 famroom volumio[908]: info: ------------------------------------------- Jan 30 23:09:09 famroom winbindd[951]: [2024/01/30 23:09:09.757986, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 30 23:09:09 famroom winbindd[951]: res_names->count = 3, expected 5 Jan 30 23:09:09 famroom systemd[1]: Started Samba SMB Daemon. Jan 30 23:09:09 famroom systemd[1]: Reached target Multi-User System. Jan 30 23:09:09 famroom systemd[1]: Reached target Graphical Interface. Jan 30 23:09:09 famroom smbd[964]: [2024/01/30 23:09:09.801652, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 30 23:09:09 famroom smbd[964]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 30 23:09:09 famroom systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 30 23:09:09 famroom systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 30 23:09:09 famroom systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 30 23:09:09 famroom systemd[1]: Startup finished in 15.623s (kernel) + 16.956s (userspace) = 32.579s. Jan 30 23:09:10 famroom volumio[908]: info: MYVOLUMIO Environment detected Jan 30 23:09:10 famroom volumio[908]: info: Plugin folders cleanup Jan 30 23:09:10 famroom volumio[908]: info: Scanning into folder /volumio/app/plugins/ Jan 30 23:09:10 famroom volumio[908]: info: Scanning category audio_interface Jan 30 23:09:10 famroom volumio[908]: info: Scanning category miscellanea Jan 30 23:09:10 famroom volumio[908]: info: Scanning category music_service Jan 30 23:09:10 famroom volumio[908]: info: Scanning category plugins.json Jan 30 23:09:10 famroom volumio[908]: info: Scanning category system_controller Jan 30 23:09:10 famroom volumio[908]: info: Scanning category user_interface Jan 30 23:09:10 famroom volumio[908]: info: Scanning into folder /data/plugins/ Jan 30 23:09:10 famroom volumio[908]: info: Scanning category music_service Jan 30 23:09:10 famroom volumio[908]: info: Plugin folders cleanup completed Jan 30 23:09:10 famroom volumio[908]: info: ------------------------------------------- Jan 30 23:09:10 famroom volumio[908]: info: ----- Core plugins startup ---- Jan 30 23:09:10 famroom volumio[908]: info: ------------------------------------------- Jan 30 23:09:10 famroom volumio[908]: info: Loading plugins from folder /volumio/app/plugins/ Jan 30 23:09:10 famroom volumio[908]: info: Adding plugin upnp to MyMusic Plugins Jan 30 23:09:10 famroom volumio[908]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 30 23:09:10 famroom volumio[908]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 30 23:09:10 famroom volumio[908]: info: Loading plugins from folder /data/plugins/ Jan 30 23:09:10 famroom volumio[908]: info: Loading plugin "system"... Jan 30 23:09:11 famroom volumio[908]: info: Loading plugin "appearance"... Jan 30 23:09:12 famroom volumio[908]: info: Loading plugin "network"... Jan 30 23:09:12 famroom volumio[908]: info: Refreshing Cached IP Addresses Jan 30 23:09:12 famroom sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 30 23:09:12 famroom sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:12 famroom volumio[908]: info: Loading plugin "services"... Jan 30 23:09:12 famroom sudo[979]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:12 famroom sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 30 23:09:12 famroom sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:12 famroom volumio[908]: info: Loading plugin "alsa_controller"... Jan 30 23:09:12 famroom sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 30 23:09:12 famroom sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:12 famroom sudo[981]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:12 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 30 23:09:12 famroom volumio[908]: info: Loading plugin "wizard"... Jan 30 23:09:12 famroom volumio[908]: info: Loading plugin "networkfs"... Jan 30 23:09:12 famroom volumio[908]: info: Starting Udev Watcher for removable devices Jan 30 23:09:12 famroom sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.200/music /mnt/NAS/NAS1 Jan 30 23:09:12 famroom sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:12 famroom sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=220904,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.202/music /mnt/NAS/NAS2 Jan 30 23:09:12 famroom sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:12 famroom volumio[908]: info: Ignoring mount for partition: boot Jan 30 23:09:12 famroom volumio[908]: info: Ignoring mount for partition: volumio Jan 30 23:09:12 famroom volumio[908]: info: Ignoring mount for partition: volumio_data Jan 30 23:09:12 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 30 23:09:12 famroom volumio[908]: info: Loading plugin "volumio_command_line_client"... Jan 30 23:09:12 famroom volumio[908]: info: Loading plugin "upnp"... Jan 30 23:09:12 famroom volumio[908]: info: [1706627352991] Starting Upmpd Daemon Jan 30 23:09:12 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 30 23:09:12 famroom volumio[908]: info: Loading plugin "my_music"... Jan 30 23:09:13 famroom volumio[908]: info: Loading plugin "mpd"... Jan 30 23:09:13 famroom kernel: Key type cifs.spnego registered Jan 30 23:09:13 famroom kernel: Key type cifs.idmap registered Jan 30 23:09:13 famroom 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. Jan 30 23:09:13 famroom kernel: CIFS: Attempting to mount \\192.168.68.202\music Jan 30 23:09:13 famroom sudo[984]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:13 famroom volumio[908]: info: Loading plugin "upnp_browser"... Jan 30 23:09:14 famroom volumio[908]: info: Loading plugin "alarm-clock"... Jan 30 23:09:14 famroom volumio-remote-updater[655]: [2024-01-30 23:09:14] [connect] Successful connection Jan 30 23:09:14 famroom volumio[908]: info: Loading plugin "airplay_emulation"... Jan 30 23:09:14 famroom volumio[908]: info: Starting Shairport Sync Jan 30 23:09:14 famroom volumio[908]: info: Loading plugin "last_100"... Jan 30 23:09:14 famroom volumio[908]: info: Loading plugin "webradio"... Jan 30 23:09:14 famroom volumio[908]: info: Loading plugin "i2s_dacs"... Jan 30 23:09:14 famroom volumio[908]: info: I2S DAC not set, start Auto-detection Jan 30 23:09:14 famroom volumio[908]: info: Loading plugin "volumiodiscovery"... Jan 30 23:09:14 famroom volumio[908]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 30 23:09:14 famroom volumio[908]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 30 23:09:14 famroom volumio[908]: *** WARNING *** For more information see Jan 30 23:09:14 famroom volumio[908]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 30 23:09:14 famroom node[908]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 30 23:09:14 famroom volumio[908]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 30 23:09:14 famroom volumio[908]: *** WARNING *** For more information see Jan 30 23:09:14 famroom node[908]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 30 23:09:14 famroom node[908]: *** WARNING *** For more information see Jan 30 23:09:14 famroom node[908]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 30 23:09:14 famroom node[908]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 30 23:09:14 famroom node[908]: *** WARNING *** For more information see Jan 30 23:09:14 famroom volumio[908]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 30 23:09:14 famroom volumio[908]: info: Discovery: Started advertising with name: FAMROOM Jan 30 23:09:14 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 30 23:09:14 famroom volumio[908]: info: Loading plugin "spop"... Jan 30 23:09:16 famroom volumio[908]: info: Plugin youtube2 is not enabled Jan 30 23:09:16 famroom volumio[908]: info: Plugin ytcr is not enabled Jan 30 23:09:16 famroom volumio[908]: info: Loading plugin "outputs"... Jan 30 23:09:16 famroom volumio[908]: info: Loading plugin "albumart"... Jan 30 23:09:16 famroom volumio[908]: info: Plugin example_plugin is not enabled Jan 30 23:09:16 famroom volumio[908]: info: Loading plugin "inputs"... Jan 30 23:09:16 famroom volumio[908]: info: Loading plugin "updater_comm"... Jan 30 23:09:16 famroom volumio[908]: info: Plugin mpdemulation is not enabled Jan 30 23:09:16 famroom volumio[908]: info: Loading plugin "rest_api"... Jan 30 23:09:16 famroom volumio[908]: info: Loading plugin "websocket"... Jan 30 23:09:16 famroom volumio[908]: info: Starting Socket.io Server version 2.3.0 Jan 30 23:09:16 famroom volumio[908]: info: Loading i18n strings for locale en Jan 30 23:09:16 famroom volumio[908]: Updating browse sources language Jan 30 23:09:16 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 30 23:09:16 famroom volumio[908]: Forking 3 albumart workers Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::initPlayerControls Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 30 23:09:17 famroom volumio[908]: Express server listening on port 3000 Jan 30 23:09:17 famroom volumio[908]: [Metrics] WebUI: 8s 953.31ms Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::resetVolumioState Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::getcurrentVolume Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:17 famroom volumio-remote-updater[655]: [2024-01-30 23:09:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1706627354 101 Jan 30 23:09:17 famroom volumio[908]: 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: 1 Jan 30 23:09:17 famroom volumio[908]: info: Reloading queue from file Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::setRepeat null single undefined Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::pushState Jan 30 23:09:17 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::volumioPushState Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::setRandom null Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::pushState Jan 30 23:09:17 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::volumioPushState Jan 30 23:09:17 famroom volumio[908]: info: Setting Device type: Raspberry PI Jan 30 23:09:17 famroom volumio[908]: info: VolumeController:: Volume=86 Mute =false Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::pushState Jan 30 23:09:17 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::volumioPushState Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::updateTrackBlock Jan 30 23:09:17 famroom volumio[908]: info: CorePlayQueue::getTrackBlock Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Jan 30 23:09:17 famroom volumio[908]: info: VolumeController:: Volume=86 Mute =false Jan 30 23:09:17 famroom volumio[908]: info: CoreStateMachine::pushState Jan 30 23:09:17 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::volumioPushState Jan 30 23:09:17 famroom volumio[908]: info: Discovery: adding 917c7a62-7189-4b82-9317-94881b7d135e Jan 30 23:09:17 famroom volumio[908]: info: Discovery: Found device FAMROOM Jan 30 23:09:17 famroom volumio[908]: info: CoreCommandRouter::volumioGetState Jan 30 23:09:17 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:09:18 famroom volumio[908]: Starting albumart workers Jan 30 23:09:18 famroom volumio[908]: Starting albumart workers Jan 30 23:09:18 famroom volumio[908]: Starting albumart workers Jan 30 23:09:19 famroom sudo[994]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:19 famroom volumio[908]: info: Cannot mount NAS NAS2 at system boot, trial number 1 ,retrying in 5 seconds Jan 30 23:09:19 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:09:19 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:09:19 famroom kernel: CIFS: Attempting to mount \\192.168.68.200\music Jan 30 23:09:23 famroom sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 30 23:09:23 famroom sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:23 famroom sudo[1079]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:23 famroom sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 30 23:09:23 famroom sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:23 famroom sudo[1081]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:23 famroom sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 30 23:09:23 famroom sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:23 famroom systemd[1]: Started UPnP Renderer front-end to MPD. Jan 30 23:09:23 famroom sudo[1085]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:23 famroom volumio[908]: info: Upmpdcli Daemon Started Jan 30 23:09:23 famroom volumio[908]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jan 30 23:09:24 famroom sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=220904,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.202/music /mnt/NAS/NAS2 Jan 30 23:09:24 famroom sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:25 famroom sudo[992]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:25 famroom volumio[908]: info: Cannot mount NAS NAS1 at system boot, trial number 1 ,retrying in 5 seconds Jan 30 23:09:25 famroom volumio[908]: info: Completed loading Core Plugins Jan 30 23:09:25 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:09:25 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:09:25 famroom kernel: CIFS: Attempting to mount \\192.168.68.202\music Jan 30 23:09:25 famroom volumio[908]: info: Preparing to generate the ALSA configuration file Jan 30 23:09:25 famroom volumio[908]: info: Asound.conf file written Jan 30 23:09:25 famroom sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 30 23:09:25 famroom sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:25 famroom sudo[1094]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:25 famroom volumio[908]: info: Output device has changed, restarting MPD Jan 30 23:09:25 famroom volumio[908]: info: Output device has changed, restarting Shairport Sync Jan 30 23:09:25 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:25 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:25 famroom sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 30 23:09:25 famroom sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:25 famroom sudo[1099]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:25 famroom sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 30 23:09:25 famroom sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:25 famroom volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 30 23:09:25 famroom volumio[908]: info: ___________ START PLUGINS ___________ Jan 30 23:09:25 famroom volumio[908]: info: ControllerMpd::onStart: Initializing MPD Jan 30 23:09:25 famroom volumio[908]: info: Creating MPD Configuration file Jan 30 23:09:25 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 30 23:09:25 famroom systemd[1]: Listening on mpd.socket. Jan 30 23:09:25 famroom sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 30 23:09:25 famroom sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:25 famroom systemd[1]: Starting Music Player Daemon... Jan 30 23:09:25 famroom volumio[908]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 30 23:09:25 famroom sudo[1109]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:25 famroom volumio[908]: info: [1706627365917] CoreMusicLibrary::Adding element Media Servers Jan 30 23:09:25 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 30 23:09:25 famroom sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 30 23:09:25 famroom sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:25 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:25 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:25 famroom systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 30 23:09:25 famroom systemd[1]: mpd.service: Succeeded. Jan 30 23:09:25 famroom systemd[1]: Stopped Music Player Daemon. Jan 30 23:09:25 famroom systemd[1]: Starting Music Player Daemon... Jan 30 23:09:26 famroom volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 30 23:09:26 famroom volumio[908]: info: [1706627366059] CoreMusicLibrary::Adding element Last_100 Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 30 23:09:26 famroom volumio[908]: info: [1706627366063] CoreMusicLibrary::Adding element Webradio Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 30 23:09:26 famroom volumio[908]: info: Initializing BBC Radios Jan 30 23:09:26 famroom sudo[1116]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 30 23:09:26 famroom sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:26 famroom sudo[1116]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 30 23:09:26 famroom sudo[1116]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: Creating Spotify config file Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: Volumio Calling Home Jan 30 23:09:26 famroom volumio[908]: info: MPD Permissions set Jan 30 23:09:26 famroom volumio[908]: info: MPD Permissions set Jan 30 23:09:26 famroom volumio[908]: info: Spotify config file written Jan 30 23:09:26 famroom sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 30 23:09:26 famroom sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:26 famroom volumio[908]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:26 famroom volumio[908]: info: No need to fix Spotify hosts Jan 30 23:09:26 famroom volumio[908]: info: Starting Shairport Sync Jan 30 23:09:26 famroom volumio[908]: info: Starting Shairport Sync Jan 30 23:09:26 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:26 famroom go-librespot[1144]: Librespot-go daemon starting... Jan 30 23:09:26 famroom volumio[908]: info: Starting Shairport Sync Jan 30 23:09:26 famroom sudo[1138]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:26 famroom sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 30 23:09:26 famroom sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 30 23:09:26 famroom sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:26 famroom sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 30 23:09:26 famroom sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:26 famroom volumio[908]: info: CoreCommandRouter::volumioGetState Jan 30 23:09:26 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:09:26 famroom sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:26 famroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 30 23:09:26 famroom systemd[1]: shairport-sync.service: Succeeded. Jan 30 23:09:26 famroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 30 23:09:26 famroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 30 23:09:26 famroom sudo[1153]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:26 famroom sudo[1150]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:26 famroom sudo[1155]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:26 famroom volumio[908]: info: Shairport-Sync Started Jan 30 23:09:26 famroom volumio[908]: Error adding Membership: Error: addMembership EINVAL Jan 30 23:09:26 famroom volumio[908]: info: Shairport-Sync Started Jan 30 23:09:26 famroom volumio[908]: info: Shairport-Sync Started Jan 30 23:09:26 famroom volumio[908]: info: Volumio called home Jan 30 23:09:27 famroom go-librespot[1144]: time="2024-01-30T23:09:27+08:00" level=info msg="generated new device id: ec0a8f46582e45b5c2a94945a3a46d4aa8a6ea08" Jan 30 23:09:27 famroom go-librespot[1144]: time="2024-01-30T23:09:27+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:27+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:27 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:27 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:29 famroom systemd[1]: systemd-fsckd.service: Succeeded. Jan 30 23:09:29 famroom mpd[1125]: Jan 30 23:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 30 23:09:29 famroom volumio[908]: info: go-librespot daemon successfully initialized Jan 30 23:09:29 famroom systemd[1]: Started Music Player Daemon. Jan 30 23:09:29 famroom volumio[908]: Upnp client error: Error: This socket has been ended by the other party Jan 30 23:09:29 famroom sudo[1111]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:29 famroom sudo[1101]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:29 famroom volumio[908]: info: Completed starting Core Plugins Jan 30 23:09:29 famroom volumio[908]: info: ------------------------------------------- Jan 30 23:09:29 famroom volumio[908]: info: ----- MyVolumio plugins startup ---- Jan 30 23:09:29 famroom volumio[908]: info: ------------------------------------------- Jan 30 23:09:29 famroom volumio[908]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 30 23:09:29 famroom volumio[908]: error: MPD error: The expression evaluated to a falsy value: Jan 30 23:09:29 famroom volumio[908]: assert.ok(self.idling) Jan 30 23:09:29 famroom volumio[908]: error: The expression evaluated to a falsy value: Jan 30 23:09:29 famroom volumio[908]: assert.ok(self.idling) Jan 30 23:09:29 famroom volumio[908]: info: MPD running with PID1125 Jan 30 23:09:29 famroom volumio[908]: ,establishing connection Jan 30 23:09:29 famroom volumio[908]: error: MPD error: The expression evaluated to a falsy value: Jan 30 23:09:29 famroom volumio[908]: assert.ok(self.idling) Jan 30 23:09:29 famroom volumio[908]: error: The expression evaluated to a falsy value: Jan 30 23:09:29 famroom volumio[908]: assert.ok(self.idling) Jan 30 23:09:29 famroom volumio[908]: error: updateQueue error: null Jan 30 23:09:30 famroom sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.200/music /mnt/NAS/NAS1 Jan 30 23:09:30 famroom sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:30 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:30 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 30 23:09:30 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:30 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:30 famroom go-librespot[1177]: Librespot-go daemon starting... Jan 30 23:09:31 famroom go-librespot[1177]: time="2024-01-30T23:09:31+08:00" level=info msg="generated new device id: 84ac386cb38b96e8e67b540c6b0e7c31300f2760" Jan 30 23:09:31 famroom go-librespot[1177]: time="2024-01-30T23:09:31+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:31+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:31 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:31 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:31 famroom sudo[1089]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:31 famroom volumio[908]: info: Cannot mount NAS NAS2 at system boot, trial number 2 ,retrying in 5 seconds Jan 30 23:09:31 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:09:31 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:09:31 famroom kernel: CIFS: Attempting to mount \\192.168.68.200\music Jan 30 23:09:32 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:32 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:33 famroom volumio[1087]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 30 23:09:33 famroom systemd[1]: systemd-hostnamed.service: Succeeded. Jan 30 23:09:34 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:34 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 30 23:09:34 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:34 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:34 famroom go-librespot[1202]: Librespot-go daemon starting... Jan 30 23:09:34 famroom go-librespot[1202]: time="2024-01-30T23:09:34+08:00" level=info msg="generated new device id: 3bd3c922a15e79ff03c81f6b29d43109f2959065" Jan 30 23:09:34 famroom go-librespot[1202]: time="2024-01-30T23:09:34+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:34+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:34 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:34 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:35 famroom volumio[908]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 30 23:09:35 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:36 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:36 famroom volumio[1087]: .......................++++ Jan 30 23:09:36 famroom sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=220904,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.202/music /mnt/NAS/NAS2 Jan 30 23:09:36 famroom sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:37 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:37 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 30 23:09:37 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:38 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:38 famroom go-librespot[1215]: Librespot-go daemon starting... Jan 30 23:09:38 famroom go-librespot[1215]: time="2024-01-30T23:09:38+08:00" level=info msg="generated new device id: d4430d15a751a419300b3348d97a609a291939fb" Jan 30 23:09:38 famroom sudo[1173]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:38 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:09:38 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:09:38 famroom kernel: CIFS: Attempting to mount \\192.168.68.202\music Jan 30 23:09:38 famroom volumio[908]: info: Cannot mount NAS NAS1 at system boot, trial number 2 ,retrying in 5 seconds Jan 30 23:09:38 famroom go-librespot[1215]: time="2024-01-30T23:09:38+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:38+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:38 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:38 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:39 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:39 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:41 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:41 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jan 30 23:09:41 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:41 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:41 famroom go-librespot[1223]: Librespot-go daemon starting... Jan 30 23:09:41 famroom go-librespot[1223]: time="2024-01-30T23:09:41+08:00" level=info msg="generated new device id: cdb42cb63cb016a80c993427c8868a46cd478f49" Jan 30 23:09:41 famroom go-librespot[1223]: time="2024-01-30T23:09:41+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:41+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:41 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:41 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:42 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:42 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:42 famroom volumio[1087]: ..........................................++++ Jan 30 23:09:42 famroom volumio[1087]: e is 65537 (0x010001) Jan 30 23:09:42 famroom volumio[1087]: writing RSA key Jan 30 23:09:43 famroom sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.200/music /mnt/NAS/NAS1 Jan 30 23:09:43 famroom sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:44 famroom sudo[1211]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:44 famroom volumio[908]: info: Cannot mount NAS NAS2 at system boot, trial number 3 ,retrying in 5 seconds Jan 30 23:09:44 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:09:44 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:09:44 famroom kernel: CIFS: Attempting to mount \\192.168.68.200\music Jan 30 23:09:44 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:44 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jan 30 23:09:44 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:44 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:44 famroom go-librespot[1251]: Librespot-go daemon starting... Jan 30 23:09:45 famroom go-librespot[1251]: time="2024-01-30T23:09:45+08:00" level=info msg="generated new device id: 68340b78bf6bd3101e4a3e97427d0c34cfa9cfcf" Jan 30 23:09:45 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:45 famroom go-librespot[1251]: time="2024-01-30T23:09:45+08:00" level=debug msg="new websocket client" Jan 30 23:09:45 famroom volumio[908]: info: Connection to go-librespot Websocket established Jan 30 23:09:45 famroom go-librespot[1251]: time="2024-01-30T23:09:45+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:45+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:45 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:45 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:45 famroom volumio[908]: info: Connection to go-librespot Websocket closed Jan 30 23:09:48 famroom volumio[908]: info: Getting Spotify volume Jan 30 23:09:48 famroom volumio[908]: (node:908) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:48 famroom volumio[908]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 30 23:09:48 famroom volumio[908]: (Use `node --trace-warnings ...` to show where the warning was created) Jan 30 23:09:48 famroom volumio[908]: (node:908) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Jan 30 23:09:48 famroom volumio[908]: (node:908) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jan 30 23:09:48 famroom volumio[908]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 30 23:09:48 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:48 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:48 famroom volumio[908]: info: CoreCommandRouter::volumioGetState Jan 30 23:09:48 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:09:48 famroom volumio[908]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 30 23:09:48 famroom volumio[908]: SPOTIFY: SPOTIFY VOLUME undefined Jan 30 23:09:48 famroom volumio[908]: SPOTIFY: VOLUMIO VOLUME 86 Jan 30 23:09:48 famroom volumio[908]: info: Aligning Spotify Volume to Volumio Volume Jan 30 23:09:48 famroom volumio[908]: info: CoreCommandRouter::volumioGetState Jan 30 23:09:48 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:09:48 famroom volumio[908]: info: Setting Spotify Volume from Volumio: 86 Jan 30 23:09:48 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:48 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jan 30 23:09:48 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:48 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:48 famroom go-librespot[1260]: Librespot-go daemon starting... Jan 30 23:09:48 famroom go-librespot[1260]: time="2024-01-30T23:09:48+08:00" level=info msg="generated new device id: a638ffd6d81dc5e02298d1a47804f6cea2a3b26a" Jan 30 23:09:48 famroom go-librespot[1260]: time="2024-01-30T23:09:48+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:48+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:48 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:48 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:49 famroom sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=220904,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.202/music /mnt/NAS/NAS2 Jan 30 23:09:49 famroom sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:49 famroom volumio[908]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Jan 30 23:09:49 famroom volumio[908]: info: Sending Spotify command with payload to local API: /player/volume Jan 30 23:09:49 famroom volumio[908]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:49 famroom volumio[908]: error: MyVolumio Plugin failed to start in a timely fashion Jan 30 23:09:49 famroom volumio[908]: info: BOOT COMPLETED Jan 30 23:09:49 famroom volumio[908]: [Metrics] CommandRouter: 40s 423.25ms Jan 30 23:09:49 famroom volumio[908]: info: CoreCommandRouter::volumiosetStartupVolume Jan 30 23:09:49 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 23:09:49 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:49 famroom volumio[908]: info: CoreCommandRouter::Close All Modals sent Jan 30 23:09:49 famroom volumio[908]: info: CoreCommandRouter::Close All Modals sent Jan 30 23:09:50 famroom sudo[1247]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:50 famroom volumio[908]: info: Cannot mount NAS NAS1 at system boot, trial number 3 ,retrying in 5 seconds Jan 30 23:09:50 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:09:50 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:09:50 famroom kernel: CIFS: Attempting to mount \\192.168.68.202\music Jan 30 23:09:50 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 30 23:09:50 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 30 23:09:50 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 30 23:09:51 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:51 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:51 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:51 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jan 30 23:09:51 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:51 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:51 famroom go-librespot[1278]: Librespot-go daemon starting... Jan 30 23:09:52 famroom go-librespot[1278]: time="2024-01-30T23:09:52+08:00" level=info msg="generated new device id: 05106486735e46f27efee57abc2d14136466831c" Jan 30 23:09:52 famroom go-librespot[1278]: time="2024-01-30T23:09:52+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:52+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:52 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:52 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:54 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:54 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:55 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:55 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jan 30 23:09:55 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:55 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:55 famroom go-librespot[1286]: Librespot-go daemon starting... Jan 30 23:09:55 famroom go-librespot[1286]: time="2024-01-30T23:09:55+08:00" level=info msg="generated new device id: d3c78cb1a7d2459c9ac1745cca07b3e6030067b0" Jan 30 23:09:55 famroom sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.200/music /mnt/NAS/NAS1 Jan 30 23:09:55 famroom sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:09:55 famroom go-librespot[1286]: time="2024-01-30T23:09:55+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:55+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:55 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:55 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:09:56 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 30 23:09:56 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 23:09:56 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 30 23:09:56 famroom sudo[1270]: pam_unix(sudo:session): session closed for user root Jan 30 23:09:56 famroom volumio[908]: info: Cannot mount NAS NAS2 at system boot, trial number 4 ,retrying in 5 seconds Jan 30 23:09:56 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:09:56 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:09:56 famroom kernel: CIFS: Attempting to mount \\192.168.68.200\music Jan 30 23:09:57 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:09:57 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:09:58 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:09:58 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 30 23:09:58 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:09:59 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:09:59 famroom go-librespot[1301]: Librespot-go daemon starting... Jan 30 23:09:59 famroom go-librespot[1301]: time="2024-01-30T23:09:59+08:00" level=info msg="generated new device id: a388453a87e445d36b826255c0690400041058af" Jan 30 23:09:59 famroom go-librespot[1301]: time="2024-01-30T23:09:59+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:09:59+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:09:59 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:09:59 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:10:00 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:10:00 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:10:01 famroom sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=220904,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.202/music /mnt/NAS/NAS2 Jan 30 23:10:01 famroom sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:10:02 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:10:02 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jan 30 23:10:02 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:10:02 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:10:02 famroom go-librespot[1352]: Librespot-go daemon starting... Jan 30 23:10:02 famroom go-librespot[1352]: time="2024-01-30T23:10:02+08:00" level=info msg="generated new device id: be42f03ac13e72c18ccbbb10cf85b827a644162c" Jan 30 23:10:02 famroom go-librespot[1352]: time="2024-01-30T23:10:02+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:10:02+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:10:02 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:10:02 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:10:03 famroom sudo[1295]: pam_unix(sudo:session): session closed for user root Jan 30 23:10:03 famroom volumio[908]: info: Cannot mount NAS NAS1 at system boot, trial number 4 ,retrying in 5 seconds Jan 30 23:10:03 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:10:03 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:10:03 famroom kernel: CIFS: Attempting to mount \\192.168.68.202\music Jan 30 23:10:03 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:10:03 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:10:05 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:10:05 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Jan 30 23:10:05 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:10:05 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:10:05 famroom go-librespot[1360]: Librespot-go daemon starting... Jan 30 23:10:06 famroom go-librespot[1360]: time="2024-01-30T23:10:06+08:00" level=info msg="generated new device id: 63bad65a6614f0a6bea891eaf2062bf621306640" Jan 30 23:10:06 famroom go-librespot[1360]: time="2024-01-30T23:10:06+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:10:06+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:10:06 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:10:06 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:10:06 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:10:06 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:10:07 famroom ntpd[831]: Soliciting pool server 222.127.1.19 Jan 30 23:10:08 famroom sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.200/music /mnt/NAS/NAS1 Jan 30 23:10:08 famroom sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 23:10:09 famroom sudo[1348]: pam_unix(sudo:session): session closed for user root Jan 30 23:10:09 famroom volumio[908]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 30 23:10:09 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:10:09 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:10:09 famroom kernel: CIFS: Attempting to mount \\192.168.68.200\music Jan 30 23:10:09 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:10:09 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jan 30 23:10:09 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:10:09 famroom ntpd[831]: Soliciting pool server 222.127.1.23 Jan 30 23:10:09 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:10:09 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:10:09 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:10:09 famroom go-librespot[1373]: Librespot-go daemon starting... Jan 30 23:10:09 famroom go-librespot[1373]: time="2024-01-30T23:10:09+08:00" level=info msg="generated new device id: 17f910b717d11b917ca3ec7d66062b6e0de3d9de" Jan 30 23:10:09 famroom go-librespot[1373]: time="2024-01-30T23:10:09+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:10:09+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:10:09 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:10:09 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:10:11 famroom ntpd[831]: Soliciting pool server 222.127.1.27 Jan 30 23:10:11 famroom ntpd[831]: Soliciting pool server 222.127.1.24 Jan 30 23:10:12 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:10:12 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:10:12 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:10:12 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Jan 30 23:10:12 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:10:12 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:10:12 famroom go-librespot[1381]: Librespot-go daemon starting... Jan 30 23:10:13 famroom go-librespot[1381]: time="2024-01-30T23:10:13+08:00" level=info msg="generated new device id: 07967307aeec9b9763aae2f00cd97b0978b321bd" Jan 30 23:10:13 famroom go-librespot[1381]: time="2024-01-30T23:10:13+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:10:13+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:10:13 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:10:13 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:10:15 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:10:15 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:10:15 famroom sudo[1369]: pam_unix(sudo:session): session closed for user root Jan 30 23:10:15 famroom volumio[908]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 30 23:10:15 famroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 30 23:10:15 famroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 30 23:10:16 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:10:16 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jan 30 23:10:16 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:10:16 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:10:16 famroom go-librespot[1390]: Librespot-go daemon starting... Jan 30 23:10:16 famroom go-librespot[1390]: time="2024-01-30T23:10:16+08:00" level=info msg="generated new device id: 810df2a4bcac54c798dc41025d266026b6667740" Jan 30 23:10:16 famroom go-librespot[1390]: time="2024-01-30T23:10:16+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:10:16+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:10:16 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:10:16 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:10:18 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:10:18 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:10:19 famroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 30 23:10:19 famroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Jan 30 23:10:19 famroom systemd[1]: Stopped go-librespot Daemon. Jan 30 23:10:19 famroom systemd[1]: Started go-librespot Daemon. Jan 30 23:10:19 famroom go-librespot[1398]: Librespot-go daemon starting... Jan 30 23:10:20 famroom go-librespot[1398]: time="2024-01-30T23:10:20+08:00" level=info msg="generated new device id: 6c0fa5b5ff1fae5601301cf3ff58ce42bd3d6faa" Jan 30 23:10:20 famroom go-librespot[1398]: time="2024-01-30T23:10:20+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-30T23:10:20+08:00 is before 2024-02-05T00:00:00Z" Jan 30 23:10:20 famroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 30 23:10:20 famroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 30 23:10:20 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 30 23:10:20 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 30 23:10:20 famroom volumio[908]: info: Discovery: Getting this device information Jan 30 23:10:20 famroom volumio[908]: info: CoreCommandRouter::volumioGetState Jan 30 23:10:20 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:10:20 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 30 23:10:20 famroom volumio[908]: verbose: New Socket.io Connection to 192.168.68.209:3000 from 192.168.68.54 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jan 30 23:10:20 famroom volumio[908]: info: CoreCommandRouter::volumioGetState Jan 30 23:10:20 famroom volumio[908]: info: CorePlayQueue::getTrack 0 Jan 30 23:10:20 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 30 23:10:20 famroom volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 30 23:10:21 famroom volumio[908]: info: Initializing connection to go-librespot Websocket Jan 30 23:10:21 famroom volumio[908]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 30 23:10:21 famroom volumio[908]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 30 23:10:21 famroom volumio[908]: Error: certificate is not yet valid Jan 30 23:10:21 famroom volumio[908]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 30 23:10:21 famroom volumio[908]: at TLSSocket.emit (events.js:315:20) Jan 30 23:10:21 famroom volumio[908]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 30 23:10:21 famroom volumio[908]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 30 23:10:21 famroom volumio[908]: code: 'CERT_NOT_YET_VALID' Jan 30 23:10:21 famroom volumio[908]: } Jan 30 23:10:21 famroom volumio[908]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 30 23:10:22 famroom sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-01-30 23:09 Jan 30 23:10:22 famroom sudo[1415]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"