-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sat 2025-06-07 17:47:01 BST. -- Jun 07 17:46:00 pyepi dhcpcd[563]: Not running dhcpcd because /etc/network/interfaces Jun 07 17:46:00 pyepi dhcpcd[563]: defines some interfaces that will use a Jun 07 17:46:00 pyepi dhcpcd[563]: DHCP client or static address Jun 07 17:46:00 pyepi mpd_monitor.sh[566]: MPD Monitor Service: Starting MPD Monitor Service Jun 07 17:46:00 pyepi thd[555]: Unable to parse trigger line: Jun 07 17:46:00 pyepi thd[555]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 07 17:46:00 pyepi thd[555]: Unable to parse trigger line: Jun 07 17:46:00 pyepi thd[555]: Found socket passed from systemd Jun 07 17:46:00 pyepi systemd-logind[554]: New seat seat0. Jun 07 17:46:00 pyepi haveged[483]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 07 17:46:00 pyepi haveged[483]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jun 07 17:46:00 pyepi haveged[483]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.001 Jun 07 17:46:00 pyepi haveged[483]: haveged: fills: 0, generated: 0 Jun 07 17:46:00 pyepi avahi-daemon[564]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 07 17:46:00 pyepi mpd_monitor.sh[566]: MPD error: Connection refused Jun 07 17:46:00 pyepi avahi-daemon[564]: Successfully dropped root privileges. Jun 07 17:46:00 pyepi mpd_monitor.sh[566]: MPD Monitor Service: MPD Appears to be inactive, restarting Jun 07 17:46:00 pyepi avahi-daemon[564]: avahi-daemon 0.7 starting up. Jun 07 17:46:01 pyepi mpd_monitor.sh[566]: mpd: no process found Jun 07 17:46:01 pyepi systemd[1]: Started Login Service. Jun 07 17:46:01 pyepi systemd[1]: Started triggerhappy global hotkey daemon. Jun 07 17:46:01 pyepi wpa_supplicant[556]: Successfully initialized wpa_supplicant Jun 07 17:46:01 pyepi avahi-daemon[564]: Successfully called chroot(). Jun 07 17:46:01 pyepi systemd[1]: Started Save/Restore Sound Card State. Jun 07 17:46:01 pyepi avahi-daemon[564]: Successfully dropped remaining capabilities. Jun 07 17:46:01 pyepi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jun 07 17:46:01 pyepi systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jun 07 17:46:01 pyepi systemd[1]: Failed to start dhcpcd on all interfaces. Jun 07 17:46:01 pyepi systemd[1]: hciuart.service: Succeeded. Jun 07 17:46:01 pyepi systemd[1]: Started Configure Bluetooth Modems connected by UART. Jun 07 17:46:01 pyepi avahi-daemon[564]: Loading service file /services/volumio.service. Jun 07 17:46:01 pyepi avahi-daemon[564]: Network interface enumeration completed. Jun 07 17:46:01 pyepi avahi-daemon[564]: Server startup complete. Host name is pyepi.local. Local service cookie is 3370544482. Jun 07 17:46:01 pyepi avahi-daemon[564]: Service "PyePi" (/services/volumio.service) successfully established. Jun 07 17:46:01 pyepi sh[510]: wlan0=wlan0 Jun 07 17:46:01 pyepi systemd[1]: Started Raise network interfaces. Jun 07 17:46:01 pyepi systemd[1]: Started WPA supplicant. Jun 07 17:46:01 pyepi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 07 17:46:01 pyepi tailscaled[544]: logtail started Jun 07 17:46:01 pyepi tailscaled[544]: Program starting: v1.84.0-t160c11f37-gbf6042458, Go 1.24.2: []string{"/usr/sbin/tailscaled", "--state=/var/lib/tailscale/tailscaled.state", "--socket=/run/tailscale/tailscaled.sock", "--port=41641"} Jun 07 17:46:01 pyepi tailscaled[544]: LogID: f56e635ef748f0257e46a3b9a19fe817ed71b3692b933737abd51801b24d8803 Jun 07 17:46:01 pyepi tailscaled[544]: logpolicy: using $STATE_DIRECTORY, "/var/lib/tailscale" Jun 07 17:46:01 pyepi tailscaled[544]: logtail: dial "log.tailscale.com:443" failed: dial tcp: lookup log.tailscale.com on 208.67.222.222:53: dial udp 208.67.222.222:53: connect: network is unreachable (in 4ms), trying bootstrap... Jun 07 17:46:01 pyepi dbus-daemon[531]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.4' (uid=0 pid=544 comm="/usr/sbin/tailscaled --state=/var/lib/tailscale/ta") Jun 07 17:46:01 pyepi systemd[1]: Listening on mpd.socket. Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp2e.tailscale.com", "192.73.252.134") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp2e.tailscale.com", "192.73.252.134") for "log.tailscale.com" error: Get "https://derp2e.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp 192.73.252.134:443: connect: network is unreachable Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp5.tailscale.com", "2001:19f0:5801:10b7:5400:2ff:feaa:284c") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp5.tailscale.com", "2001:19f0:5801:10b7:5400:2ff:feaa:284c") for "log.tailscale.com" error: Get "https://derp5.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp [2001:19f0:5801:10b7:5400:2ff:feaa:284c]:443: connect: cannot assign requested address Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp1c.tailscale.com", "104.248.8.210") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp1c.tailscale.com", "104.248.8.210") for "log.tailscale.com" error: Get "https://derp1c.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp 104.248.8.210:443: connect: network is unreachable Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp8b.tailscale.com", "2a03:b0c0:1:d0::ec1:e001") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp8b.tailscale.com", "2a03:b0c0:1:d0::ec1:e001") for "log.tailscale.com" error: Get "https://derp8b.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp [2a03:b0c0:1:d0::ec1:e001]:443: connect: cannot assign requested address Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp6.tailscale.com", "68.183.90.120") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp6.tailscale.com", "68.183.90.120") for "log.tailscale.com" error: Get "https://derp6.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp 68.183.90.120:443: connect: network is unreachable Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp2f.tailscale.com", "2607:f740:0:3f::f4") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp2f.tailscale.com", "2607:f740:0:3f::f4") for "log.tailscale.com" error: Get "https://derp2f.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp [2607:f740:0:3f::f4]:443: connect: cannot assign requested address Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp9b.tailscale.com", "144.202.67.195") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp9b.tailscale.com", "144.202.67.195") for "log.tailscale.com" error: Get "https://derp9b.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp 144.202.67.195:443: connect: network is unreachable Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp2f.tailscale.com", "2607:f740:0:3f::f4") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp2f.tailscale.com", "2607:f740:0:3f::f4") for "log.tailscale.com" error: Get "https://derp2f.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp [2607:f740:0:3f::f4]:443: connect: cannot assign requested address Jun 07 17:46:01 pyepi systemd[1]: Starting Raspberry Pi bluetooth helper... Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp2e.tailscale.com", "192.73.252.134") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi systemd[1]: Reached target Network. Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp2e.tailscale.com", "192.73.252.134") for "log.tailscale.com" error: Get "https://derp2e.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp 192.73.252.134:443: connect: network is unreachable Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp1c.tailscale.com", "2604:a880:800:10::7a0:e001") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi systemd[1]: Starting Permit User Sessions... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp1c.tailscale.com", "2604:a880:800:10::7a0:e001") for "log.tailscale.com" error: Get "https://derp1c.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp [2604:a880:800:10::7a0:e001]:443: connect: cannot assign requested address Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp10.tailscale.com", "137.220.36.168") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp10.tailscale.com", "137.220.36.168") for "log.tailscale.com" error: Get "https://derp10.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp 137.220.36.168:443: connect: network is unreachable Jun 07 17:46:01 pyepi tailscaled[544]: trying bootstrapDNS("derp1d.tailscale.com", "2604:a880:800:10::7fe:f001") for "log.tailscale.com" ... Jun 07 17:46:01 pyepi tailscaled[544]: bootstrapDNS("derp1d.tailscale.com", "2604:a880:800:10::7fe:f001") for "log.tailscale.com" error: Get "https://derp1d.tailscale.com/bootstrap-dns?q=log.tailscale.com": dial tcp [2604:a880:800:10::7fe:f001]:443: connect: cannot assign requested address Jun 07 17:46:01 pyepi tailscaled[544]: logtail: upload: log upload of 1378 bytes compressed failed: Post "https://log.tailscale.com/c/tailnode.log.tailscale.io/b0b190fecb8389dc17dd0772d5e56ac3b49bc3068b18ae92879b1b96521e8263": failed to resolve "log.tailscale.com": no DNS fallback candidates remain for "log.tailscale.com" Jun 07 17:46:01 pyepi systemd[1]: Starting Network Time Service... Jun 07 17:46:01 pyepi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 07 17:46:01 pyepi volumio-remote-updater[550]: Error: No active session Jun 07 17:46:01 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:01] [info] asio async_connect error: system:111 (Connection refused) Jun 07 17:46:01 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 07 17:46:01 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:01] [error] handle_connect error: Underlying Transport Error Jun 07 17:46:01 pyepi systemd[1]: Starting OpenBSD Secure Shell server... Jun 07 17:46:01 pyepi systemd[1]: Reached target Network is Online. Jun 07 17:46:01 pyepi systemd[1]: Starting LSB: Brings up/down network automatically... Jun 07 17:46:02 pyepi systemd[1]: Starting Samba NMB Daemon... Jun 07 17:46:02 pyepi volumio[549]: Could not open config: /tmp/upmpdcli.conf Jun 07 17:46:02 pyepi systemd[1]: Starting /etc/rc.local Compatibility... Jun 07 17:46:02 pyepi systemd[1]: Reached target Sound Card. Jun 07 17:46:02 pyepi bthelper[645]: Raspberry Pi BDADDR already set Jun 07 17:46:02 pyepi systemd[1]: Starting Music Player Daemon... Jun 07 17:46:02 pyepi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 17:46:02 pyepi systemd[1]: Started Raspberry Pi bluetooth helper. Jun 07 17:46:02 pyepi systemd[1]: Started Permit User Sessions. Jun 07 17:46:02 pyepi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 07 17:46:02 pyepi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 07 17:46:02 pyepi systemd[1]: Started /etc/rc.local Compatibility. Jun 07 17:46:02 pyepi systemd[1]: iptables.service: Succeeded. Jun 07 17:46:02 pyepi loadcpufreq[579]: Loading cpufreq kernel modules...done (none). Jun 07 17:46:02 pyepi volumio-time-update[552]: volumio-time-update-util: Date not found in response Jun 07 17:46:02 pyepi volumio-time-update[552]: volumio-time-update-util: Retrying in 5 seconds... Jun 07 17:46:02 pyepi dbus-daemon[531]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found. Jun 07 17:46:02 pyepi tailscaled[544]: dns: [rc=unknown ret=direct] Jun 07 17:46:02 pyepi tailscaled[544]: dns: using "direct" mode Jun 07 17:46:02 pyepi tailscaled[544]: dns: using *dns.directManager Jun 07 17:46:02 pyepi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 07 17:46:02 pyepi tailscaled[544]: dns: inotify: NewDirWatcher: context canceled Jun 07 17:46:02 pyepi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 07 17:46:02 pyepi systemd[1]: Starting Hold until boot process finishes up... Jun 07 17:46:02 pyepi systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 07 17:46:02 pyepi systemd[1]: Starting Bluetooth service... Jun 07 17:46:02 pyepi systemd[1]: Received SIGRTMIN+21 from PID 180 (plymouthd). Jun 07 17:46:02 pyepi ntpd[647]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 07 17:46:02 pyepi ntpd[647]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 07 17:46:02 pyepi systemd[1]: Started Network Time Service. Jun 07 17:46:02 pyepi ntpd[716]: proto: precision = 0.573 usec (-21) Jun 07 17:46:02 pyepi systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 07 17:46:02 pyepi systemd[1]: Started Hold until boot process finishes up. Jun 07 17:46:02 pyepi systemd[1]: plymouth-quit.service: Succeeded. Jun 07 17:46:02 pyepi systemd[1]: Started Terminate Plymouth Boot Screen. Jun 07 17:46:02 pyepi systemd[1]: Received SIGRTMIN+21 from PID 180 (n/a). Jun 07 17:46:02 pyepi ifplugd[667]: Network Interface Plugging Daemon...skip eth0...done. Jun 07 17:46:02 pyepi systemd[1]: Started Getty on tty1. Jun 07 17:46:02 pyepi systemd[1]: Reached target Login Prompts. Jun 07 17:46:02 pyepi systemd[1]: Started LSB: Brings up/down network automatically. Jun 07 17:46:02 pyepi ntpd[716]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 07 17:46:02 pyepi ntpd[716]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 07 17:46:02 pyepi ntpd[716]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 162 days ago Jun 07 17:46:02 pyepi ntpd[716]: Listen and drop on 0 v6wildcard [::]:123 Jun 07 17:46:02 pyepi ntpd[716]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 07 17:46:02 pyepi ntpd[716]: Listen normally on 2 lo 127.0.0.1:123 Jun 07 17:46:02 pyepi ntpd[716]: Listening on routing socket on fd #19 for interface updates Jun 07 17:46:02 pyepi ntpd[716]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 07 17:46:02 pyepi ntpd[716]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 07 17:46:02 pyepi cpufrequtils[701]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 07 17:46:02 pyepi systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 07 17:46:02 pyepi sshd[735]: Server listening on 0.0.0.0 port 22. Jun 07 17:46:02 pyepi sshd[735]: Server listening on :: port 22. Jun 07 17:46:02 pyepi systemd[1]: Started OpenBSD Secure Shell server. Jun 07 17:46:02 pyepi bluetoothd[710]: Bluetooth daemon 5.50 Jun 07 17:46:02 pyepi bluetoothd[710]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 07 17:46:02 pyepi systemd[1]: Started Bluetooth service. Jun 07 17:46:02 pyepi systemd[1]: Reached target Bluetooth. Jun 07 17:46:02 pyepi bluetoothd[710]: Starting SDP server Jun 07 17:46:02 pyepi bluetoothd[710]: Excluding (cli) sap Jun 07 17:46:02 pyepi sudo[684]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 17:46:02 pyepi sudo[684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:02 pyepi sudo[684]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 07 17:46:02 pyepi sudo[684]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:02 pyepi dbus-daemon[531]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.6' (uid=0 pid=710 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 07 17:46:02 pyepi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 07 17:46:02 pyepi kernel: Bluetooth: BNEP filters: protocol multicast Jun 07 17:46:02 pyepi kernel: Bluetooth: BNEP socket layer initialized Jun 07 17:46:02 pyepi kernel: Bluetooth: MGMT ver 1.22 Jun 07 17:46:02 pyepi bluetoothd[710]: Bluetooth management interface 1.22 initialized Jun 07 17:46:02 pyepi tailscaled[544]: wgengine.NewUserspaceEngine(tun "tailscale0") ... Jun 07 17:46:02 pyepi kernel: tun: Universal TUN/TAP device driver, 1.6 Jun 07 17:46:02 pyepi systemd-udevd[328]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 07 17:46:02 pyepi systemd[1]: Started Tailscale node agent. Jun 07 17:46:02 pyepi systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 755 (tailscaled) Jun 07 17:46:02 pyepi bluetoothd[710]: Failed to set privacy: Rejected (0x0b) Jun 07 17:46:03 pyepi systemd[1]: Mounting Arbitrary Executable File Formats File System... Jun 07 17:46:03 pyepi systemd[1]: Starting Hostname Service... Jun 07 17:46:03 pyepi systemd[1]: Mounted Arbitrary Executable File Formats File System. Jun 07 17:46:03 pyepi dbus-daemon[531]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.4' (uid=0 pid=544 comm="/usr/sbin/tailscaled --state=/var/lib/tailscale/ta") Jun 07 17:46:03 pyepi dbus-daemon[531]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found. Jun 07 17:46:03 pyepi tailscaled[544]: dns: [rc=unknown ret=direct] Jun 07 17:46:03 pyepi tailscaled[544]: dns: using "direct" mode Jun 07 17:46:03 pyepi tailscaled[544]: dns: using *dns.directManager Jun 07 17:46:03 pyepi tailscaled[544]: link state: interfaces.State{defaultRoute= ifs={} v4=false v6=false} Jun 07 17:46:03 pyepi tailscaled[544]: onPortUpdate(port=41641, network=udp6) Jun 07 17:46:03 pyepi tailscaled[544]: router: using firewall mode pref Jun 07 17:46:03 pyepi tailscaled[544]: router: default choosing iptables Jun 07 17:46:03 pyepi tailscaled[544]: router: disabling tunneled IPv6 due to system IPv6 config: disable_ipv6 is set Jun 07 17:46:03 pyepi tailscaled[544]: onPortUpdate(port=41641, network=udp4) Jun 07 17:46:03 pyepi tailscaled[544]: magicsock: disco key = d:c5f9c29fe703745e Jun 07 17:46:03 pyepi tailscaled[544]: magicsock: SetNetworkUp(false) Jun 07 17:46:03 pyepi tailscaled[544]: Creating WireGuard device... Jun 07 17:46:03 pyepi tailscaled[544]: Bringing WireGuard device up... Jun 07 17:46:03 pyepi tailscaled[544]: Bringing router up... Jun 07 17:46:03 pyepi tailscaled[544]: external route: up Jun 07 17:46:03 pyepi tailscaled[544]: Clearing router settings... Jun 07 17:46:03 pyepi tailscaled[544]: Starting network monitor... Jun 07 17:46:03 pyepi tailscaled[544]: Engine created. Jun 07 17:46:03 pyepi ntpd[716]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 07 17:46:03 pyepi tailscaled[544]: pm: using backend prefs for "profile-86ed": Prefs{ra=false dns=true want=true routes=[] statefulFiltering=false nf=on update=on Persist{o=, n=[19Nvg] u="ian2000t@googlemail.com"}} Jun 07 17:46:03 pyepi tailscaled[544]: envknob: PORT="41641" Jun 07 17:46:03 pyepi tailscaled[544]: logpolicy: using $STATE_DIRECTORY, "/var/lib/tailscale" Jun 07 17:46:03 pyepi tailscaled[544]: got LocalBackend in 496ms Jun 07 17:46:03 pyepi tailscaled[544]: Start Jun 07 17:46:03 pyepi tailscaled[544]: ipnext: active extensions: relayserver, taildrop Jun 07 17:46:03 pyepi tailscaled[544]: Backend: logs: be:f56e635ef748f0257e46a3b9a19fe817ed71b3692b933737abd51801b24d8803 fe: Jun 07 17:46:03 pyepi tailscaled[544]: control: client.Login(0) Jun 07 17:46:03 pyepi dbus-daemon[531]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 07 17:46:03 pyepi systemd[1]: Started Hostname Service. Jun 07 17:46:03 pyepi tailscaled[544]: control: setPaused(true) Jun 07 17:46:03 pyepi tailscaled[544]: offline auto-update: starting update checks Jun 07 17:46:03 pyepi tailscaled[544]: control: updateRoutine: awaiting unpause Jun 07 17:46:03 pyepi tailscaled[544]: control: mapRoutine: awaiting unpause Jun 07 17:46:03 pyepi tailscaled[544]: control: doLogin(regen=false, hasUrl=false) Jun 07 17:46:03 pyepi tailscaled[544]: control: authRoutine: awaiting unpause Jun 07 17:46:03 pyepi tailscaled[544]: health(warnable=warming-up): error: Tailscale is starting. Please wait. Jun 07 17:46:03 pyepi tailscaled[544]: health(warnable=login-state): error: You are logged out. The last login error was: fetch control key: Get "https://controlplane.tailscale.com/key?v=116": context canceled Jun 07 17:46:03 pyepi nmbd[694]: [2025/06/07 17:46:03.856552, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 07 17:46:03 pyepi nmbd[694]: started asyncdns process 771 Jun 07 17:46:03 pyepi nmbd[694]: [2025/06/07 17:46:03.859040, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 07 17:46:03 pyepi nmbd[694]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 07 17:46:03 pyepi nmbd[694]: [2025/06/07 17:46:03.859209, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 07 17:46:03 pyepi nmbd[694]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 07 17:46:04 pyepi ntpd[716]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 07 17:46:04 pyepi systemd[1]: systemd-rfkill.service: Succeeded. Jun 07 17:46:04 pyepi wireless.js[553]: Cleaning previous... Jun 07 17:46:04 pyepi sudo[779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 07 17:46:04 pyepi sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:04 pyepi sudo[779]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:04 pyepi sudo[781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 07 17:46:04 pyepi sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:05 pyepi sudo[781]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:05 pyepi wireless.js[553]: Stopped aP Jun 07 17:46:05 pyepi sudo[788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 07 17:46:05 pyepi sudo[788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:05 pyepi sudo[788]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:05 pyepi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 07 17:46:05 pyepi sudo[790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 07 17:46:05 pyepi sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:05 pyepi sudo[790]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:05 pyepi sudo[797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 07 17:46:05 pyepi sudo[797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:05 pyepi ntpd[716]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 07 17:46:05 pyepi sudo[797]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:05 pyepi sudo[799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 07 17:46:05 pyepi sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:06 pyepi ntpd[716]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 07 17:46:06 pyepi mpd[750]: Jun 07 17:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 17:46:06 pyepi systemd[1]: Started Music Player Daemon. Jun 07 17:46:06 pyepi mpd_monitor.sh[566]: MPD Monitor Service: MPD restarted due to no mpc output. Jun 07 17:46:06 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:06] [info] asio async_connect error: system:111 (Connection refused) Jun 07 17:46:06 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 07 17:46:06 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:06] [error] handle_connect error: Underlying Transport Error Jun 07 17:46:07 pyepi bthelper[645]: Changing power off succeeded Jun 07 17:46:07 pyepi volumio-time-update[552]: volumio-time-update-util: Fetching time from Volumio... Jun 07 17:46:07 pyepi volumio-time-update[552]: volumio-time-update-util: Date not found in response Jun 07 17:46:07 pyepi volumio-time-update[552]: volumio-time-update-util: Retrying in 5 seconds... Jun 07 17:46:07 pyepi bthelper[645]: [CHG] Controller B8:27:EB:F2:3E:FF Class: 0x0000041c Jun 07 17:46:07 pyepi bthelper[645]: Changing power on succeeded Jun 07 17:46:07 pyepi sudo[799]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:07 pyepi wireless.js[553]: SETTING APPROPRIATE REG DOMAIN: DE Jun 07 17:46:07 pyepi sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 07 17:46:07 pyepi sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:07 pyepi sudo[818]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:08 pyepi sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jun 07 17:46:08 pyepi sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:08 pyepi sudo[820]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:08 pyepi wireless.js[553]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jun 07 17:46:08 pyepi wireless.js[553]: Start wireless flow Jun 07 17:46:08 pyepi wireless.js[553]: Stopped hotspot (if there).. Jun 07 17:46:08 pyepi sudo[825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 07 17:46:08 pyepi sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:08 pyepi sudo[825]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:08 pyepi sudo[827]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 07 17:46:08 pyepi sudo[827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:08 pyepi tailscaled[544]: health(warnable=warming-up): ok Jun 07 17:46:08 pyepi sudo[827]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:08 pyepi wireless.js[553]: DHCP IP Jun 07 17:46:08 pyepi wireless.js[553]: Start ap Jun 07 17:46:08 pyepi wpa_supplicant[829]: Successfully initialized wpa_supplicant Jun 07 17:46:08 pyepi sudo[830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 07 17:46:08 pyepi sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:08 pyepi dhcpcd[831]: dev: loaded udev Jun 07 17:46:08 pyepi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 07 17:46:08 pyepi kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 07 17:46:08 pyepi dhcpcd-run-hooks[839]: wlan0: starting wpa_supplicant Jun 07 17:46:09 pyepi dhcpcd-run-hooks[844]: wlan0: failed to start wpa_supplicant Jun 07 17:46:09 pyepi dhcpcd-run-hooks[845]: 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 Jun 07 17:46:09 pyepi dhcpcd[831]: wlan0: connected to Access Point `' Jun 07 17:46:09 pyepi dhcpcd[831]: no interfaces have a carrier Jun 07 17:46:09 pyepi dhcpcd[831]: forked to background, child pid 859 Jun 07 17:46:09 pyepi dhcpcd[859]: wlan0: waiting for carrier Jun 07 17:46:09 pyepi sudo[830]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:09 pyepi wireless.js[553]: trying... Jun 07 17:46:09 pyepi sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:09 pyepi sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:09 pyepi sudo[861]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:10 pyepi wireless.js[553]: trying... Jun 07 17:46:10 pyepi sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:10 pyepi sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:10 pyepi sudo[864]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:11 pyepi wireless.js[553]: trying... Jun 07 17:46:11 pyepi sudo[867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:11 pyepi sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:11 pyepi sudo[867]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:12 pyepi volumio-time-update[552]: volumio-time-update-util: Fetching time from Volumio... Jun 07 17:46:12 pyepi volumio-time-update[552]: volumio-time-update-util: Date not found in response Jun 07 17:46:12 pyepi volumio-time-update[552]: volumio-time-update-util: Retrying in 5 seconds... Jun 07 17:46:12 pyepi wireless.js[553]: trying... Jun 07 17:46:12 pyepi sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:12 pyepi sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:12 pyepi sudo[875]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:13 pyepi wireless.js[553]: trying... Jun 07 17:46:13 pyepi sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:13 pyepi sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:13 pyepi sudo[878]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:14 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:14] [info] asio async_connect error: system:111 (Connection refused) Jun 07 17:46:14 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 07 17:46:14 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:14] [error] handle_connect error: Underlying Transport Error Jun 07 17:46:14 pyepi wireless.js[553]: trying... Jun 07 17:46:14 pyepi sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:14 pyepi sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:14 pyepi sudo[881]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:15 pyepi wpa_supplicant[843]: wlan0: Trying to associate with SSID 'beechwood-deco' Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: carrier acquired Jun 07 17:46:15 pyepi wpa_supplicant[843]: wlan0: Associated with 60:83:e7:f6:11:d3 Jun 07 17:46:15 pyepi wpa_supplicant[843]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:83:e7:f6:11:d3 completed [id=0 id_str=] Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: connected to Access Point `beechwood-deco' Jun 07 17:46:15 pyepi wpa_supplicant[843]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 07 17:46:15 pyepi wpa_supplicant[843]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 07 17:46:15 pyepi dhcpcd[859]: DUID 00:01:00:01:2f:b6:23:59:b8:27:eb:0d:c1:00 Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: IAID eb:0d:c1:00 Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: adding address fe80::a33c:53ab:19d1:dd7e Jun 07 17:46:15 pyepi dhcpcd[859]: ipv6_addaddr1: Permission denied Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: carrier lost Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: carrier acquired Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: IAID eb:0d:c1:00 Jun 07 17:46:15 pyepi wireless.js[553]: trying... Jun 07 17:46:15 pyepi sudo[897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:15 pyepi sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:15 pyepi sudo[897]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: rebinding lease of 192.168.1.43 Jun 07 17:46:15 pyepi dhcpcd[859]: wlan0: probing address 192.168.1.43/24 Jun 07 17:46:16 pyepi dhcpcd[859]: wlan0: soliciting an IPv6 router Jun 07 17:46:16 pyepi wireless.js[553]: trying... Jun 07 17:46:16 pyepi sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:16 pyepi sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:16 pyepi sudo[900]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:17 pyepi volumio-time-update[552]: volumio-time-update-util: Fetching time from Volumio... Jun 07 17:46:17 pyepi volumio-time-update[552]: volumio-time-update-util: Date not found in response Jun 07 17:46:17 pyepi volumio-time-update[552]: volumio-time-update-util: Retrying in 5 seconds... Jun 07 17:46:17 pyepi wireless.js[553]: trying... Jun 07 17:46:17 pyepi sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:17 pyepi sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:17 pyepi sudo[908]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:18 pyepi wireless.js[553]: trying... Jun 07 17:46:18 pyepi sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:18 pyepi sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:18 pyepi sudo[911]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:19 pyepi wireless.js[553]: trying... Jun 07 17:46:19 pyepi sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:19 pyepi sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:19 pyepi sudo[914]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:20 pyepi dhcpcd[859]: wlan0: leased 192.168.1.43 for infinity Jun 07 17:46:20 pyepi avahi-daemon[564]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.43. Jun 07 17:46:20 pyepi tailscaled[544]: monitor: RTM_NEWROUTE: src=192.168.1.43/0, dst=192.168.1.43/32, gw=, outif=2, table=255 Jun 07 17:46:20 pyepi tailscaled[544]: monitor: RTM_NEWROUTE: src=192.168.1.43/0, dst=192.168.1.255/32, gw=, outif=2, table=255 Jun 07 17:46:20 pyepi avahi-daemon[564]: New relevant interface wlan0.IPv4 for mDNS. Jun 07 17:46:20 pyepi dhcpcd[859]: wlan0: adding route to 192.168.1.0/24 Jun 07 17:46:20 pyepi avahi-daemon[564]: Registering new address record for 192.168.1.43 on wlan0.IPv4. Jun 07 17:46:20 pyepi dhcpcd[859]: wlan0: adding default route via 192.168.1.1 Jun 07 17:46:20 pyepi tailscaled[544]: monitor: RTM_NEWROUTE: src=192.168.1.43/0, dst=192.168.1.0/24, gw=, outif=2, table=254 Jun 07 17:46:20 pyepi tailscaled[544]: monitor: RTM_NEWROUTE: src=192.168.1.43/0, dst=, gw=192.168.1.1, outif=2, table=254 Jun 07 17:46:20 pyepi tailscaled[544]: control: setPaused(false) Jun 07 17:46:20 pyepi tailscaled[544]: control: doLogin(regen=false, hasUrl=false) Jun 07 17:46:20 pyepi tailscaled[544]: LinkChange: major, rebinding. New state: interfaces.State{defaultRoute=wlan0 ifs={wlan0:[192.168.1.43/24]} v4=true v6=false} Jun 07 17:46:20 pyepi tailscaled[544]: magicsock: SetNetworkUp(true) Jun 07 17:46:20 pyepi tailscaled[544]: onPortUpdate(port=41641, network=udp6) Jun 07 17:46:20 pyepi tailscaled[544]: onPortUpdate(port=41641, network=udp4) Jun 07 17:46:20 pyepi tailscaled[544]: Rebind; defIf="wlan0", ips=[192.168.1.43/24] Jun 07 17:46:20 pyepi tailscaled[544]: magicsock: 0 active derp conns Jun 07 17:46:20 pyepi tailscaled[544]: monitor: gateway and self IP changed: gw=192.168.1.1 self=192.168.1.43 Jun 07 17:46:20 pyepi wireless.js[553]: trying... Jun 07 17:46:20 pyepi sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 07 17:46:20 pyepi sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:20 pyepi sudo[938]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:20 pyepi wireless.js[553]: Connected to: ----beechwood-deco Jun 07 17:46:20 pyepi wireless.js[553]: ---- Jun 07 17:46:20 pyepi sudo[941]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:46:20 pyepi sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:20 pyepi sudo[941]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:20 pyepi wireless.js[553]: ... joined AP, wlan0 IPv4 is 192.168.1.43, ipV6 is undefined Jun 07 17:46:20 pyepi wireless.js[553]: It's done! AP Jun 07 17:46:20 pyepi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 07 17:46:20 pyepi systemd[1]: Started Wireless Services. Jun 07 17:46:20 pyepi systemd[1]: Started Volumio Backend Module. Jun 07 17:46:20 pyepi systemd[1]: Started Volumio Cpu Tweaker. Jun 07 17:46:20 pyepi volumio-cpu-tweak[947]: Setting RT Priority for mpd Jun 07 17:46:20 pyepi volumio-cpu-tweak[947]: Setting MPD Affinity Jun 07 17:46:20 pyepi volumio-cpu-tweak[947]: pid 750's current affinity mask: f Jun 07 17:46:20 pyepi volumio-cpu-tweak[947]: pid 750's new affinity mask: 3 Jun 07 17:46:20 pyepi volumio-cpu-tweak[947]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 07 17:46:20 pyepi volumio-cpu-tweak[947]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 07 17:46:20 pyepi systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 07 17:46:21 pyepi tailscaled[544]: control: control server key from https://controlplane.tailscale.com: ts2021=[fSeS+], legacy=[nlFWp] Jun 07 17:46:21 pyepi tailscaled[544]: control: RegisterReq: onode= node=[19Nvg] fup=false nks=false Jun 07 17:46:21 pyepi tailscaled[544]: control: RegisterReq: got response; nodeKeyExpired=false, machineAuthorized=true; authURL=false Jun 07 17:46:21 pyepi tailscaled[544]: health(warnable=login-state): ok Jun 07 17:46:21 pyepi tailscaled[544]: health(warnable=not-in-map-poll): ok Jun 07 17:46:21 pyepi tailscaled[544]: control: netmap: got new dial plan from control Jun 07 17:46:21 pyepi tailscaled[544]: active login: ian2000t@googlemail.com Jun 07 17:46:21 pyepi tailscaled[544]: Switching ipn state NoState -> Starting (WantRunning=true, nm=true) Jun 07 17:46:21 pyepi tailscaled[544]: magicsock: SetPrivateKey called (init) Jun 07 17:46:21 pyepi tailscaled[544]: wgengine: Reconfig: configuring userspace WireGuard config (with 0/8 peers) Jun 07 17:46:21 pyepi tailscaled[544]: wgengine: Reconfig: configuring router Jun 07 17:46:21 pyepi tailscaled[544]: wgengine: Reconfig: user dialer Jun 07 17:46:21 pyepi tailscaled[544]: tsdial: bart table size: 11 Jun 07 17:46:21 pyepi tailscaled[544]: wgengine: Reconfig: configuring DNS Jun 07 17:46:21 pyepi tailscaled[544]: dns: Set: {DefaultResolvers:[] Routes:{tail2d733d.ts.net.:[] ts.net.:[199.247.155.53 2620:111:8007::53]}+65arpa SearchDomains:[tail2d733d.ts.net.] Hosts:9} Jun 07 17:46:21 pyepi tailscaled[544]: dns: Resolvercfg: {Routes:{.:[192.168.1.20 192.168.1.23 208.67.222.222 208.67.220.220] ts.net.:[199.247.155.53 2620:111:8007::53]} Hosts:9 LocalDomains:[tail2d733d.ts.net.]+65arpa} Jun 07 17:46:21 pyepi tailscaled[544]: dns: OScfg: {Nameservers:[100.100.100.100] SearchDomains:[tail2d733d.ts.net.] } Jun 07 17:46:21 pyepi tailscaled[544]: magicsock: home DERP changing from derp-0 [0ms] to derp-8 [14ms] Jun 07 17:46:21 pyepi tailscaled[544]: magicsock: home is now derp-8 (lhr) Jun 07 17:46:21 pyepi tailscaled[544]: magicsock: endpoints changed: 67.208.53.218:41641 (stun), 192.168.1.43:41641 (local) Jun 07 17:46:21 pyepi tailscaled[544]: magicsock: adding connection to derp-8 for home-keep-alive Jun 07 17:46:21 pyepi tailscaled[544]: magicsock: 1 active derp conns: derp-8=cr0s,wr0s Jun 07 17:46:21 pyepi tailscaled[544]: health(warnable=no-derp-home): ok Jun 07 17:46:21 pyepi tailscaled[544]: derphttp.Client.Connect: connecting to derp-8 (lhr) Jun 07 17:46:21 pyepi tailscaled[544]: control: NetInfo: NetInfo{varies=false hairpin= ipv6=false ipv6os=false udp=true icmpv4=false derp=#8 portmap= link="" firewallmode="ipt-default"} Jun 07 17:46:21 pyepi tailscaled[544]: peerapi: serving on http://100.97.38.68:65192 Jun 07 17:46:21 pyepi tailscaled[544]: peerapi: failed to do peerAPI listen, harmless (netstack available) but error was: listen tcp6 [fd7a:115c:a1e0::fb32:2644]:0: bind: cannot assign requested address Jun 07 17:46:21 pyepi tailscaled[544]: peerapi: serving on http://[fd7a:115c:a1e0::fb32:2644]:1 Jun 07 17:46:21 pyepi tailscaled[544]: offline auto-update: stopping update checks Jun 07 17:46:21 pyepi tailscaled[544]: Switching ipn state Starting -> Running (WantRunning=true, nm=true) Jun 07 17:46:21 pyepi tailscaled[544]: magicsock: derp-8 connected; connGen=1 Jun 07 17:46:21 pyepi tailscaled[544]: health(warnable=no-derp-connection): ok Jun 07 17:46:22 pyepi volumio-time-update[552]: volumio-time-update-util: Fetching time from Volumio... Jun 07 17:46:22 pyepi volumio-time-update[552]: volumio-time-update-util: Setting system time to: 2025-06-07 17:46:42 Jun 07 17:46:22 pyepi sudo[1000]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-07 17:46:42 Jun 07 17:46:22 pyepi sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:22 pyepi dbus-daemon[531]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1001 comm="timedatectl set-time 2025-06-07 17:46:42 ") Jun 07 17:46:22 pyepi systemd[1]: Starting Time & Date Service... Jun 07 17:46:22 pyepi dbus-daemon[531]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 07 17:46:22 pyepi systemd[1]: Started Time & Date Service. Jun 07 17:46:42 pyepi systemd-timedated[1002]: Changed local time to Sat Jun 7 17:46:42 2025 Jun 07 17:46:42 pyepi sudo[1000]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:42 pyepi volumio-time-update[552]: volumio-time-update-util: System time updated successfully. Jun 07 17:46:42 pyepi systemd[1]: Started Volumio Time Update Utility. Jun 07 17:46:42 pyepi ntpd[716]: Listen normally on 3 wlan0 192.168.1.43:123 Jun 07 17:46:42 pyepi ntpd[716]: Listen normally on 4 tailscale0 100.97.38.68:123 Jun 07 17:46:42 pyepi ntpd[716]: new interface(s) found: waking up resolver Jun 07 17:46:42 pyepi volumio[946]: info: ------------------------------------------- Jun 07 17:46:42 pyepi volumio[946]: info: ----- Volumio3 ---- Jun 07 17:46:42 pyepi volumio[946]: info: ------------------------------------------- Jun 07 17:46:42 pyepi volumio[946]: info: ----- System startup ---- Jun 07 17:46:42 pyepi volumio[946]: info: ------------------------------------------- Jun 07 17:46:43 pyepi nmbd[694]: [2025/06/07 17:46:43.173658, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 07 17:46:43 pyepi systemd[1]: Started Samba NMB Daemon. Jun 07 17:46:43 pyepi nmbd[694]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 07 17:46:43 pyepi systemd[1]: Starting Samba Winbind Daemon... Jun 07 17:46:43 pyepi winbindd[1011]: [2025/06/07 17:46:43.579199, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 07 17:46:43 pyepi winbindd[1011]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 07 17:46:43 pyepi winbindd[1011]: [2025/06/07 17:46:43.612431, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 07 17:46:43 pyepi winbindd[1011]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 07 17:46:43 pyepi systemd[1]: Started Samba Winbind Daemon. Jun 07 17:46:43 pyepi systemd[1]: Starting Samba SMB Daemon... Jun 07 17:46:43 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:43] [connect] Successful connection Jun 07 17:46:44 pyepi smbd[1016]: [2025/06/07 17:46:44.306201, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 07 17:46:44 pyepi systemd[1]: Started Samba SMB Daemon. Jun 07 17:46:44 pyepi volumio[946]: info: MYVOLUMIO Environment detected Jun 07 17:46:44 pyepi smbd[1016]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 07 17:46:44 pyepi systemd[1]: Reached target Multi-User System. Jun 07 17:46:44 pyepi systemd[1]: Reached target Graphical Interface. Jun 07 17:46:44 pyepi systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 07 17:46:44 pyepi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 07 17:46:44 pyepi systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 07 17:46:44 pyepi systemd[1]: Startup finished in 12.999s (kernel) + 29.365s (userspace) = 42.364s. Jun 07 17:46:44 pyepi volumio[946]: info: Plugin folders cleanup Jun 07 17:46:44 pyepi volumio[946]: info: Scanning into folder /volumio/app/plugins/ Jun 07 17:46:44 pyepi volumio[946]: info: Scanning category audio_interface Jun 07 17:46:44 pyepi volumio[946]: info: Scanning category miscellanea Jun 07 17:46:44 pyepi volumio[946]: info: Scanning category music_service Jun 07 17:46:44 pyepi volumio[946]: info: Scanning category plugins.json Jun 07 17:46:44 pyepi volumio[946]: info: Scanning category system_controller Jun 07 17:46:44 pyepi volumio[946]: info: Scanning category user_interface Jun 07 17:46:44 pyepi volumio[946]: info: Scanning into folder /data/plugins/ Jun 07 17:46:44 pyepi volumio[946]: info: Scanning category audio_interface Jun 07 17:46:44 pyepi volumio[946]: info: Plugin folders cleanup completed Jun 07 17:46:44 pyepi volumio[946]: info: ------------------------------------------- Jun 07 17:46:44 pyepi volumio[946]: info: ----- Core plugins startup ---- Jun 07 17:46:44 pyepi volumio[946]: info: ------------------------------------------- Jun 07 17:46:44 pyepi volumio[946]: info: Loading plugins from folder /volumio/app/plugins/ Jun 07 17:46:44 pyepi volumio[946]: info: Adding plugin upnp to MyMusic Plugins Jun 07 17:46:44 pyepi volumio[946]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 07 17:46:44 pyepi volumio[946]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 07 17:46:44 pyepi volumio[946]: info: Loading plugins from folder /data/plugins/ Jun 07 17:46:44 pyepi volumio[946]: info: Loading plugin "system"... Jun 07 17:46:44 pyepi volumio[946]: info: Loading plugin "appearance"... Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "network"... Jun 07 17:46:46 pyepi volumio[946]: info: Refreshing Cached IP Addresses Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "services"... Jun 07 17:46:46 pyepi sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:46:46 pyepi sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "alsa_controller"... Jun 07 17:46:46 pyepi sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 17:46:46 pyepi sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:46 pyepi sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 07 17:46:46 pyepi sudo[1036]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:46 pyepi sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:46 pyepi sudo[1034]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:46 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "wizard"... Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "networkfs"... Jun 07 17:46:46 pyepi volumio[946]: info: Starting Udev Watcher for removable devices Jun 07 17:46:46 pyepi sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.20:/Music/Music_Artists /mnt/NAS/MusicArtists Jun 07 17:46:46 pyepi sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:46 pyepi sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.20:/Music/Music_Various /mnt/NAS/MusicVarious Jun 07 17:46:46 pyepi sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:46 pyepi volumio[946]: info: Ignoring mount for partition: boot Jun 07 17:46:46 pyepi volumio[946]: info: Ignoring mount for partition: volumio Jun 07 17:46:46 pyepi volumio[946]: info: Ignoring mount for partition: volumio_data Jun 07 17:46:46 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "volumio_command_line_client"... Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "upnp"... Jun 07 17:46:46 pyepi volumio[946]: info: [1749314806586] Starting Upmpd Daemon Jun 07 17:46:46 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "my_music"... Jun 07 17:46:46 pyepi volumio[946]: info: Loading plugin "mpd"... Jun 07 17:46:46 pyepi kernel: hwmon hwmon1: Undervoltage detected! Jun 07 17:46:46 pyepi sudo[1064]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:47 pyepi sudo[1066]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:47 pyepi volumio[946]: info: Loading plugin "upnp_browser"... Jun 07 17:46:48 pyepi systemd[1]: systemd-fsckd.service: Succeeded. Jun 07 17:46:48 pyepi volumio[946]: info: Loading plugin "alarm-clock"... Jun 07 17:46:48 pyepi volumio[946]: info: Loading plugin "airplay_emulation"... Jun 07 17:46:48 pyepi volumio[946]: info: Starting Shairport Sync Jun 07 17:46:48 pyepi volumio[946]: info: Loading plugin "last_100"... Jun 07 17:46:48 pyepi volumio[946]: info: Loading plugin "webradio"... Jun 07 17:46:49 pyepi volumio[946]: info: Loading plugin "i2s_dacs"... Jun 07 17:46:49 pyepi volumio[946]: info: Loading plugin "volumiodiscovery"... Jun 07 17:46:49 pyepi volumio[946]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 07 17:46:49 pyepi volumio[946]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 07 17:46:49 pyepi volumio[946]: *** WARNING *** For more information see Jun 07 17:46:49 pyepi node[946]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 07 17:46:49 pyepi volumio[946]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 07 17:46:49 pyepi volumio[946]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 07 17:46:49 pyepi volumio[946]: *** WARNING *** For more information see Jun 07 17:46:49 pyepi node[946]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 07 17:46:49 pyepi node[946]: *** WARNING *** For more information see Jun 07 17:46:49 pyepi node[946]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 07 17:46:49 pyepi node[946]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 07 17:46:49 pyepi node[946]: *** WARNING *** For more information see Jun 07 17:46:49 pyepi volumio[946]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 07 17:46:49 pyepi volumio[946]: info: Discovery: Started advertising with name: PyePi Jun 07 17:46:49 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 07 17:46:49 pyepi volumio[946]: info: Loading plugin "outputs"... Jun 07 17:46:49 pyepi volumio[946]: info: Loading plugin "albumart"... Jun 07 17:46:49 pyepi volumio[946]: info: Plugin example_plugin is not enabled Jun 07 17:46:49 pyepi volumio[946]: info: Loading plugin "inputs"... Jun 07 17:46:49 pyepi volumio[946]: info: Loading plugin "updater_comm"... Jun 07 17:46:49 pyepi sudo[1043]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:49 pyepi volumio[946]: info: Plugin mpdemulation is not enabled Jun 07 17:46:49 pyepi volumio[946]: info: Loading plugin "rest_api"... Jun 07 17:46:50 pyepi volumio[946]: info: Loading plugin "websocket"... Jun 07 17:46:50 pyepi volumio[946]: info: Starting Socket.io Server version 2.3.0 Jun 07 17:46:50 pyepi volumio[946]: info: Loading plugin "fusiondsp"... Jun 07 17:46:50 pyepi volumio[946]: Forking 3 albumart workers Jun 07 17:46:50 pyepi volumio[946]: info: Applying required configuration parameters for plugin fusiondsp Jun 07 17:46:50 pyepi volumio[946]: info: Loading i18n strings for locale en Jun 07 17:46:50 pyepi volumio[946]: Updating browse sources language Jun 07 17:46:50 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:46:51 pyepi volumio[946]: Starting albumart workers Jun 07 17:46:51 pyepi volumio[946]: Starting albumart workers Jun 07 17:46:51 pyepi volumio[946]: Starting albumart workers Jun 07 17:46:51 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 17:46:51 pyepi volumio[946]: info: CoreCommandRouter::initPlayerControls Jun 07 17:46:51 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:51 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 17:46:51 pyepi volumio[946]: Express server listening on port 3000 Jun 07 17:46:51 pyepi volumio[946]: [Metrics] WebUI: 9s 400.66ms Jun 07 17:46:51 pyepi volumio[946]: info: CoreStateMachine::resetVolumioState Jun 07 17:46:51 pyepi volumio[946]: info: CoreStateMachine::getcurrentVolume Jun 07 17:46:51 pyepi volumio[946]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 17:46:51 pyepi volumio[946]: info: Volumio Network Manager: Network status updated: 2 Jun 07 17:46:51 pyepi volumio[946]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 07 17:46:51 pyepi volumio[946]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 07 17:46:51 pyepi volumio[946]: verbose: New Socket.io Connection to 192.168.1.43:3000 from 192.168.1.42 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 07 17:46:51 pyepi volumio[946]: verbose: New Socket.io Connection to 192.168.1.43:3000 from 192.168.1.41 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 07 17:46:52 pyepi volumio[946]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 07 17:46:52 pyepi volumio[946]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 07 17:46:52 pyepi volumio[946]: info: Reloading queue from file Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:52 pyepi volumio[946]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 07 17:46:52 pyepi volumio[946]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 07 17:46:52 pyepi volumio[946]: info: CoreStateMachine::setRepeat null single undefined Jun 07 17:46:52 pyepi volumio[946]: info: CoreStateMachine::pushState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioPushState Jun 07 17:46:52 pyepi volumio[946]: info: CoreStateMachine::setRandom null Jun 07 17:46:52 pyepi volumio[946]: info: CoreStateMachine::pushState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioPushState Jun 07 17:46:52 pyepi volumio[946]: info: Setting Device type: Raspberry PI Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 17:46:52 pyepi volumio[946]: info: Completed loading Core Plugins Jun 07 17:46:52 pyepi volumio[946]: info: Preparing to generate the ALSA configuration file Jun 07 17:46:52 pyepi volumio[946]: info: VolumeController:: Volume=25 Mute =false Jun 07 17:46:52 pyepi volumio[946]: info: CoreStateMachine::pushState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioPushState Jun 07 17:46:52 pyepi volumio[946]: info: CoreStateMachine::updateTrackBlock Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrackBlock Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 17:46:52 pyepi volumio[946]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 17:46:52 pyepi volumio[946]: info: Reading ALSA contributions from plugins. Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioGetVisibleSources Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioGetState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 07 17:46:52 pyepi tailscaled[544]: LinkChange: major, rebinding. New state: interfaces.State{defaultRoute=wlan0 ifs={tailscale0:[100.97.38.68/32] wlan0:[192.168.1.43/24]} v4=true v6=false} Jun 07 17:46:52 pyepi tailscaled[544]: dns: Set: {DefaultResolvers:[] Routes:{tail2d733d.ts.net.:[] ts.net.:[199.247.155.53 2620:111:8007::53]}+65arpa SearchDomains:[tail2d733d.ts.net.] Hosts:9} Jun 07 17:46:52 pyepi volumio[946]: info: Received Get System Info Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 17:46:52 pyepi volumio[946]: info: Discovery: Getting this device information Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioGetState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioGetState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi tailscaled[544]: dns: Resolvercfg: {Routes:{.:[192.168.1.20 192.168.1.23 208.67.222.222 208.67.220.220] ts.net.:[199.247.155.53 2620:111:8007::53]} Hosts:9 LocalDomains:[tail2d733d.ts.net.]+65arpa} Jun 07 17:46:52 pyepi tailscaled[544]: dns: OScfg: {Nameservers:[100.100.100.100] SearchDomains:[tail2d733d.ts.net.] } Jun 07 17:46:52 pyepi volumio[946]: info: Listing playlists Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioGetQueue Jun 07 17:46:52 pyepi volumio[946]: info: CoreStateMachine::getQueue Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getQueue Jun 07 17:46:52 pyepi tailscaled[544]: wgengine: set DNS config again after major link change Jun 07 17:46:52 pyepi tailscaled[544]: onPortUpdate(port=41641, network=udp6) Jun 07 17:46:52 pyepi tailscaled[544]: onPortUpdate(port=41641, network=udp4) Jun 07 17:46:52 pyepi tailscaled[544]: Rebind; defIf="wlan0", ips=[192.168.1.43/24] Jun 07 17:46:52 pyepi tailscaled[544]: magicsock: 1 active derp conns: derp-8=cr12s,wr12s Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioGetState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi tailscaled[544]: post-rebind ping of DERP region 8 okay Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioGetState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi volumio[946]: info: VolumeController::SetAlsaVolume0 Jun 07 17:46:52 pyepi systemd[1]: systemd-hostnamed.service: Succeeded. Jun 07 17:46:52 pyepi volumio[946]: info: CoreStateMachine::pushState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioPushState Jun 07 17:46:52 pyepi kernel: hwmon hwmon1: Voltage normalised Jun 07 17:46:52 pyepi volumio[946]: info: Discovery: adding 8f6e26fa-dceb-4842-9e3e-24d9302fb323 Jun 07 17:46:52 pyepi volumio[946]: info: Discovery: Found device PyePi Jun 07 17:46:52 pyepi volumio[946]: info: CoreCommandRouter::volumioGetState Jun 07 17:46:52 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:52 pyepi volumio[946]: info: Discovery: adding c790b7d5-0a8f-4052-941e-1e7cee6e093b Jun 07 17:46:52 pyepi volumio[946]: info: Discovery: Found device GarageMusicPi Jun 07 17:46:52 pyepi volumio[946]: info: Discovery: Connecting to remote: 192.168.1.42 Jun 07 17:46:53 pyepi volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 17:46:53 pyepi volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 17:46:53 pyepi volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 17:46:53 pyepi volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 17:46:53 pyepi volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 17:46:53 pyepi volumio[946]: info: VolumeController:: Volume=25 Mute =false Jun 07 17:46:53 pyepi volumio[946]: info: CoreStateMachine::pushState Jun 07 17:46:53 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:53 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:46:53 pyepi volumio[946]: info: CoreCommandRouter::volumioPushState Jun 07 17:46:53 pyepi volumio[946]: info: Discovery: adding 2977edaf-44d6-4b9c-b47c-566939cc6a02 Jun 07 17:46:53 pyepi volumio[946]: info: Discovery: Found device JerryMusicPi Jun 07 17:46:53 pyepi volumio[946]: info: Discovery: Connecting to remote: 192.168.1.41 Jun 07 17:46:53 pyepi volumio[946]: info: FusionDsp - no preset used Jun 07 17:46:53 pyepi volumio[946]: info: FusionDsp - no preset used Jun 07 17:46:54 pyepi volumio[946]: info: FusionDsp - no preset used Jun 07 17:46:54 pyepi volumio[946]: info: FusionDsp - no preset used Jun 07 17:46:54 pyepi kernel: hwmon hwmon1: Undervoltage detected! Jun 07 17:46:54 pyepi volumio[946]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,IanT 1.json,IanT 2.json Jun 07 17:46:55 pyepi volumio[946]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,IanT 1.json,IanT 2.json Jun 07 17:46:55 pyepi volumio[946]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,IanT 1.json,IanT 2.json Jun 07 17:46:55 pyepi volumio[946]: info: FusionDsp - no preset used Jun 07 17:46:55 pyepi volumio[946]: info: Discovery: Connected to remote: 192.168.1.41 Jun 07 17:46:55 pyepi volumio[946]: info: Discovery: Connected to remote: 192.168.1.42 Jun 07 17:46:56 pyepi volumio[946]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,IanT 1.json,IanT 2.json Jun 07 17:46:56 pyepi volumio[946]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,IanT 1.json,IanT 2.json Jun 07 17:46:56 pyepi volumio[946]: info: Asound.conf file written Jun 07 17:46:56 pyepi sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 07 17:46:56 pyepi sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:56 pyepi sudo[1152]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:56 pyepi volumio[946]: info: Output device has changed, restarting MPD Jun 07 17:46:56 pyepi sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 17:46:56 pyepi volumio[946]: info: Output device has changed, restarting Shairport Sync Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:56 pyepi sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:56 pyepi sudo[1171]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:56 pyepi sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 17:46:56 pyepi sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:56 pyepi volumio[946]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 17:46:56 pyepi volumio[946]: info: ___________ START PLUGINS ___________ Jun 07 17:46:56 pyepi systemd[1]: Stopping Music Player Daemon... Jun 07 17:46:56 pyepi volumio[946]: info: ControllerMpd::onStart: Initializing MPD Jun 07 17:46:56 pyepi volumio[946]: info: Creating MPD Configuration file Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 17:46:56 pyepi volumio[946]: info: [1749314816728] CoreMusicLibrary::Adding element Media Servers Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:46:56 pyepi sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 17:46:56 pyepi sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:56 pyepi sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:56 pyepi sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:56 pyepi sudo[1180]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:56 pyepi volumio[946]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:56 pyepi volumio[946]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 17:46:56 pyepi volumio[946]: info: [1749314816891] CoreMusicLibrary::Adding element Last_100 Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 17:46:56 pyepi volumio[946]: info: [1749314816895] CoreMusicLibrary::Adding element Webradio Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 17:46:56 pyepi volumio[946]: info: Initializing BBC Radios Jun 07 17:46:56 pyepi volumio[946]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 17:46:56 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:56 pyepi volumio[946]: info: Loading i18n strings for locale en Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: FusionDsp - mixtype--------------------- Hardware Jun 07 17:46:57 pyepi volumio[946]: info: Volumio Calling Home Jun 07 17:46:57 pyepi volumio[946]: info: Preparing to generate the ALSA configuration file Jun 07 17:46:57 pyepi sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 17:46:57 pyepi sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:57 pyepi volumio[946]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 17:46:57 pyepi volumio[946]: info: Reading ALSA contributions from plugins. Jun 07 17:46:57 pyepi sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:46:57 pyepi sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:57 pyepi sudo[1207]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:57 pyepi volumio[946]: info: MPD Permissions set Jun 07 17:46:57 pyepi volumio[946]: info: MPD Permissions set Jun 07 17:46:57 pyepi volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 07 17:46:57 pyepi volumio[946]: info: Volumio called home Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi sudo[1204]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:57 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:57 pyepi systemd[1]: mpd.service: Succeeded. Jun 07 17:46:57 pyepi systemd[1]: Stopped Music Player Daemon. Jun 07 17:46:57 pyepi systemd[1]: Starting Music Player Daemon... Jun 07 17:46:58 pyepi volumio[946]: info: Starting Shairport Sync Jun 07 17:46:58 pyepi volumio[946]: info: Starting Shairport Sync Jun 07 17:46:58 pyepi sudo[1220]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 17:46:58 pyepi sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 17:46:58 pyepi sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 17:46:58 pyepi sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi volumio[946]: info: Starting Shairport Sync Jun 07 17:46:58 pyepi sudo[1220]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:58 pyepi sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 07 17:46:58 pyepi sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::volumioGetState Jun 07 17:46:58 pyepi volumio[946]: info: CorePlayQueue::getTrack 0 Jun 07 17:46:58 pyepi sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 17:46:58 pyepi sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi volumio[946]: info: Asound.conf file unchanged, so no further update is needed Jun 07 17:46:58 pyepi volumio[946]: info: Output device has changed, restarting MPD Jun 07 17:46:58 pyepi systemd[1]: Started UPnP Renderer front-end to MPD. Jun 07 17:46:58 pyepi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 17:46:58 pyepi systemd[1]: shairport-sync.service: Succeeded. Jun 07 17:46:58 pyepi sudo[1228]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:58 pyepi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 17:46:58 pyepi volumio[946]: info: Output device has changed, restarting Shairport Sync Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:58 pyepi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 17:46:58 pyepi sudo[1224]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:58 pyepi sudo[1222]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:58 pyepi sudo[1233]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:58 pyepi sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 17:46:58 pyepi sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 17:46:58 pyepi sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi sudo[1237]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:58 pyepi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 07 17:46:58 pyepi systemd[1]: mpd.service: Succeeded. Jun 07 17:46:58 pyepi systemd[1]: Stopped Music Player Daemon. Jun 07 17:46:58 pyepi volumio[946]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 17:46:58 pyepi systemd[1]: Starting Music Player Daemon... Jun 07 17:46:58 pyepi volumio[946]: info: MPD Permissions set Jun 07 17:46:58 pyepi volumio[946]: info: Shairport-Sync Started Jun 07 17:46:58 pyepi volumio[946]: Error adding Membership: Error: addMembership EINVAL Jun 07 17:46:58 pyepi volumio[946]: info: Shairport-Sync Started Jun 07 17:46:58 pyepi volumio[946]: info: Upmpdcli Daemon Started Jun 07 17:46:58 pyepi sudo[1246]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:58] [connect] Successful connection Jun 07 17:46:58 pyepi sudo[1246]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:46:58 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:58 pyepi volumio-remote-updater[550]: [2025-06-07 17:46:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749314818 101 Jun 07 17:46:58 pyepi volumio[946]: 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: 10 Jun 07 17:46:58 pyepi volumio[946]: info: Starting Shairport Sync Jun 07 17:46:58 pyepi sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 17:46:58 pyepi sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:58 pyepi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 17:46:58 pyepi systemd[1]: shairport-sync.service: Succeeded. Jun 07 17:46:58 pyepi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 17:46:58 pyepi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 17:46:59 pyepi sudo[1257]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:59 pyepi volumio[946]: info: camilladsp service started and running in background, instance 1 Jun 07 17:46:59 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:46:59 pyepi volumio[946]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Jun 07 17:46:59 pyepi volumio[946]: info: FusionDsp loaded Jun 07 17:46:59 pyepi volumio[946]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 17:46:59 pyepi sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 07 17:46:59 pyepi sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:46:59 pyepi volumio[946]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 07 17:46:59 pyepi volumio[946]: info: Adding Signal Path Element [object Object] Jun 07 17:46:59 pyepi volumio[946]: info: Adding fusiondspeq DSP Signal Path Element Jun 07 17:46:59 pyepi volumio[946]: info: FusionDsp - ---- installed callbackRead Jun 07 17:46:59 pyepi volumio[946]: info: Shairport-Sync Started Jun 07 17:46:59 pyepi volumio[946]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 07 17:46:59 pyepi volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 07 17:46:59 pyepi systemd[1]: Started FusionDsp Daemon. Jun 07 17:46:59 pyepi sudo[1278]: pam_unix(sudo:session): session closed for user root Jun 07 17:46:59 pyepi volumio[946]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 07 17:46:59 pyepi volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 07 17:46:59 pyepi volumio[946]: info: CoreStateMachine::ClearQueue Jun 07 17:46:59 pyepi volumio[946]: info: CoreStateMachine::stop Jun 07 17:46:59 pyepi volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 17:46:59 pyepi volumio[946]: info: CorePlayQueue::clearPlayQueue Jun 07 17:46:59 pyepi volumio[946]: info: CorePlayQueue::saveQueue Jun 07 17:46:59 pyepi volumio[946]: info: CoreCommandRouter::volumioPushQueue Jun 07 17:46:59 pyepi volumio[946]: info: CoreStateMachine::addQueueItems Jun 07 17:46:59 pyepi volumio[946]: info: CorePlayQueue::addQueueItems Jun 07 17:46:59 pyepi volumio[946]: info: Preload queue cleared Jun 07 17:46:59 pyepi volumio[946]: info: Adding Item to queue: music-library/NAS/MusicArtists/Imagine Dragons/2017 - Evolve Deluxe Edition (FLAC)/03-imagine_dragons-believer.flac Jun 07 17:46:59 pyepi volumio[946]: info: Exploding uri music-library/NAS/MusicArtists/Imagine Dragons/2017 - Evolve Deluxe Edition (FLAC)/03-imagine_dragons-believer.flac in service mpd Jun 07 17:46:59 pyepi volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 17:46:59 pyepi volumio[946]: TypeError: Cannot read property 'then' of undefined Jun 07 17:46:59 pyepi volumio[946]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2528:19) Jun 07 17:46:59 pyepi volumio[946]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2326:21) Jun 07 17:46:59 pyepi volumio[946]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Jun 07 17:46:59 pyepi volumio[946]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jun 07 17:46:59 pyepi volumio[946]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jun 07 17:46:59 pyepi volumio[946]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Jun 07 17:46:59 pyepi volumio[946]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Jun 07 17:46:59 pyepi volumio[946]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:25) Jun 07 17:46:59 pyepi volumio[946]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:108:33) Jun 07 17:46:59 pyepi volumio[946]: at Socket.emit (events.js:315:20) Jun 07 17:46:59 pyepi volumio[946]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 07 17:46:59 pyepi volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 07 17:46:59 pyepi volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 17:47:01 pyepi sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-07 17:46 Jun 07 17:47:01 pyepi sudo[1291]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"