-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2024-10-14 20:00:01 CEST. -- Oct 14 19:59:00 volumio-martha kernel: rtl8192cu: Tx queue select: 0x05 Oct 14 19:59:00 volumio-martha kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 14 19:59:00 volumio-martha systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 14 19:59:00 volumio-martha systemd[1]: Reached target System Initialization. Oct 14 19:59:00 volumio-martha systemd[1]: apt-daily.timer: Not using persistent file timestamp Fri 2024-11-29 19:09:48 CET as it is in the future. Oct 14 19:59:00 volumio-martha systemd[1]: Started Daily apt download activities. Oct 14 19:59:00 volumio-martha kernel: hwmon hwmon1: Voltage normalised Oct 14 19:59:00 volumio-martha systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 14 19:59:00 volumio-martha systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 14 19:59:01 volumio-martha systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 14 19:59:01 volumio-martha systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Fri 2024-11-29 06:00:42 CET as it is in the future. Oct 14 19:59:01 volumio-martha systemd[1]: Started Daily apt upgrade and clean activities. Oct 14 19:59:01 volumio-martha systemd[1]: Reached target Timers. Oct 14 19:59:01 volumio-martha systemd[1]: Listening on triggerhappy.socket. Oct 14 19:59:01 volumio-martha systemd[1]: Reached target Sockets. Oct 14 19:59:01 volumio-martha systemd[1]: Reached target Basic System. Oct 14 19:59:01 volumio-martha systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 14 19:59:01 volumio-martha systemd[1]: Started UPnP Renderer front-end to MPD. Oct 14 19:59:01 volumio-martha systemd[1]: Starting Wireless Services... Oct 14 19:59:01 volumio-martha systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 14 19:59:01 volumio-martha systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 14 19:59:01 volumio-martha systemd[1]: Started volumio-remote-updater.service. Oct 14 19:59:01 volumio-martha systemd[1]: Starting Login Service... Oct 14 19:59:01 volumio-martha systemd[1]: Started D-Bus System Message Bus. Oct 14 19:59:01 volumio-martha systemd[1]: Started Volumio Iptables Module. Oct 14 19:59:01 volumio-martha systemd[1]: Started Volumio Log Rotation Service. Oct 14 19:59:01 volumio-martha systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Oct 14 19:59:01 volumio-martha systemd[1]: Starting triggerhappy global hotkey daemon... Oct 14 19:59:01 volumio-martha systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 14 19:59:01 volumio-martha systemd[1]: Starting dhcpcd on all interfaces... Oct 14 19:59:01 volumio-martha systemd[1]: Started Manage Sound Card State (restore and store). Oct 14 19:59:01 volumio-martha alsactl[545]: alsactl 1.1.8 daemon started Oct 14 19:59:02 volumio-martha systemd[1]: Starting Save/Restore Sound Card State... Oct 14 19:59:02 volumio-martha thd[541]: Unable to parse trigger line: Oct 14 19:59:02 volumio-martha thd[541]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 14 19:59:02 volumio-martha thd[541]: Unable to parse trigger line: Oct 14 19:59:02 volumio-martha thd[541]: Found socket passed from systemd Oct 14 19:59:02 volumio-martha systemd[1]: Starting WPA supplicant... Oct 14 19:59:02 volumio-martha dhcpcd[542]: Not running dhcpcd because /etc/network/interfaces Oct 14 19:59:02 volumio-martha dhcpcd[542]: defines some interfaces that will use a Oct 14 19:59:02 volumio-martha dhcpcd[542]: DHCP client or static address Oct 14 19:59:02 volumio-martha systemd[1]: Started RPC bind portmap service. Oct 14 19:59:02 volumio-martha systemd[1]: Started triggerhappy global hotkey daemon. Oct 14 19:59:02 volumio-martha systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Oct 14 19:59:02 volumio-martha systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Oct 14 19:59:02 volumio-martha systemd[1]: Failed to start dhcpcd on all interfaces. Oct 14 19:59:02 volumio-martha avahi-daemon[522]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Oct 14 19:59:02 volumio-martha avahi-daemon[522]: Successfully dropped root privileges. Oct 14 19:59:02 volumio-martha avahi-daemon[522]: avahi-daemon 0.7 starting up. Oct 14 19:59:02 volumio-martha systemd-logind[528]: New seat seat0. Oct 14 19:59:02 volumio-martha systemd[1]: Started Save/Restore Sound Card State. Oct 14 19:59:02 volumio-martha systemd[1]: Reached target Sound Card. Oct 14 19:59:02 volumio-martha systemd[1]: Reached target RPC Port Mapper. Oct 14 19:59:02 volumio-martha systemd[1]: Reached target Remote File Systems (Pre). Oct 14 19:59:02 volumio-martha systemd[1]: Reached target Remote File Systems. Oct 14 19:59:02 volumio-martha sh[447]: wlan0=wlan0 Oct 14 19:59:02 volumio-martha systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 14 19:59:03 volumio-martha sh[441]: eth0: waiting for carrier Oct 14 19:59:03 volumio-martha kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Oct 14 19:59:03 volumio-martha kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 14 19:59:03 volumio-martha dhcpcd[490]: eth0: waiting for carrier Oct 14 19:59:03 volumio-martha wpa_supplicant[551]: Successfully initialized wpa_supplicant Oct 14 19:59:03 volumio-martha systemd[1]: Started Login Service. Oct 14 19:59:03 volumio-martha avahi-daemon[522]: Successfully called chroot(). Oct 14 19:59:03 volumio-martha avahi-daemon[522]: Successfully dropped remaining capabilities. Oct 14 19:59:03 volumio-martha avahi-daemon[522]: Loading service file /services/volumio.service. Oct 14 19:59:03 volumio-martha avahi-daemon[522]: Network interface enumeration completed. Oct 14 19:59:03 volumio-martha avahi-daemon[522]: Server startup complete. Host name is volumio-martha.local. Local service cookie is 4102515812. Oct 14 19:59:03 volumio-martha avahi-daemon[522]: Service "Volumio-Martha" (/services/volumio.service) successfully established. Oct 14 19:59:03 volumio-martha systemd[1]: Started Raise network interfaces. Oct 14 19:59:03 volumio-martha systemd[1]: Started WPA supplicant. Oct 14 19:59:03 volumio-martha systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 14 19:59:03 volumio-martha systemd[1]: Reached target Network. Oct 14 19:59:03 volumio-martha volumio-remote-updater[525]: Error: No active session Oct 14 19:59:03 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:03] [info] asio async_connect error: system:111 (Connection refused) Oct 14 19:59:03 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 14 19:59:03 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:03] [error] handle_connect error: Underlying Transport Error Oct 14 19:59:03 volumio-martha systemd[1]: Starting Network Time Service... Oct 14 19:59:03 volumio-martha systemd[1]: Starting OpenBSD Secure Shell server... Oct 14 19:59:03 volumio-martha systemd[1]: Starting Permit User Sessions... Oct 14 19:59:03 volumio-martha volumio[511]: Could not open config: /tmp/upmpdcli.conf Oct 14 19:59:03 volumio-martha systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 14 19:59:03 volumio-martha systemd[1]: Reached target Network is Online. Oct 14 19:59:04 volumio-martha systemd[1]: Starting Samba NMB Daemon... Oct 14 19:59:04 volumio-martha systemd[1]: Starting LSB: Brings up/down network automatically... Oct 14 19:59:04 volumio-martha systemd[1]: Starting /etc/rc.local Compatibility... Oct 14 19:59:04 volumio-martha systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 19:59:04 volumio-martha systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 19:59:04 volumio-martha systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 19:59:04 volumio-martha systemd[1]: systemd-rfkill.service: Succeeded. Oct 14 19:59:04 volumio-martha systemd[1]: Started Permit User Sessions. Oct 14 19:59:04 volumio-martha systemd[1]: Started /etc/rc.local Compatibility. Oct 14 19:59:04 volumio-martha systemd[1]: iptables.service: Succeeded. Oct 14 19:59:04 volumio-martha systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 14 19:59:04 volumio-martha systemd[1]: Starting Hold until boot process finishes up... Oct 14 19:59:04 volumio-martha ntpd[616]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 14 19:59:04 volumio-martha ntpd[616]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Oct 14 19:59:04 volumio-martha systemd[1]: Started Network Time Service. Oct 14 19:59:04 volumio-martha ntpd[657]: proto: precision = 1.719 usec (-19) Oct 14 19:59:04 volumio-martha ntpd[657]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 14 19:59:04 volumio-martha ntpd[657]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 14 19:59:04 volumio-martha ntpd[657]: Listen and drop on 0 v6wildcard [::]:123 Oct 14 19:59:04 volumio-martha ntpd[657]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 14 19:59:04 volumio-martha ntpd[657]: Listen normally on 2 lo 127.0.0.1:123 Oct 14 19:59:04 volumio-martha ntpd[657]: Listening on routing socket on fd #19 for interface updates Oct 14 19:59:04 volumio-martha ntpd[657]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 14 19:59:04 volumio-martha ntpd[657]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 14 19:59:04 volumio-martha systemd[1]: plymouth-quit.service: Succeeded. Oct 14 19:59:04 volumio-martha systemd[1]: Started Terminate Plymouth Boot Screen. Oct 14 19:59:04 volumio-martha systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 14 19:59:04 volumio-martha systemd[1]: Started Hold until boot process finishes up. Oct 14 19:59:04 volumio-martha systemd[1]: Received SIGRTMIN+21 from PID 181 (n/a). Oct 14 19:59:04 volumio-martha systemd[1]: Received SIGRTMIN+21 from PID 181 (n/a). Oct 14 19:59:04 volumio-martha loadcpufreq[571]: Loading cpufreq kernel modules...done (none). Oct 14 19:59:04 volumio-martha systemd[1]: Started Serial Getty on ttyAMA0. Oct 14 19:59:04 volumio-martha ifplugd(eth0)[690]: ifplugd 0.28 initializing. Oct 14 19:59:04 volumio-martha ifplugd(eth0)[690]: Using interface eth0/B8:27:EB:B3:5D:A4 with driver (version: 6.1.69-v7+) Oct 14 19:59:04 volumio-martha ifplugd(eth0)[690]: Using detection mode: SIOCETHTOOL Oct 14 19:59:04 volumio-martha ifplugd(eth0)[690]: Initialization complete, link beat not detected. Oct 14 19:59:05 volumio-martha systemd[1]: Started Getty on tty1. Oct 14 19:59:05 volumio-martha systemd[1]: Reached target Login Prompts. Oct 14 19:59:05 volumio-martha systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 14 19:59:05 volumio-martha systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 14 19:59:05 volumio-martha ifplugd[629]: Network Interface Plugging Daemon...start eth0...done. Oct 14 19:59:05 volumio-martha systemd[1]: Started LSB: Brings up/down network automatically. Oct 14 19:59:05 volumio-martha sshd[692]: Server listening on 0.0.0.0 port 22. Oct 14 19:59:05 volumio-martha sshd[692]: Server listening on :: port 22. Oct 14 19:59:05 volumio-martha systemd[1]: Started OpenBSD Secure Shell server. Oct 14 19:59:05 volumio-martha haveged[496]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Oct 14 19:59:05 volumio-martha haveged[496]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Oct 14 19:59:05 volumio-martha haveged[496]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99983 Oct 14 19:59:05 volumio-martha haveged[496]: haveged: fills: 0, generated: 0 Oct 14 19:59:05 volumio-martha cpufrequtils[693]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 14 19:59:05 volumio-martha systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 14 19:59:05 volumio-martha ntpd[657]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 14 19:59:06 volumio-martha nmbd[645]: [2024/10/14 19:59:06.264646, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 14 19:59:06 volumio-martha nmbd[645]: started asyncdns process 706 Oct 14 19:59:06 volumio-martha nmbd[645]: [2024/10/14 19:59:06.271293, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 14 19:59:06 volumio-martha nmbd[645]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 14 19:59:06 volumio-martha nmbd[645]: [2024/10/14 19:59:06.272067, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 14 19:59:06 volumio-martha nmbd[645]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 14 19:59:06 volumio-martha ntpd[657]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 14 19:59:07 volumio-martha wireless.js[519]: Cleaning previous... Oct 14 19:59:07 volumio-martha sudo[714]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 14 19:59:07 volumio-martha sudo[714]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:07 volumio-martha sudo[714]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:07 volumio-martha sudo[716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 14 19:59:07 volumio-martha sudo[716]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:07 volumio-martha ntpd[657]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 14 19:59:08 volumio-martha sudo[716]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:08 volumio-martha ntpd[657]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 14 19:59:08 volumio-martha wireless.js[519]: Stopped aP Oct 14 19:59:08 volumio-martha sudo[723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 14 19:59:08 volumio-martha sudo[723]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:08 volumio-martha kernel: rtl8192cu: MAC auto ON okay! Oct 14 19:59:08 volumio-martha kernel: rtl8192cu: Tx queue select: 0x05 Oct 14 19:59:09 volumio-martha sudo[723]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:09 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:09] [info] asio async_connect error: system:111 (Connection refused) Oct 14 19:59:09 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 14 19:59:09 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:09] [error] handle_connect error: Underlying Transport Error Oct 14 19:59:09 volumio-martha sudo[725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 14 19:59:09 volumio-martha sudo[725]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:09 volumio-martha sudo[725]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:09 volumio-martha sudo[732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 14 19:59:09 volumio-martha sudo[732]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:09 volumio-martha sudo[732]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:09 volumio-martha sudo[734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 14 19:59:09 volumio-martha sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:11 volumio-martha sudo[734]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:11 volumio-martha wireless.js[519]: SETTING APPROPRIATE REG DOMAIN: CZ Oct 14 19:59:11 volumio-martha sudo[739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 14 19:59:11 volumio-martha sudo[739]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:11 volumio-martha sudo[739]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:11 volumio-martha sudo[741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CZ Oct 14 19:59:11 volumio-martha sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:11 volumio-martha sudo[741]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:11 volumio-martha wireless.js[519]: SUCCESSFULLY SET NEW REGDOMAIN: CZ Oct 14 19:59:11 volumio-martha wireless.js[519]: Start wireless flow Oct 14 19:59:11 volumio-martha wireless.js[519]: Stopped hotspot (if there).. Oct 14 19:59:11 volumio-martha sudo[746]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 14 19:59:11 volumio-martha sudo[746]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:11 volumio-martha sudo[746]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:11 volumio-martha sudo[748]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 14 19:59:11 volumio-martha sudo[748]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:11 volumio-martha sudo[748]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:11 volumio-martha wireless.js[519]: DHCP IP Oct 14 19:59:11 volumio-martha wireless.js[519]: Start ap Oct 14 19:59:11 volumio-martha wpa_supplicant[750]: Successfully initialized wpa_supplicant Oct 14 19:59:11 volumio-martha sudo[751]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 14 19:59:11 volumio-martha kernel: rtl8192cu: MAC auto ON okay! Oct 14 19:59:11 volumio-martha sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:11 volumio-martha dhcpcd[752]: dev: loaded udev Oct 14 19:59:11 volumio-martha kernel: rtl8192cu: Tx queue select: 0x05 Oct 14 19:59:12 volumio-martha wireless.js[519]: trying... Oct 14 19:59:12 volumio-martha sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:12 volumio-martha sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:12 volumio-martha sudo[754]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:12 volumio-martha dhcpcd[752]: wlan0: connected to Access Point `' Oct 14 19:59:12 volumio-martha dhcpcd[752]: no interfaces have a carrier Oct 14 19:59:12 volumio-martha dhcpcd[752]: forked to background, child pid 783 Oct 14 19:59:12 volumio-martha dhcpcd[783]: eth0: waiting for carrier Oct 14 19:59:12 volumio-martha dhcpcd[783]: wlan0: waiting for carrier Oct 14 19:59:12 volumio-martha sudo[751]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:13 volumio-martha wireless.js[519]: trying... Oct 14 19:59:13 volumio-martha sudo[785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:13 volumio-martha sudo[785]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:13 volumio-martha sudo[785]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:14 volumio-martha wpa_supplicant[773]: wlan0: SME: Trying to authenticate with 78:45:58:b7:9c:5a (SSID='drakoland' freq=2437 MHz) Oct 14 19:59:14 volumio-martha kernel: wlan0: authenticate with 78:45:58:b7:9c:5a Oct 14 19:59:14 volumio-martha kernel: wlan0: 80 MHz not supported, disabling VHT Oct 14 19:59:14 volumio-martha wpa_supplicant[773]: wlan0: Trying to associate with 78:45:58:b7:9c:5a (SSID='drakoland' freq=2437 MHz) Oct 14 19:59:14 volumio-martha kernel: wlan0: send auth to 78:45:58:b7:9c:5a (try 1/3) Oct 14 19:59:14 volumio-martha kernel: wlan0: authenticated Oct 14 19:59:14 volumio-martha kernel: wlan0: associate with 78:45:58:b7:9c:5a (try 1/3) Oct 14 19:59:14 volumio-martha kernel: wlan0: RX AssocResp from 78:45:58:b7:9c:5a (capab=0x1031 status=0 aid=1) Oct 14 19:59:14 volumio-martha kernel: wlan0: associated Oct 14 19:59:14 volumio-martha kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 78:45:58:b7:9c:5a Oct 14 19:59:14 volumio-martha wpa_supplicant[773]: wlan0: Associated with 78:45:58:b7:9c:5a Oct 14 19:59:14 volumio-martha wpa_supplicant[773]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 14 19:59:14 volumio-martha wpa_supplicant[773]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CZ Oct 14 19:59:14 volumio-martha kernel: cryptd: max_cpu_qlen set to 1000 Oct 14 19:59:14 volumio-martha wpa_supplicant[773]: wlan0: WPA: Key negotiation completed with 78:45:58:b7:9c:5a [PTK=CCMP GTK=CCMP] Oct 14 19:59:14 volumio-martha wpa_supplicant[773]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:45:58:b7:9c:5a completed [id=0 id_str=] Oct 14 19:59:14 volumio-martha dhcpcd[783]: wlan0: carrier acquired Oct 14 19:59:14 volumio-martha dhcpcd[783]: wlan0: connected to Access Point `drakoland' Oct 14 19:59:14 volumio-martha dhcpcd[783]: DUID 00:01:00:01:2d:4b:cd:21:74:da:38:e4:64:43 Oct 14 19:59:14 volumio-martha dhcpcd[783]: wlan0: IAID 38:e4:64:43 Oct 14 19:59:14 volumio-martha dhcpcd[783]: wlan0: adding address fe80::3b5e:6940:832a:e271 Oct 14 19:59:14 volumio-martha dhcpcd[783]: ipv6_addaddr1: Permission denied Oct 14 19:59:14 volumio-martha wireless.js[519]: trying... Oct 14 19:59:14 volumio-martha sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:14 volumio-martha sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:14 volumio-martha sudo[814]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:14 volumio-martha dhcpcd[783]: wlan0: rebinding lease of 192.168.211.151 Oct 14 19:59:14 volumio-martha dhcpcd[783]: wlan0: soliciting an IPv6 router Oct 14 19:59:14 volumio-martha dhcpcd[783]: wlan0: probing address 192.168.211.151/24 Oct 14 19:59:15 volumio-martha wireless.js[519]: trying... Oct 14 19:59:15 volumio-martha sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:15 volumio-martha sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:15 volumio-martha sudo[817]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:16 volumio-martha wireless.js[519]: trying... Oct 14 19:59:16 volumio-martha sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:16 volumio-martha sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:16 volumio-martha sudo[820]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:17 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:17] [info] asio async_connect error: system:111 (Connection refused) Oct 14 19:59:17 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 14 19:59:17 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:17] [error] handle_connect error: Underlying Transport Error Oct 14 19:59:17 volumio-martha wireless.js[519]: trying... Oct 14 19:59:17 volumio-martha sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:17 volumio-martha sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:17 volumio-martha sudo[823]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:18 volumio-martha wireless.js[519]: trying... Oct 14 19:59:18 volumio-martha sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:18 volumio-martha sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:18 volumio-martha sudo[826]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:19 volumio-martha wireless.js[519]: trying... Oct 14 19:59:19 volumio-martha sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:19 volumio-martha sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:19 volumio-martha sudo[829]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:19 volumio-martha dhcpcd[783]: wlan0: leased 192.168.211.151 for 43200 seconds Oct 14 19:59:19 volumio-martha avahi-daemon[522]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.151. Oct 14 19:59:19 volumio-martha dhcpcd[783]: wlan0: adding route to 192.168.211.0/24 Oct 14 19:59:19 volumio-martha avahi-daemon[522]: New relevant interface wlan0.IPv4 for mDNS. Oct 14 19:59:19 volumio-martha dhcpcd[783]: wlan0: adding default route via 192.168.211.1 Oct 14 19:59:19 volumio-martha avahi-daemon[522]: Registering new address record for 192.168.211.151 on wlan0.IPv4. Oct 14 19:59:20 volumio-martha wireless.js[519]: trying... Oct 14 19:59:20 volumio-martha sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 14 19:59:20 volumio-martha sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:20 volumio-martha sudo[853]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:20 volumio-martha wireless.js[519]: Connected to: ----drakoland Oct 14 19:59:20 volumio-martha wireless.js[519]: ---- Oct 14 19:59:20 volumio-martha sudo[856]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 19:59:20 volumio-martha sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:20 volumio-martha sudo[856]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:20 volumio-martha wireless.js[519]: ... joined AP, wlan0 IPv4 is 192.168.211.151, ipV6 is undefined Oct 14 19:59:20 volumio-martha wireless.js[519]: It's done! AP Oct 14 19:59:20 volumio-martha iwconfig[860]: Error for wireless request "Set Power Management" (8B2C) : Oct 14 19:59:20 volumio-martha iwconfig[860]: SET failed on device wlan0 ; Operation not supported. Oct 14 19:59:20 volumio-martha systemd[1]: Started Wireless Services. Oct 14 19:59:20 volumio-martha systemd[1]: Started Volumio Backend Module. Oct 14 19:59:20 volumio-martha systemd[1]: Started Volumio Cpu Tweaker. Oct 14 19:59:21 volumio-martha volumio-cpu-tweak[862]: Setting RT Priority for mpd Oct 14 19:59:21 volumio-martha volumio-cpu-tweak[862]: pid 35's current scheduling policy: SCHED_OTHER Oct 14 19:59:21 volumio-martha volumio-cpu-tweak[862]: pid 35's current scheduling priority: 0 Oct 14 19:59:21 volumio-martha volumio-cpu-tweak[862]: Setting MPD Affinity Oct 14 19:59:21 volumio-martha volumio-cpu-tweak[862]: pid 3's current affinity mask: f Oct 14 19:59:21 volumio-martha volumio-cpu-tweak[862]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 14 19:59:21 volumio-martha volumio-cpu-tweak[862]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 14 19:59:21 volumio-martha systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 14 19:59:21 volumio-martha nmbd[645]: [2024/10/14 19:59:21.285220, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 14 19:59:21 volumio-martha nmbd[645]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 14 19:59:21 volumio-martha systemd[1]: Started Samba NMB Daemon. Oct 14 19:59:21 volumio-martha systemd[1]: Starting Samba Winbind Daemon... Oct 14 19:59:21 volumio-martha nmbd[645]: [2024/10/14 19:59:21.388264, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 14 19:59:21 volumio-martha nmbd[645]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.211.151 for name WORKGROUP<1d>. Oct 14 19:59:21 volumio-martha nmbd[645]: This response was from IP 192.168.211.194, reporting an IP address of 192.168.211.194. Oct 14 19:59:21 volumio-martha ntpd[657]: Listen normally on 3 wlan0 192.168.211.151:123 Oct 14 19:59:21 volumio-martha ntpd[657]: new interface(s) found: waking up resolver Oct 14 19:59:21 volumio-martha winbindd[878]: [2024/10/14 19:59:21.934318, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 14 19:59:21 volumio-martha winbindd[878]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 14 19:59:21 volumio-martha winbindd[878]: [2024/10/14 19:59:21.971606, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 14 19:59:21 volumio-martha winbindd[878]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 14 19:59:21 volumio-martha systemd[1]: Started Samba Winbind Daemon. Oct 14 19:59:22 volumio-martha systemd[1]: Starting Samba SMB Daemon... Oct 14 19:59:22 volumio-martha winbindd[878]: [2024/10/14 19:59:22.839169, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Oct 14 19:59:22 volumio-martha winbindd[878]: res_names->count = 3, expected 4 Oct 14 19:59:22 volumio-martha systemd[1]: Started Samba SMB Daemon. Oct 14 19:59:22 volumio-martha smbd[883]: [2024/10/14 19:59:22.926269, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 14 19:59:22 volumio-martha systemd[1]: Reached target Multi-User System. Oct 14 19:59:22 volumio-martha smbd[883]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 14 19:59:22 volumio-martha systemd[1]: Reached target Graphical Interface. Oct 14 19:59:22 volumio-martha systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 14 19:59:23 volumio-martha systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 14 19:59:23 volumio-martha systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 14 19:59:23 volumio-martha systemd[1]: Startup finished in 18.135s (kernel) + 31.973s (userspace) = 50.108s. Oct 14 19:59:26 volumio-martha volumio[861]: info: ------------------------------------------- Oct 14 19:59:26 volumio-martha volumio[861]: info: ----- Volumio3 ---- Oct 14 19:59:26 volumio-martha volumio[861]: info: ------------------------------------------- Oct 14 19:59:26 volumio-martha volumio[861]: info: ----- System startup ---- Oct 14 19:59:26 volumio-martha volumio[861]: info: ------------------------------------------- Oct 14 19:59:27 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:27] [connect] Successful connection Oct 14 19:59:28 volumio-martha volumio[861]: info: MYVOLUMIO Environment detected Oct 14 19:59:28 volumio-martha volumio[861]: info: Plugin folders cleanup Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning into folder /volumio/app/plugins/ Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning category audio_interface Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning category miscellanea Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning category music_service Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning category plugins.json Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning category system_controller Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning category user_interface Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning into folder /data/plugins/ Oct 14 19:59:28 volumio-martha volumio[861]: info: Scanning category music_service Oct 14 19:59:28 volumio-martha volumio[861]: info: Plugin folders cleanup completed Oct 14 19:59:28 volumio-martha volumio[861]: info: ------------------------------------------- Oct 14 19:59:28 volumio-martha volumio[861]: info: ----- Core plugins startup ---- Oct 14 19:59:28 volumio-martha volumio[861]: info: ------------------------------------------- Oct 14 19:59:28 volumio-martha volumio[861]: info: Loading plugins from folder /volumio/app/plugins/ Oct 14 19:59:28 volumio-martha volumio[861]: info: Adding plugin upnp to MyMusic Plugins Oct 14 19:59:28 volumio-martha volumio[861]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 14 19:59:28 volumio-martha volumio[861]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 14 19:59:28 volumio-martha volumio[861]: info: Loading plugins from folder /data/plugins/ Oct 14 19:59:28 volumio-martha volumio[861]: info: Loading plugin "system"... Oct 14 19:59:28 volumio-martha volumio[861]: info: Loading plugin "appearance"... Oct 14 19:59:29 volumio-martha systemd[1]: systemd-fsckd.service: Succeeded. Oct 14 19:59:31 volumio-martha volumio[861]: info: Loading plugin "network"... Oct 14 19:59:31 volumio-martha volumio[861]: info: Refreshing Cached IP Addresses Oct 14 19:59:31 volumio-martha sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 19:59:31 volumio-martha sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:31 volumio-martha volumio[861]: info: Loading plugin "services"... Oct 14 19:59:31 volumio-martha sudo[906]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:31 volumio-martha sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 19:59:31 volumio-martha sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:31 volumio-martha volumio[861]: info: Loading plugin "alsa_controller"... Oct 14 19:59:31 volumio-martha sudo[908]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:31 volumio-martha sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 14 19:59:31 volumio-martha sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:31 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 19:59:31 volumio-martha volumio[861]: info: Loading plugin "wizard"... Oct 14 19:59:31 volumio-martha volumio[861]: info: Loading plugin "networkfs"... Oct 14 19:59:32 volumio-martha volumio[861]: info: Starting Udev Watcher for removable devices Oct 14 19:59:32 volumio-martha sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=elektro,password=elektro,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.211.1/srv6tb/Hudba/ /mnt/NAS/NAS Oct 14 19:59:32 volumio-martha sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:32 volumio-martha volumio[861]: info: Ignoring mount for partition: boot Oct 14 19:59:32 volumio-martha volumio[861]: info: Ignoring mount for partition: volumio Oct 14 19:59:32 volumio-martha volumio[861]: info: Ignoring mount for partition: volumio_data Oct 14 19:59:32 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 19:59:32 volumio-martha volumio[861]: info: Loading plugin "volumio_command_line_client"... Oct 14 19:59:32 volumio-martha volumio[861]: info: Loading plugin "upnp"... Oct 14 19:59:32 volumio-martha volumio[861]: info: [1728928772228] Starting Upmpd Daemon Oct 14 19:59:32 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 19:59:32 volumio-martha volumio[861]: info: Loading plugin "my_music"... Oct 14 19:59:32 volumio-martha volumio[861]: info: Loading plugin "mpd"... Oct 14 19:59:32 volumio-martha kernel: Key type cifs.spnego registered Oct 14 19:59:32 volumio-martha kernel: Key type cifs.idmap registered Oct 14 19:59:32 volumio-martha 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. Oct 14 19:59:32 volumio-martha kernel: CIFS: Attempting to mount \\192.168.211.1\srv6tb Oct 14 19:59:33 volumio-martha sh[441]: timed out Oct 14 19:59:33 volumio-martha dhcpcd[490]: timed out Oct 14 19:59:33 volumio-martha sh[441]: dhcpcd exited Oct 14 19:59:33 volumio-martha dhcpcd[490]: dhcpcd exited Oct 14 19:59:33 volumio-martha sh[441]: ifup: failed to bring up eth0 Oct 14 19:59:33 volumio-martha systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 14 19:59:33 volumio-martha systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 14 19:59:33 volumio-martha sudo[919]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:33 volumio-martha volumio[861]: info: Loading plugin "upnp_browser"... Oct 14 19:59:34 volumio-martha volumio[861]: info: Loading plugin "alarm-clock"... Oct 14 19:59:34 volumio-martha volumio[861]: info: Loading plugin "airplay_emulation"... Oct 14 19:59:34 volumio-martha volumio[861]: info: Starting Shairport Sync Oct 14 19:59:34 volumio-martha volumio[861]: info: Loading plugin "last_100"... Oct 14 19:59:34 volumio-martha volumio[861]: info: Loading plugin "webradio"... Oct 14 19:59:34 volumio-martha volumio[861]: info: Loading plugin "i2s_dacs"... Oct 14 19:59:34 volumio-martha volumio[861]: info: Loading plugin "volumiodiscovery"... Oct 14 19:59:35 volumio-martha volumio[861]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 14 19:59:35 volumio-martha node[861]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 14 19:59:35 volumio-martha volumio[861]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 14 19:59:35 volumio-martha node[861]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 14 19:59:35 volumio-martha volumio[861]: *** WARNING *** For more information see Oct 14 19:59:35 volumio-martha node[861]: *** WARNING *** For more information see Oct 14 19:59:35 volumio-martha volumio[861]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 14 19:59:35 volumio-martha node[861]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 14 19:59:35 volumio-martha volumio[861]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 14 19:59:35 volumio-martha node[861]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 14 19:59:35 volumio-martha volumio[861]: *** WARNING *** For more information see Oct 14 19:59:35 volumio-martha node[861]: *** WARNING *** For more information see Oct 14 19:59:35 volumio-martha volumio[861]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 14 19:59:35 volumio-martha volumio[861]: info: Discovery: Started advertising with name: Volumio-Martha Oct 14 19:59:35 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 19:59:35 volumio-martha volumio[861]: info: Loading plugin "spop"... Oct 14 19:59:35 volumio-martha sudo[911]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:38 volumio-martha volumio[861]: info: Loading plugin "outputs"... Oct 14 19:59:38 volumio-martha volumio[861]: info: Loading plugin "albumart"... Oct 14 19:59:38 volumio-martha volumio[861]: info: Plugin example_plugin is not enabled Oct 14 19:59:38 volumio-martha volumio[861]: info: Loading plugin "inputs"... Oct 14 19:59:38 volumio-martha volumio[861]: info: Loading plugin "updater_comm"... Oct 14 19:59:38 volumio-martha volumio[861]: info: Plugin mpdemulation is not enabled Oct 14 19:59:38 volumio-martha volumio[861]: info: Loading plugin "rest_api"... Oct 14 19:59:38 volumio-martha volumio[861]: info: Loading plugin "websocket"... Oct 14 19:59:38 volumio-martha volumio[861]: info: Starting Socket.io Server version 2.3.0 Oct 14 19:59:38 volumio-martha volumio[861]: info: Loading i18n strings for locale cs Oct 14 19:59:38 volumio-martha volumio[861]: Updating browse sources language Oct 14 19:59:38 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 19:59:38 volumio-martha volumio[861]: Forking 3 albumart workers Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::initPlayerControls Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 19:59:39 volumio-martha volumio[861]: Express server listening on port 3000 Oct 14 19:59:39 volumio-martha volumio[861]: [Metrics] WebUI: 14s 916.29ms Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreStateMachine::resetVolumioState Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreStateMachine::getcurrentVolume Oct 14 19:59:39 volumio-martha volumio[861]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 19:59:40 volumio-martha volumio[861]: verbose: New Socket.io Connection to 192.168.211.151:3000 from 192.168.211.223 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 14 19:59:40 volumio-martha volumio[861]: verbose: New Socket.io Connection to 192.168.211.151:3000 from 192.168.211.223 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:40 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:40 volumio-martha volumio[861]: info: Reloading queue from file Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 19:59:40 volumio-martha volumio[861]: info: VolumeController:: Volume=29 Mute =false Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreStateMachine::pushState Oct 14 19:59:40 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::volumioPushState Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreStateMachine::updateTrackBlock Oct 14 19:59:40 volumio-martha volumio[861]: info: CorePlayQueue::getTrackBlock Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreStateMachine::setRepeat true single undefined Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreStateMachine::pushState Oct 14 19:59:40 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::volumioPushState Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreStateMachine::setRandom false Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreStateMachine::pushState Oct 14 19:59:40 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::volumioPushState Oct 14 19:59:40 volumio-martha volumio[861]: info: Setting Device type: Raspberry PI Oct 14 19:59:40 volumio-martha volumio[861]: info: Listing playlists Oct 14 19:59:40 volumio-martha volumio[861]: info: Listing playlists Oct 14 19:59:40 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 14 19:59:41 volumio-martha volumio[861]: info: Discovery: adding 949b4ddc-6437-4ede-96bd-bf12b6723f7b Oct 14 19:59:41 volumio-martha volumio[861]: info: Discovery: Found device Volumio-Martha Oct 14 19:59:41 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:41 volumio-martha volumio[861]: Starting albumart workers Oct 14 19:59:41 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:41 volumio-martha volumio[861]: Starting albumart workers Oct 14 19:59:41 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:41 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:41 volumio-martha volumio[861]: Starting albumart workers Oct 14 19:59:41 volumio-martha volumio[861]: info: VolumeController:: Volume=29 Mute =false Oct 14 19:59:41 volumio-martha volumio[861]: info: CoreStateMachine::pushState Oct 14 19:59:41 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:41 volumio-martha volumio[861]: info: CoreCommandRouter::volumioPushState Oct 14 19:59:41 volumio-martha volumio[861]: info: Discovery: adding 58ccddf8-abbd-4132-979e-b64a083a4902 Oct 14 19:59:41 volumio-martha volumio[861]: info: Discovery: Found device Volumio-Jorgos Oct 14 19:59:41 volumio-martha volumio[861]: info: Discovery: Connecting to remote: 192.168.211.223 Oct 14 19:59:41 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:41 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:41 volumio-martha volumio[861]: info: Completed loading Core Plugins Oct 14 19:59:41 volumio-martha volumio[861]: info: Preparing to generate the ALSA configuration file Oct 14 19:59:41 volumio-martha volumio[861]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 14 19:59:41 volumio-martha volumio[861]: info: Reading ALSA contributions from plugins. Oct 14 19:59:42 volumio-martha volumio[861]: info: Discovery: Connected to remote: 192.168.211.223 Oct 14 19:59:42 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:42] [connect] Successful connection Oct 14 19:59:42 volumio-martha sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 19:59:42 volumio-martha sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:42 volumio-martha sudo[994]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:42 volumio-martha sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 19:59:42 volumio-martha sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:42 volumio-martha volumio-remote-updater[525]: [2024-10-14 19:59:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728928782 101 Oct 14 19:59:42 volumio-martha sudo[996]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:42 volumio-martha volumio[861]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Oct 14 19:59:42 volumio-martha volumio[861]: info: Asound.conf file unchanged, so no further update is needed Oct 14 19:59:42 volumio-martha volumio[861]: info: Output device has changed, restarting MPD Oct 14 19:59:42 volumio-martha volumio[861]: info: Output device has changed, restarting Shairport Sync Oct 14 19:59:42 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:42 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 19:59:42 volumio-martha sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 19:59:42 volumio-martha sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:42 volumio-martha sudo[1008]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:42 volumio-martha sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 19:59:42 volumio-martha sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:42 volumio-martha volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 19:59:42 volumio-martha volumio[861]: info: ___________ START PLUGINS ___________ Oct 14 19:59:42 volumio-martha volumio[861]: info: ControllerMpd::onStart: Initializing MPD Oct 14 19:59:42 volumio-martha volumio[861]: info: Creating MPD Configuration file Oct 14 19:59:43 volumio-martha systemd[1]: Listening on mpd.socket. Oct 14 19:59:43 volumio-martha systemd[1]: Starting Music Player Daemon... Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 14 19:59:43 volumio-martha volumio[861]: info: [1728928783045] CoreMusicLibrary::Adding element Média servery Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 19:59:43 volumio-martha sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 19:59:43 volumio-martha sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:43 volumio-martha sudo[1034]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:43 volumio-martha sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 19:59:43 volumio-martha sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 19:59:43 volumio-martha systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 14 19:59:43 volumio-martha systemd[1]: mpd.service: Succeeded. Oct 14 19:59:43 volumio-martha systemd[1]: Stopped Music Player Daemon. Oct 14 19:59:43 volumio-martha volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 19:59:43 volumio-martha systemd[1]: Starting Music Player Daemon... Oct 14 19:59:43 volumio-martha volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 14 19:59:43 volumio-martha volumio[861]: info: [1728928783314] CoreMusicLibrary::Adding element Last_100 Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 14 19:59:43 volumio-martha volumio[861]: info: [1728928783321] CoreMusicLibrary::Adding element Webradio Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 19:59:43 volumio-martha volumio[861]: info: Initializing BBC Radios Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 19:59:43 volumio-martha sudo[1043]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 19:59:43 volumio-martha sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:43 volumio-martha sudo[1043]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 14 19:59:43 volumio-martha sudo[1043]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:43 volumio-martha volumio[861]: info: Creating Spotify config file Oct 14 19:59:43 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:43 volumio-martha volumio[861]: info: Volumio Calling Home Oct 14 19:59:43 volumio-martha volumio[861]: info: MPD Permissions set Oct 14 19:59:43 volumio-martha volumio[861]: info: MPD Permissions set Oct 14 19:59:43 volumio-martha volumio[861]: info: Volumio called home Oct 14 19:59:43 volumio-martha volumio[861]: info: Spotify config file written Oct 14 19:59:43 volumio-martha sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 14 19:59:44 volumio-martha sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:44 volumio-martha volumio[861]: error: error Oct 14 19:59:44 volumio-martha sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 14 19:59:44 volumio-martha sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:44 volumio-martha volumio[861]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha systemd[1]: Started UPnP Renderer front-end to MPD. Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha sudo[1063]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 19:59:44 volumio-martha systemd[1]: Started go-librespot Daemon. Oct 14 19:59:44 volumio-martha go-librespot[1077]: Librespot-go daemon starting... Oct 14 19:59:44 volumio-martha sudo[1065]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:44 volumio-martha volumio[861]: info: Upmpdcli Daemon Started Oct 14 19:59:44 volumio-martha volumio[861]: info: Starting Shairport Sync Oct 14 19:59:44 volumio-martha volumio[861]: info: Starting Shairport Sync Oct 14 19:59:44 volumio-martha volumio[861]: info: Starting Shairport Sync Oct 14 19:59:44 volumio-martha sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 19:59:44 volumio-martha sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:44 volumio-martha sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 19:59:44 volumio-martha sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:44 volumio-martha sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 19:59:44 volumio-martha sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:44 volumio-martha systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 19:59:44 volumio-martha systemd[1]: shairport-sync.service: Succeeded. Oct 14 19:59:44 volumio-martha systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 19:59:44 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:44 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:44 volumio-martha systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 19:59:44 volumio-martha sudo[1080]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:44 volumio-martha volumio[861]: info: Shairport-Sync Started Oct 14 19:59:44 volumio-martha volumio[861]: Error adding Membership: Error: addMembership EINVAL Oct 14 19:59:44 volumio-martha systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 19:59:44 volumio-martha systemd[1]: shairport-sync.service: Succeeded. Oct 14 19:59:44 volumio-martha systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 19:59:45 volumio-martha systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 19:59:45 volumio-martha sudo[1083]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:45 volumio-martha sudo[1089]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:45 volumio-martha volumio[861]: info: Shairport-Sync Started Oct 14 19:59:45 volumio-martha volumio[861]: info: Shairport-Sync Started Oct 14 19:59:45 volumio-martha go-librespot[1077]: time="2024-10-14T19:59:45+02:00" level=info msg="generated new device id: a9dcd6d9b8262a0309958d2fe4154f65eac327ae" Oct 14 19:59:46 volumio-martha sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 19:59:46 volumio-martha sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 19:59:46 volumio-martha sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:46 volumio-martha sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:46 volumio-martha sudo[1101]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:46 volumio-martha sudo[1103]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:46 volumio-martha volumio[861]: verbose: New Socket.io Connection to 192.168.211.151 from 192.168.211.107 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 14 19:59:46 volumio-martha go-librespot[1077]: time="2024-10-14T19:59:46+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 19:59:46 volumio-martha go-librespot[1077]: time="2024-10-14T19:59:46+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 19:59:46 volumio-martha go-librespot[1077]: time="2024-10-14T19:59:46+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 19:59:46 volumio-martha go-librespot[1077]: time="2024-10-14T19:59:46+02:00" level=debug msg="zeroconf server listening on port 37599" Oct 14 19:59:46 volumio-martha sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 19:59:46 volumio-martha sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:46 volumio-martha sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 19:59:46 volumio-martha sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 19:59:46 volumio-martha sudo[1107]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:46 volumio-martha sudo[1109]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:46 volumio-martha volumio[861]: verbose: New Socket.io Connection to 192.168.211.151 from 192.168.211.107 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:46 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 19:59:46 volumio-martha volumio[861]: info: Received Get System Info Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 19:59:46 volumio-martha volumio[861]: info: Discovery: Getting this device information Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:46 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:46 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:46 volumio-martha volumio[861]: info: Listing playlists Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 14 19:59:46 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 19:59:49 volumio-martha mpd[1058]: Oct 14 19:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 19:59:49 volumio-martha systemd[1]: Started Music Player Daemon. Oct 14 19:59:49 volumio-martha sudo[1015]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:49 volumio-martha sudo[1037]: pam_unix(sudo:session): session closed for user root Oct 14 19:59:59 volumio-martha volumio[861]: info: go-librespot daemon successfully initialized Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetQueue Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreStateMachine::getQueue Oct 14 19:59:59 volumio-martha volumio[861]: info: CorePlayQueue::getQueue Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 19:59:59 volumio-martha volumio[861]: info: Received Get System Info Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 19:59:59 volumio-martha volumio[861]: info: Discovery: Getting this device information Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:59 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 19:59:59 volumio-martha volumio[861]: info: Received Get System Info Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 19:59:59 volumio-martha volumio[861]: info: Discovery: Getting this device information Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::volumioGetState Oct 14 19:59:59 volumio-martha volumio[861]: info: CorePlayQueue::getTrack 0 Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 19:59:59 volumio-martha volumio[861]: info: CURURI: artists://Skibi%20Kids Oct 14 19:59:59 volumio-martha volumio[861]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Oct 14 19:59:59 volumio-martha volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 19:59:59 volumio-martha volumio[861]: info: CURURI: artists://Skibi%20Kids Oct 14 19:59:59 volumio-martha volumio[861]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Oct 14 19:59:59 volumio-martha volumio[861]: info: Preload queue cleared Oct 14 20:00:00 volumio-martha volumio[861]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 20:00:00 volumio-martha volumio[861]: info: CoreStateMachine::ClearQueue Oct 14 20:00:00 volumio-martha volumio[861]: info: CoreStateMachine::stop Oct 14 20:00:00 volumio-martha volumio[861]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 20:00:00 volumio-martha volumio[861]: info: CorePlayQueue::clearPlayQueue Oct 14 20:00:00 volumio-martha volumio[861]: info: CorePlayQueue::saveQueue Oct 14 20:00:00 volumio-martha volumio[861]: info: CoreCommandRouter::volumioPushQueue Oct 14 20:00:00 volumio-martha volumio[861]: info: CoreStateMachine::addQueueItems Oct 14 20:00:00 volumio-martha volumio[861]: info: CorePlayQueue::addQueueItems Oct 14 20:00:00 volumio-martha volumio[861]: info: Preload queue cleared Oct 14 20:00:00 volumio-martha volumio[861]: info: Adding Item to queue: artists://Skibi%20Kids Oct 14 20:00:00 volumio-martha volumio[861]: info: Exploding uri artists://Skibi%20Kids in service mpd Oct 14 20:00:00 volumio-martha volumio[861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 20:00:00 volumio-martha volumio[861]: TypeError: Cannot read property 'sendCommand' of undefined Oct 14 20:00:00 volumio-martha volumio[861]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2187:20) Oct 14 20:00:00 volumio-martha volumio[861]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1365:16) Oct 14 20:00:00 volumio-martha volumio[861]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Oct 14 20:00:00 volumio-martha volumio[861]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Oct 14 20:00:00 volumio-martha volumio[861]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Oct 14 20:00:00 volumio-martha volumio[861]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Oct 14 20:00:00 volumio-martha volumio[861]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:513:23) Oct 14 20:00:00 volumio-martha volumio[861]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:483:17) Oct 14 20:00:00 volumio-martha volumio[861]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Oct 14 20:00:00 volumio-martha volumio[861]: at Socket.emit (events.js:315:20) Oct 14 20:00:00 volumio-martha volumio[861]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 14 20:00:00 volumio-martha volumio[861]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 14 20:00:00 volumio-martha volumio[861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 20:00:01 volumio-martha sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-14 19:59 Oct 14 20:00:01 volumio-martha sudo[1123]: 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"