-- Logs begin at Fri 2025-02-14 17:53:55 CET, end at Fri 2025-02-14 17:55:33 CET. -- Feb 14 17:54:00 hifi-stue mpd_monitor.sh[643]: mpd: no process found Feb 14 17:54:00 hifi-stue wpa_supplicant[649]: Successfully initialized wpa_supplicant Feb 14 17:54:00 hifi-stue systemd[1]: Started Login Service. Feb 14 17:54:00 hifi-stue avahi-daemon[631]: Successfully called chroot(). Feb 14 17:54:00 hifi-stue avahi-daemon[631]: Successfully dropped remaining capabilities. Feb 14 17:54:00 hifi-stue systemd[1]: hciuart.service: Succeeded. Feb 14 17:54:00 hifi-stue systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 14 17:54:00 hifi-stue avahi-daemon[631]: Loading service file /services/volumio.service. Feb 14 17:54:00 hifi-stue avahi-daemon[631]: Network interface enumeration completed. Feb 14 17:54:00 hifi-stue avahi-daemon[631]: Server startup complete. Host name is hifi-stue.local. Local service cookie is 163672024. Feb 14 17:54:00 hifi-stue avahi-daemon[631]: Service "HiFi-stue" (/services/volumio.service) successfully established. Feb 14 17:54:00 hifi-stue systemd[1]: Started Raise network interfaces. Feb 14 17:54:00 hifi-stue systemd[1]: Started WPA supplicant. Feb 14 17:54:00 hifi-stue systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 14 17:54:00 hifi-stue volumio-remote-updater[645]: Error: No active session Feb 14 17:54:00 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:00] [info] asio async_connect error: system:111 (Connection refused) Feb 14 17:54:00 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 17:54:00 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:00] [error] handle_connect error: Underlying Transport Error Feb 14 17:54:00 hifi-stue systemd[1]: Listening on mpd.socket. Feb 14 17:54:00 hifi-stue systemd[1]: Reached target Network. Feb 14 17:54:00 hifi-stue systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 14 17:54:00 hifi-stue volumio[651]: Could not open config: /tmp/upmpdcli.conf Feb 14 17:54:00 hifi-stue systemd[1]: Starting Music Player Daemon... Feb 14 17:54:00 hifi-stue systemd[1]: Starting Network Time Service... Feb 14 17:54:00 hifi-stue systemd[1]: Starting Permit User Sessions... Feb 14 17:54:00 hifi-stue systemd[1]: Reached target Network is Online. Feb 14 17:54:00 hifi-stue systemd[1]: Starting Samba NMB Daemon... Feb 14 17:54:00 hifi-stue systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 14 17:54:01 hifi-stue systemd[1]: Starting /etc/rc.local Compatibility... Feb 14 17:54:01 hifi-stue systemd[1]: Starting LSB: Brings up/down network automatically... Feb 14 17:54:01 hifi-stue systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 14 17:54:01 hifi-stue volumio-time-update[632]: volumio-time-update-util: Date not found in response Feb 14 17:54:01 hifi-stue volumio-time-update[632]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 17:54:01 hifi-stue systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 14 17:54:01 hifi-stue systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 14 17:54:01 hifi-stue systemd[1]: Started /etc/rc.local Compatibility. Feb 14 17:54:01 hifi-stue systemd[1]: Started Permit User Sessions. Feb 14 17:54:01 hifi-stue systemd[1]: iptables.service: Succeeded. Feb 14 17:54:01 hifi-stue systemd[1]: Starting Hold until boot process finishes up... Feb 14 17:54:01 hifi-stue systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 14 17:54:01 hifi-stue systemd[1]: Received SIGRTMIN+21 from PID 205 (plymouthd). Feb 14 17:54:01 hifi-stue systemd[1]: plymouth-quit.service: Succeeded. Feb 14 17:54:01 hifi-stue systemd[1]: Started Terminate Plymouth Boot Screen. Feb 14 17:54:01 hifi-stue systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 14 17:54:01 hifi-stue systemd[1]: Started Hold until boot process finishes up. Feb 14 17:54:01 hifi-stue systemd[1]: Received SIGRTMIN+21 from PID 205 (plymouthd). Feb 14 17:54:01 hifi-stue systemd[1]: Started Getty on tty1. Feb 14 17:54:01 hifi-stue systemd[1]: Reached target Login Prompts. Feb 14 17:54:01 hifi-stue bthelper[734]: Raspberry Pi BDADDR already set Feb 14 17:54:01 hifi-stue systemd[1]: Started Raspberry Pi bluetooth helper. Feb 14 17:54:01 hifi-stue systemd[1]: Starting Bluetooth service... Feb 14 17:54:01 hifi-stue ifplugd(eth0)[782]: ifplugd 0.28 initializing. Feb 14 17:54:01 hifi-stue ifplugd(eth0)[782]: Using interface eth0/DC:A6:32:3F:F7:D2 with driver (version: 6.6.62-v7l+) Feb 14 17:54:01 hifi-stue ifplugd(eth0)[782]: Using detection mode: SIOCETHTOOL Feb 14 17:54:01 hifi-stue ifplugd(eth0)[782]: Initialization complete, link beat not detected. Feb 14 17:54:01 hifi-stue loadcpufreq[660]: Loading cpufreq kernel modules...done (none). Feb 14 17:54:01 hifi-stue systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 14 17:54:01 hifi-stue systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 14 17:54:01 hifi-stue ifplugd[733]: Network Interface Plugging Daemon...start eth0...done. Feb 14 17:54:01 hifi-stue systemd[1]: Started LSB: Brings up/down network automatically. Feb 14 17:54:01 hifi-stue ntpd[726]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 14 17:54:01 hifi-stue ntpd[726]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 14 17:54:01 hifi-stue systemd[1]: Started Network Time Service. Feb 14 17:54:01 hifi-stue ntpd[793]: proto: precision = 2.000 usec (-19) Feb 14 17:54:01 hifi-stue ntpd[793]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 14 17:54:01 hifi-stue ntpd[793]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 14 17:54:01 hifi-stue ntpd[793]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 49 days ago Feb 14 17:54:01 hifi-stue ntpd[793]: Listen and drop on 0 v6wildcard [::]:123 Feb 14 17:54:01 hifi-stue ntpd[793]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 14 17:54:01 hifi-stue ntpd[793]: Listen normally on 2 lo 127.0.0.1:123 Feb 14 17:54:01 hifi-stue ntpd[793]: Listening on routing socket on fd #19 for interface updates Feb 14 17:54:01 hifi-stue ntpd[793]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 17:54:01 hifi-stue ntpd[793]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 17:54:01 hifi-stue cpufrequtils[791]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 14 17:54:01 hifi-stue systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 14 17:54:01 hifi-stue bluetoothd[786]: Bluetooth daemon 5.50 Feb 14 17:54:01 hifi-stue sudo[725]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 14 17:54:01 hifi-stue sudo[725]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:01 hifi-stue bluetoothd[786]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 14 17:54:01 hifi-stue sudo[725]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 14 17:54:02 hifi-stue sudo[725]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:02 hifi-stue systemd[1]: Started Bluetooth service. Feb 14 17:54:02 hifi-stue bluetoothd[786]: Starting SDP server Feb 14 17:54:02 hifi-stue bluetoothd[786]: Excluding (cli) sap Feb 14 17:54:02 hifi-stue systemd[1]: Reached target Bluetooth. Feb 14 17:54:02 hifi-stue kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 14 17:54:02 hifi-stue kernel: Bluetooth: BNEP filters: protocol multicast Feb 14 17:54:02 hifi-stue kernel: Bluetooth: BNEP socket layer initialized Feb 14 17:54:02 hifi-stue dbus-daemon[636]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=786 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 14 17:54:02 hifi-stue bluetoothd[786]: Bluetooth management interface 1.22 initialized Feb 14 17:54:02 hifi-stue kernel: Bluetooth: MGMT ver 1.22 Feb 14 17:54:02 hifi-stue bluetoothd[786]: Failed to set privacy: Rejected (0x0b) Feb 14 17:54:02 hifi-stue systemd[1]: Starting Hostname Service... Feb 14 17:54:02 hifi-stue ntpd[793]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 17:54:02 hifi-stue haveged[596]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 14 17:54:02 hifi-stue haveged[596]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Feb 14 17:54:02 hifi-stue haveged[596]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99962 Feb 14 17:54:02 hifi-stue haveged[596]: haveged: fills: 0, generated: 0 Feb 14 17:54:02 hifi-stue dbus-daemon[636]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 14 17:54:02 hifi-stue systemd[1]: Started Hostname Service. Feb 14 17:54:03 hifi-stue nmbd[740]: [2025/02/14 17:54:03.439427, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 14 17:54:03 hifi-stue nmbd[740]: started asyncdns process 817 Feb 14 17:54:03 hifi-stue nmbd[740]: [2025/02/14 17:54:03.447671, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 14 17:54:03 hifi-stue nmbd[740]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 14 17:54:03 hifi-stue nmbd[740]: [2025/02/14 17:54:03.447906, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 14 17:54:03 hifi-stue nmbd[740]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 14 17:54:03 hifi-stue ntpd[793]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 17:54:03 hifi-stue systemd[1]: systemd-rfkill.service: Succeeded. Feb 14 17:54:04 hifi-stue wireless.js[634]: Cleaning previous... Feb 14 17:54:04 hifi-stue sudo[825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 17:54:04 hifi-stue sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:04 hifi-stue sudo[825]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:04 hifi-stue sudo[827]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 17:54:04 hifi-stue sudo[827]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:04 hifi-stue ntpd[793]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 17:54:04 hifi-stue sudo[827]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:05 hifi-stue wireless.js[634]: Stopped aP Feb 14 17:54:05 hifi-stue sudo[834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 17:54:05 hifi-stue sudo[834]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:05 hifi-stue sudo[834]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:05 hifi-stue kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 17:54:05 hifi-stue sudo[836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 14 17:54:05 hifi-stue sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:05 hifi-stue sudo[836]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:05 hifi-stue sudo[843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 17:54:05 hifi-stue sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:05 hifi-stue sudo[843]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:05 hifi-stue sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 14 17:54:05 hifi-stue sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:05 hifi-stue ntpd[793]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 17:54:05 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:05] [info] asio async_connect error: system:111 (Connection refused) Feb 14 17:54:05 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 17:54:05 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:05] [error] handle_connect error: Underlying Transport Error Feb 14 17:54:06 hifi-stue volumio-time-update[632]: volumio-time-update-util: Fetching time from Volumio... Feb 14 17:54:06 hifi-stue volumio-time-update[632]: volumio-time-update-util: Date not found in response Feb 14 17:54:06 hifi-stue volumio-time-update[632]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 17:54:06 hifi-stue bthelper[734]: Changing power off succeeded Feb 14 17:54:06 hifi-stue bthelper[734]: [CHG] Controller DC:A6:32:3F:F7:D4 Powered: no Feb 14 17:54:06 hifi-stue bthelper[734]: [CHG] Controller DC:A6:32:3F:F7:D4 Discovering: no Feb 14 17:54:06 hifi-stue bthelper[734]: [CHG] Controller DC:A6:32:3F:F7:D4 Class: 0x00000000 Feb 14 17:54:06 hifi-stue mpd[813]: Feb 14 17:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 14 17:54:06 hifi-stue bthelper[734]: [CHG] Controller DC:A6:32:3F:F7:D4 Class: 0x0000041c Feb 14 17:54:06 hifi-stue bthelper[734]: Changing power on succeeded Feb 14 17:54:06 hifi-stue bthelper[734]: [CHG] Controller DC:A6:32:3F:F7:D4 Powered: yes Feb 14 17:54:06 hifi-stue systemd[1]: Started Music Player Daemon. Feb 14 17:54:06 hifi-stue mpd_monitor.sh[643]: MPD restarted due to no mpc output. Feb 14 17:54:07 hifi-stue sudo[845]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:07 hifi-stue wireless.js[634]: SETTING APPROPRIATE REG DOMAIN: E0 Feb 14 17:54:07 hifi-stue sudo[866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 17:54:07 hifi-stue sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:07 hifi-stue sudo[866]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:07 hifi-stue sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set E0 Feb 14 17:54:07 hifi-stue sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:07 hifi-stue sudo[868]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:07 hifi-stue wireless.js[634]: not a valid ISO/IEC 3166-1 alpha2 Feb 14 17:54:07 hifi-stue wireless.js[634]: Special non-alpha2 usable entries: Feb 14 17:54:07 hifi-stue wireless.js[634]: 00 World Regulatory domain Feb 14 17:54:07 hifi-stue wireless.js[634]: Failed to set new reg domain: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up && /usr/bin/sudo /sbin/iw reg set E0 Feb 14 17:54:07 hifi-stue wireless.js[634]: not a valid ISO/IEC 3166-1 alpha2 Feb 14 17:54:07 hifi-stue wireless.js[634]: Special non-alpha2 usable entries: Feb 14 17:54:07 hifi-stue wireless.js[634]: 00 World Regulatory domain Feb 14 17:54:07 hifi-stue wireless.js[634]: Start wireless flow Feb 14 17:54:07 hifi-stue wireless.js[634]: Stopped hotspot (if there).. Feb 14 17:54:07 hifi-stue sudo[873]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 17:54:07 hifi-stue sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:07 hifi-stue sudo[873]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:08 hifi-stue sudo[875]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 17:54:08 hifi-stue sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:08 hifi-stue sudo[875]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:08 hifi-stue wireless.js[634]: DHCP IP Feb 14 17:54:08 hifi-stue wireless.js[634]: Start ap Feb 14 17:54:08 hifi-stue wpa_supplicant[877]: Successfully initialized wpa_supplicant Feb 14 17:54:08 hifi-stue sudo[878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 14 17:54:08 hifi-stue sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:08 hifi-stue kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 17:54:08 hifi-stue dhcpcd[879]: dev: loaded udev Feb 14 17:54:08 hifi-stue dhcpcd-run-hooks[897]: wlan0: starting wpa_supplicant Feb 14 17:54:09 hifi-stue dhcpcd-run-hooks[902]: wlan0: failed to start wpa_supplicant Feb 14 17:54:09 hifi-stue dhcpcd-run-hooks[903]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Feb 14 17:54:09 hifi-stue dhcpcd[879]: wlan0: connected to Access Point `' Feb 14 17:54:09 hifi-stue dhcpcd[879]: no interfaces have a carrier Feb 14 17:54:09 hifi-stue dhcpcd[879]: forked to background, child pid 913 Feb 14 17:54:09 hifi-stue dhcpcd[913]: eth0: waiting for carrier Feb 14 17:54:09 hifi-stue dhcpcd[913]: wlan0: waiting for carrier Feb 14 17:54:09 hifi-stue sudo[878]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:09 hifi-stue wireless.js[634]: trying... Feb 14 17:54:09 hifi-stue sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:09 hifi-stue sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:09 hifi-stue sudo[915]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:10 hifi-stue wireless.js[634]: trying... Feb 14 17:54:10 hifi-stue sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:10 hifi-stue sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:10 hifi-stue sudo[918]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:11 hifi-stue volumio-time-update[632]: volumio-time-update-util: Fetching time from Volumio... Feb 14 17:54:11 hifi-stue volumio-time-update[632]: volumio-time-update-util: Date not found in response Feb 14 17:54:11 hifi-stue volumio-time-update[632]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 17:54:11 hifi-stue wireless.js[634]: trying... Feb 14 17:54:11 hifi-stue sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:11 hifi-stue sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:11 hifi-stue sudo[926]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:12 hifi-stue wireless.js[634]: trying... Feb 14 17:54:12 hifi-stue sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:12 hifi-stue sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:12 hifi-stue sudo[929]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:13 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:13] [info] asio async_connect error: system:111 (Connection refused) Feb 14 17:54:13 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 17:54:13 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:13] [error] handle_connect error: Underlying Transport Error Feb 14 17:54:13 hifi-stue wireless.js[634]: trying... Feb 14 17:54:13 hifi-stue sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:13 hifi-stue sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:13 hifi-stue sudo[932]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:14 hifi-stue wireless.js[634]: trying... Feb 14 17:54:14 hifi-stue sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:14 hifi-stue sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:14 hifi-stue sudo[935]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:14 hifi-stue wpa_supplicant[901]: wlan0: Trying to associate with SSID 'Oxalis' Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: carrier acquired Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: connected to Access Point `Oxalis' Feb 14 17:54:15 hifi-stue wpa_supplicant[901]: wlan0: Associated with 04:42:1a:be:4e:68 Feb 14 17:54:15 hifi-stue wpa_supplicant[901]: wlan0: CTRL-EVENT-CONNECTED - Connection to 04:42:1a:be:4e:68 completed [id=0 id_str=] Feb 14 17:54:15 hifi-stue wpa_supplicant[901]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 14 17:54:15 hifi-stue dhcpcd[913]: DUID 00:01:00:01:2c:c5:40:e8:dc:a6:32:3f:f7:d2 Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: IAID 32:3f:f7:d3 Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: adding address fe80::4461:d10f:f512:20d9 Feb 14 17:54:15 hifi-stue dhcpcd[913]: ipv6_addaddr1: Permission denied Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: carrier lost Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: carrier acquired Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: IAID 32:3f:f7:d3 Feb 14 17:54:15 hifi-stue wireless.js[634]: trying... Feb 14 17:54:15 hifi-stue sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:15 hifi-stue sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:15 hifi-stue sudo[951]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: soliciting a DHCP lease Feb 14 17:54:15 hifi-stue dhcpcd[913]: wlan0: soliciting an IPv6 router Feb 14 17:54:16 hifi-stue volumio-time-update[632]: volumio-time-update-util: Fetching time from Volumio... Feb 14 17:54:16 hifi-stue volumio-time-update[632]: volumio-time-update-util: Date not found in response Feb 14 17:54:16 hifi-stue volumio-time-update[632]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 17:54:16 hifi-stue wireless.js[634]: trying... Feb 14 17:54:16 hifi-stue sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:16 hifi-stue sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:16 hifi-stue sudo[959]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:17 hifi-stue wireless.js[634]: trying... Feb 14 17:54:17 hifi-stue sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:17 hifi-stue sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:17 hifi-stue sudo[965]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:18 hifi-stue wireless.js[634]: trying... Feb 14 17:54:18 hifi-stue sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:18 hifi-stue sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:18 hifi-stue sudo[968]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:19 hifi-stue dhcpcd[913]: wlan0: offered 192.168.2.138 from 192.168.2.1 Feb 14 17:54:19 hifi-stue dhcpcd[913]: wlan0: probing address 192.168.2.138/24 Feb 14 17:54:19 hifi-stue wireless.js[634]: trying... Feb 14 17:54:19 hifi-stue sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:19 hifi-stue sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:19 hifi-stue sudo[971]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:20 hifi-stue wireless.js[634]: trying... Feb 14 17:54:20 hifi-stue sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:20 hifi-stue sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:20 hifi-stue sudo[974]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:21 hifi-stue volumio-time-update[632]: volumio-time-update-util: Fetching time from Volumio... Feb 14 17:54:21 hifi-stue volumio-time-update[632]: volumio-time-update-util: Date not found in response Feb 14 17:54:21 hifi-stue volumio-time-update[632]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 17:54:21 hifi-stue wireless.js[634]: trying... Feb 14 17:54:21 hifi-stue sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:21 hifi-stue sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:21 hifi-stue sudo[982]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:22 hifi-stue wireless.js[634]: trying... Feb 14 17:54:22 hifi-stue sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:22 hifi-stue sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:22 hifi-stue sudo[985]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:23 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:23] [info] asio async_connect error: system:111 (Connection refused) Feb 14 17:54:23 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 17:54:23 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:54:23] [error] handle_connect error: Underlying Transport Error Feb 14 17:54:23 hifi-stue wireless.js[634]: trying... Feb 14 17:54:23 hifi-stue sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:23 hifi-stue sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:23 hifi-stue sudo[988]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:24 hifi-stue dhcpcd[913]: wlan0: leased 192.168.2.138 for 86400 seconds Feb 14 17:54:24 hifi-stue avahi-daemon[631]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.138. Feb 14 17:54:24 hifi-stue dhcpcd[913]: wlan0: adding route to 192.168.2.0/24 Feb 14 17:54:24 hifi-stue dhcpcd[913]: wlan0: adding default route via 192.168.2.1 Feb 14 17:54:24 hifi-stue avahi-daemon[631]: New relevant interface wlan0.IPv4 for mDNS. Feb 14 17:54:24 hifi-stue avahi-daemon[631]: Registering new address record for 192.168.2.138 on wlan0.IPv4. Feb 14 17:54:24 hifi-stue wireless.js[634]: trying... Feb 14 17:54:24 hifi-stue sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 17:54:24 hifi-stue sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:24 hifi-stue sudo[1011]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:24 hifi-stue wireless.js[634]: Connected to: ----Oxalis Feb 14 17:54:24 hifi-stue wireless.js[634]: ---- Feb 14 17:54:24 hifi-stue sudo[1014]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 17:54:24 hifi-stue sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:24 hifi-stue sudo[1014]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:24 hifi-stue wireless.js[634]: ... joined AP, wlan0 IPv4 is 192.168.2.138, ipV6 is undefined Feb 14 17:54:24 hifi-stue wireless.js[634]: It's done! AP Feb 14 17:54:24 hifi-stue kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 17:54:24 hifi-stue systemd[1]: Started Wireless Services. Feb 14 17:54:24 hifi-stue systemd[1]: Started Volumio Backend Module. Feb 14 17:54:24 hifi-stue systemd[1]: Started Volumio Cpu Tweaker. Feb 14 17:54:24 hifi-stue volumio-cpu-tweak[1020]: Setting RT Priority for mpd Feb 14 17:54:24 hifi-stue volumio-cpu-tweak[1020]: Setting MPD Affinity Feb 14 17:54:24 hifi-stue volumio-cpu-tweak[1020]: pid 813's current affinity mask: f Feb 14 17:54:24 hifi-stue volumio-cpu-tweak[1020]: pid 813's new affinity mask: 3 Feb 14 17:54:24 hifi-stue volumio-cpu-tweak[1020]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 14 17:54:24 hifi-stue volumio-cpu-tweak[1020]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 14 17:54:24 hifi-stue systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 14 17:54:25 hifi-stue ntpd[793]: Listen normally on 3 wlan0 192.168.2.138:123 Feb 14 17:54:25 hifi-stue ntpd[793]: new interface(s) found: waking up resolver Feb 14 17:54:26 hifi-stue volumio-time-update[632]: volumio-time-update-util: Fetching time from Volumio... Feb 14 17:54:26 hifi-stue volumio-time-update[632]: volumio-time-update-util: Setting system time to: 2025-02-14 17:54:59 Feb 14 17:54:26 hifi-stue sudo[1041]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-02-14 17:54:59 Feb 14 17:54:26 hifi-stue sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:54:26 hifi-stue dbus-daemon[636]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1042 comm="timedatectl set-time 2025-02-14 17:54:59 ") Feb 14 17:54:26 hifi-stue systemd[1]: Starting Time & Date Service... Feb 14 17:54:27 hifi-stue dbus-daemon[636]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 14 17:54:27 hifi-stue systemd[1]: Started Time & Date Service. Feb 14 17:54:59 hifi-stue systemd-timedated[1043]: Changed local time to Fri Feb 14 17:54:59 2025 Feb 14 17:54:59 hifi-stue sudo[1041]: pam_unix(sudo:session): session closed for user root Feb 14 17:54:59 hifi-stue volumio-time-update[632]: volumio-time-update-util: System time updated successfully. Feb 14 17:54:59 hifi-stue systemd[1]: Started Volumio Time Update Utility. Feb 14 17:55:00 hifi-stue nmbd[740]: [2025/02/14 17:55:00.510563, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 14 17:55:00 hifi-stue systemd[1]: Started Samba NMB Daemon. Feb 14 17:55:00 hifi-stue volumio[1019]: info: ------------------------------------------- Feb 14 17:55:00 hifi-stue volumio[1019]: info: ----- Volumio3 ---- Feb 14 17:55:00 hifi-stue volumio[1019]: info: ------------------------------------------- Feb 14 17:55:00 hifi-stue volumio[1019]: info: ----- System startup ---- Feb 14 17:55:00 hifi-stue volumio[1019]: info: ------------------------------------------- Feb 14 17:55:00 hifi-stue nmbd[740]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 14 17:55:00 hifi-stue systemd[1]: Starting Samba Winbind Daemon... Feb 14 17:55:00 hifi-stue systemd[1]: systemd-fsckd.service: Succeeded. Feb 14 17:55:01 hifi-stue winbindd[1055]: [2025/02/14 17:55:01.039990, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 14 17:55:01 hifi-stue winbindd[1055]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 14 17:55:01 hifi-stue winbindd[1055]: [2025/02/14 17:55:01.081403, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 14 17:55:01 hifi-stue systemd[1]: Started Samba Winbind Daemon. Feb 14 17:55:01 hifi-stue winbindd[1055]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 14 17:55:01 hifi-stue systemd[1]: Starting Samba SMB Daemon... Feb 14 17:55:01 hifi-stue smbd[1060]: [2025/02/14 17:55:01.875004, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 14 17:55:01 hifi-stue systemd[1]: Started Samba SMB Daemon. Feb 14 17:55:01 hifi-stue smbd[1060]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 14 17:55:01 hifi-stue systemd[1]: Reached target Multi-User System. Feb 14 17:55:01 hifi-stue systemd[1]: Reached target Graphical Interface. Feb 14 17:55:01 hifi-stue sh[533]: timed out Feb 14 17:55:01 hifi-stue systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 14 17:55:01 hifi-stue dhcpcd[583]: timed out Feb 14 17:55:01 hifi-stue sh[533]: dhcpcd exited Feb 14 17:55:01 hifi-stue dhcpcd[583]: dhcpcd exited Feb 14 17:55:01 hifi-stue sh[533]: ifup: failed to bring up eth0 Feb 14 17:55:01 hifi-stue systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 14 17:55:01 hifi-stue systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 14 17:55:02 hifi-stue systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 14 17:55:02 hifi-stue systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 14 17:55:02 hifi-stue systemd[1]: Startup finished in 13.626s (kernel) + 36.861s (userspace) = 50.487s. Feb 14 17:55:02 hifi-stue volumio[1019]: info: MYVOLUMIO Environment detected Feb 14 17:55:02 hifi-stue volumio[1019]: info: Plugin folders cleanup Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning into folder /volumio/app/plugins/ Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category audio_interface Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category miscellanea Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category music_service Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category plugins.json Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category system_controller Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category user_interface Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning into folder /data/plugins/ Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category audio_interface Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category music_service Feb 14 17:55:02 hifi-stue volumio[1019]: info: Scanning category user_interface Feb 14 17:55:02 hifi-stue volumio[1019]: info: Plugin folders cleanup completed Feb 14 17:55:02 hifi-stue volumio[1019]: info: ------------------------------------------- Feb 14 17:55:02 hifi-stue volumio[1019]: info: ----- Core plugins startup ---- Feb 14 17:55:02 hifi-stue volumio[1019]: info: ------------------------------------------- Feb 14 17:55:02 hifi-stue volumio[1019]: info: Loading plugins from folder /volumio/app/plugins/ Feb 14 17:55:02 hifi-stue volumio[1019]: info: Adding plugin upnp to MyMusic Plugins Feb 14 17:55:02 hifi-stue volumio[1019]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 14 17:55:02 hifi-stue volumio[1019]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 14 17:55:02 hifi-stue volumio[1019]: info: Loading plugins from folder /data/plugins/ Feb 14 17:55:02 hifi-stue volumio[1019]: info: Loading plugin "system"... Feb 14 17:55:02 hifi-stue volumio[1019]: info: Loading plugin "appearance"... Feb 14 17:55:04 hifi-stue systemd[1]: systemd-hostnamed.service: Succeeded. Feb 14 17:55:04 hifi-stue volumio[1019]: info: Loading plugin "network"... Feb 14 17:55:04 hifi-stue volumio[1019]: info: Refreshing Cached IP Addresses Feb 14 17:55:04 hifi-stue sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 14 17:55:04 hifi-stue sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:04 hifi-stue sudo[1078]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:04 hifi-stue sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 17:55:04 hifi-stue sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:04 hifi-stue sudo[1080]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:04 hifi-stue volumio[1019]: info: Loading plugin "services"... Feb 14 17:55:04 hifi-stue volumio[1019]: info: Loading plugin "alsa_controller"... Feb 14 17:55:04 hifi-stue sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 14 17:55:04 hifi-stue sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:05 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 17:55:05 hifi-stue volumio[1019]: info: Loading plugin "wizard"... Feb 14 17:55:05 hifi-stue volumio[1019]: info: Loading plugin "networkfs"... Feb 14 17:55:05 hifi-stue volumio[1019]: info: Starting Udev Watcher for removable devices Feb 14 17:55:05 hifi-stue sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bjorn,password=Gordon917,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.46/BigBird\\Music /mnt/NAS/BigBird Feb 14 17:55:05 hifi-stue sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:05 hifi-stue volumio[1019]: info: Ignoring mount for partition: boot Feb 14 17:55:05 hifi-stue volumio[1019]: info: Ignoring mount for partition: volumio Feb 14 17:55:05 hifi-stue volumio[1019]: info: Ignoring mount for partition: volumio_data Feb 14 17:55:05 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 17:55:05 hifi-stue volumio[1019]: info: Loading plugin "volumio_command_line_client"... Feb 14 17:55:05 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:55:05] [connect] Successful connection Feb 14 17:55:05 hifi-stue volumio[1019]: info: Loading plugin "upnp"... Feb 14 17:55:05 hifi-stue volumio[1019]: info: [1739552105345] Starting Upmpd Daemon Feb 14 17:55:05 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 17:55:05 hifi-stue volumio[1019]: info: Loading plugin "my_music"... Feb 14 17:55:05 hifi-stue volumio[1019]: info: Loading plugin "mpd"... Feb 14 17:55:05 hifi-stue kernel: Key type cifs.spnego registered Feb 14 17:55:05 hifi-stue kernel: Key type cifs.idmap registered Feb 14 17:55:05 hifi-stue 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. Feb 14 17:55:05 hifi-stue kernel: CIFS: Attempting to mount //192.168.2.46/BigBird/Music Feb 14 17:55:05 hifi-stue kernel: cryptd: max_cpu_qlen set to 1000 Feb 14 17:55:06 hifi-stue volumio[1019]: info: Loading plugin "upnp_browser"... Feb 14 17:55:06 hifi-stue sudo[1107]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:06 hifi-stue volumio[1019]: info: Loading plugin "alarm-clock"... Feb 14 17:55:06 hifi-stue volumio[1019]: info: Loading plugin "airplay_emulation"... Feb 14 17:55:07 hifi-stue volumio[1019]: info: Starting Shairport Sync Feb 14 17:55:07 hifi-stue volumio[1019]: info: Loading plugin "last_100"... Feb 14 17:55:07 hifi-stue volumio[1019]: info: Loading plugin "webradio"... Feb 14 17:55:07 hifi-stue volumio[1019]: info: Loading plugin "i2s_dacs"... Feb 14 17:55:07 hifi-stue volumio[1019]: info: Loading plugin "volumiodiscovery"... Feb 14 17:55:07 hifi-stue volumio[1019]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 14 17:55:07 hifi-stue volumio[1019]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 17:55:07 hifi-stue node[1019]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 14 17:55:07 hifi-stue volumio[1019]: *** WARNING *** For more information see Feb 14 17:55:07 hifi-stue volumio[1019]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 14 17:55:07 hifi-stue volumio[1019]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 17:55:07 hifi-stue volumio[1019]: *** WARNING *** For more information see Feb 14 17:55:07 hifi-stue node[1019]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 17:55:07 hifi-stue node[1019]: *** WARNING *** For more information see Feb 14 17:55:07 hifi-stue node[1019]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 14 17:55:07 hifi-stue node[1019]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 17:55:07 hifi-stue node[1019]: *** WARNING *** For more information see Feb 14 17:55:07 hifi-stue volumio[1019]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 14 17:55:07 hifi-stue volumio[1019]: info: Discovery: Started advertising with name: HiFi-stue Feb 14 17:55:07 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 17:55:07 hifi-stue volumio[1019]: info: Loading plugin "spop"... Feb 14 17:55:08 hifi-stue sudo[1093]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:09 hifi-stue volumio[1019]: info: Loading plugin "now_playing"... Feb 14 17:55:11 hifi-stue volumio[1019]: info: Loading plugin "outputs"... Feb 14 17:55:11 hifi-stue volumio[1019]: info: Loading plugin "albumart"... Feb 14 17:55:11 hifi-stue volumio[1019]: info: Plugin example_plugin is not enabled Feb 14 17:55:11 hifi-stue volumio[1019]: info: Loading plugin "inputs"... Feb 14 17:55:11 hifi-stue volumio[1019]: info: Loading plugin "updater_comm"... Feb 14 17:55:12 hifi-stue volumio[1019]: info: Plugin mpdemulation is not enabled Feb 14 17:55:12 hifi-stue volumio[1019]: info: Loading plugin "rest_api"... Feb 14 17:55:12 hifi-stue volumio[1019]: info: Loading plugin "websocket"... Feb 14 17:55:12 hifi-stue volumio[1019]: info: Starting Socket.io Server version 2.3.0 Feb 14 17:55:12 hifi-stue volumio[1019]: info: Plugin fusiondsp is not enabled Feb 14 17:55:12 hifi-stue volumio[1019]: info: Loading plugin "touch_display"... Feb 14 17:55:12 hifi-stue volumio[1019]: Forking 3 albumart workers Feb 14 17:55:12 hifi-stue volumio[1019]: info: Applying required configuration parameters for plugin touch_display Feb 14 17:55:13 hifi-stue volumio[1019]: info: Loading i18n strings for locale en Feb 14 17:55:13 hifi-stue volumio[1019]: Updating browse sources language Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:55:13 hifi-stue volumio[1019]: Starting albumart workers Feb 14 17:55:13 hifi-stue volumio[1019]: Starting albumart workers Feb 14 17:55:13 hifi-stue volumio[1019]: Starting albumart workers Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::initPlayerControls Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 17:55:13 hifi-stue volumio[1019]: Express server listening on port 3000 Feb 14 17:55:13 hifi-stue volumio[1019]: [Metrics] WebUI: 14s 403.39ms Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreStateMachine::resetVolumioState Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreStateMachine::getcurrentVolume Feb 14 17:55:13 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 17:55:13 hifi-stue volumio[1019]: info: Volumio Network Manager: Network status updated: 2 Feb 14 17:55:14 hifi-stue volumio[1019]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:55:14 hifi-stue volumio[1019]: info: Reloading queue from file Feb 14 17:55:14 hifi-stue volumio[1019]: info: VolumeController:: Volume=60 Mute =false Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreStateMachine::pushState Feb 14 17:55:14 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioPushState Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreStateMachine::updateTrackBlock Feb 14 17:55:14 hifi-stue volumio[1019]: info: CorePlayQueue::getTrackBlock Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 17:55:14 hifi-stue volumio[1019]: info: Setting Device type: Raspberry PI Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreStateMachine::setRepeat false single undefined Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreStateMachine::pushState Feb 14 17:55:14 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioPushState Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreStateMachine::setRandom false Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreStateMachine::pushState Feb 14 17:55:14 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioPushState Feb 14 17:55:14 hifi-stue volumio[1019]: info: Completed loading Core Plugins Feb 14 17:55:14 hifi-stue volumio[1019]: info: Preparing to generate the ALSA configuration file Feb 14 17:55:14 hifi-stue volumio[1019]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 14 17:55:14 hifi-stue volumio[1019]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Feb 14 17:55:14 hifi-stue volumio[1019]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 14 17:55:14 hifi-stue volumio[1019]: info: Asound.conf file unchanged, so no further update is needed Feb 14 17:55:14 hifi-stue volumio[1019]: info: Output device has changed, restarting MPD Feb 14 17:55:14 hifi-stue sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 14 17:55:14 hifi-stue volumio[1019]: info: Output device has changed, restarting Shairport Sync Feb 14 17:55:14 hifi-stue sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:55:14 hifi-stue sudo[1214]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:14 hifi-stue sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 14 17:55:14 hifi-stue sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:14 hifi-stue systemd[1]: Stopping Music Player Daemon... Feb 14 17:55:14 hifi-stue volumio[1019]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 17:55:14 hifi-stue volumio[1019]: info: ___________ START PLUGINS ___________ Feb 14 17:55:14 hifi-stue volumio[1019]: info: ControllerMpd::onStart: Initializing MPD Feb 14 17:55:14 hifi-stue volumio[1019]: info: Creating MPD Configuration file Feb 14 17:55:14 hifi-stue sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 14 17:55:14 hifi-stue sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 17:55:14 hifi-stue volumio[1019]: info: [1739552114846] CoreMusicLibrary::Adding element Media Servers Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:55:14 hifi-stue sudo[1223]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:14 hifi-stue sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 14 17:55:14 hifi-stue sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:55:14 hifi-stue volumio[1019]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:14 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:55:14 hifi-stue systemd[1]: mpd.service: Succeeded. Feb 14 17:55:14 hifi-stue systemd[1]: Stopped Music Player Daemon. Feb 14 17:55:15 hifi-stue volumio[1019]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 17:55:15 hifi-stue systemd[1]: Starting Music Player Daemon... Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 17:55:15 hifi-stue volumio[1019]: info: [1739552115052] CoreMusicLibrary::Adding element Last_100 Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 17:55:15 hifi-stue volumio[1019]: info: [1739552115057] CoreMusicLibrary::Adding element Webradio Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 17:55:15 hifi-stue volumio[1019]: info: Initializing BBC Radios Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:55:15 hifi-stue volumio[1019]: info: Creating Spotify config file Feb 14 17:55:15 hifi-stue sudo[1240]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 14 17:55:15 hifi-stue sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:15 hifi-stue sudo[1240]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:15 hifi-stue volumio[1019]: info: [now-playing] ConfigUpdater: config is up to date. Feb 14 17:55:15 hifi-stue volumio[1019]: info: Loading i18n strings for locale en Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 17:55:15 hifi-stue volumio[1019]: info: Volumio Calling Home Feb 14 17:55:15 hifi-stue sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 14 17:55:15 hifi-stue sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:15 hifi-stue systemd[1]: Reloading. Feb 14 17:55:15 hifi-stue volumio[1019]: info: [now-playing] App is listening on port 4004. Feb 14 17:55:15 hifi-stue volumio[1019]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 14 17:55:15 hifi-stue volumio[1019]: info: Discovery: adding 3b77399d-a166-444b-b26e-08c73f0ccc2a Feb 14 17:55:15 hifi-stue volumio[1019]: info: Discovery: Found device HiFi-stue Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioGetState Feb 14 17:55:15 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:15 hifi-stue volumio[1019]: info: VolumeController:: Volume=60 Mute =false Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreStateMachine::pushState Feb 14 17:55:15 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:15 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioPushState Feb 14 17:55:15 hifi-stue sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 14 17:55:15 hifi-stue sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:15 hifi-stue sudo[1281]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:15 hifi-stue volumio[1019]: info: MPD Permissions set Feb 14 17:55:15 hifi-stue volumio[1019]: info: MPD Permissions set Feb 14 17:55:15 hifi-stue sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 17:55:15 hifi-stue sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:15 hifi-stue sudo[1284]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:15 hifi-stue volumio[1019]: info: Spotify config file written Feb 14 17:55:16 hifi-stue volumio[1019]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 14 17:55:16 hifi-stue sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 14 17:55:16 hifi-stue sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:16 hifi-stue volumio[1019]: info: Volumio called home Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:55:16 hifi-stue volumio[1019]: info: touch_display: Raspberry Pi Foundation touch screen detected. Feb 14 17:55:16 hifi-stue volumio[1019]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Feb 14 17:55:16 hifi-stue volumio[1019]: info: Starting Shairport Sync Feb 14 17:55:16 hifi-stue sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 14 17:55:16 hifi-stue volumio[1019]: info: Starting Shairport Sync Feb 14 17:55:16 hifi-stue sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:16 hifi-stue sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 14 17:55:16 hifi-stue sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:16 hifi-stue volumio[1019]: info: Starting Shairport Sync Feb 14 17:55:16 hifi-stue sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 14 17:55:16 hifi-stue sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:16 hifi-stue sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 14 17:55:16 hifi-stue sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:16 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioGetState Feb 14 17:55:16 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:16 hifi-stue systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Feb 14 17:55:16 hifi-stue sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Feb 14 17:55:16 hifi-stue sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:16 hifi-stue sudo[1310]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:16 hifi-stue volumio[1019]: info: touch_display: File permissions for backlight brightness control set. Feb 14 17:55:16 hifi-stue sudo[1265]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:16 hifi-stue volumio[1019]: info: touch_display: systemctl daemon-reload succeeded. Feb 14 17:55:16 hifi-stue sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 14 17:55:16 hifi-stue sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:17 hifi-stue mpd[1260]: Feb 14 17:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 14 17:55:17 hifi-stue systemd[1]: Started Volumio Kiosk. Feb 14 17:55:17 hifi-stue systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 14 17:55:17 hifi-stue sudo[1316]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:17 hifi-stue volumio[1019]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 14 17:55:17 hifi-stue volumio[1019]: info: touch_display: Volumio Kiosk started Feb 14 17:55:17 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioGetState Feb 14 17:55:17 hifi-stue systemd[1]: Started UPnP Renderer front-end to MPD. Feb 14 17:55:17 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:17 hifi-stue sudo[1298]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:17 hifi-stue systemd[1]: Started go-librespot Daemon. Feb 14 17:55:17 hifi-stue sudo[1287]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:17 hifi-stue go-librespot[1321]: Librespot-go daemon starting... Feb 14 17:55:17 hifi-stue systemd[1]: shairport-sync.service: Succeeded. Feb 14 17:55:17 hifi-stue systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 14 17:55:17 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioGetState Feb 14 17:55:17 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:17 hifi-stue volumio[1019]: info: Upmpdcli Daemon Started Feb 14 17:55:17 hifi-stue systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 14 17:55:17 hifi-stue sudo[1306]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:17 hifi-stue systemd[1]: Started Music Player Daemon. Feb 14 17:55:17 hifi-stue sudo[1304]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:17 hifi-stue sudo[1216]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:17 hifi-stue sudo[1225]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:17 hifi-stue sudo[1300]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:17 hifi-stue volumio[1019]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 14 17:55:17 hifi-stue volumio[1019]: info: Shairport-Sync Started Feb 14 17:55:17 hifi-stue volumio[1019]: Error adding Membership: Error: addMembership EINVAL Feb 14 17:55:17 hifi-stue volumio[1019]: info: Completed starting Core Plugins Feb 14 17:55:17 hifi-stue volumio[1019]: info: ------------------------------------------- Feb 14 17:55:17 hifi-stue volumio[1019]: info: ----- MyVolumio plugins startup ---- Feb 14 17:55:17 hifi-stue volumio[1019]: info: ------------------------------------------- Feb 14 17:55:17 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 14 17:55:17 hifi-stue volumio[1019]: info: Shairport-Sync Started Feb 14 17:55:17 hifi-stue volumio[1019]: info: Shairport-Sync Started Feb 14 17:55:17 hifi-stue volumio[1019]: info: MPD running with PID1260 Feb 14 17:55:17 hifi-stue volumio[1019]: ,establishing connection Feb 14 17:55:17 hifi-stue volumio[1019]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 14 17:55:17 hifi-stue volumio[1319]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 14 17:55:17 hifi-stue startx[1318]: X.Org X Server 1.20.4 Feb 14 17:55:17 hifi-stue startx[1318]: X Protocol Version 11, Revision 0 Feb 14 17:55:17 hifi-stue startx[1318]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Feb 14 17:55:17 hifi-stue startx[1318]: Current Operating System: Linux hifi-stue 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Feb 14 17:55:17 hifi-stue startx[1318]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:3F:F7:D2 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=660717eb-e471-4727-aac1-2c263938c365 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 14 17:55:17 hifi-stue startx[1318]: Build Date: 04 April 2023 07:50:56AM Feb 14 17:55:17 hifi-stue startx[1318]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Feb 14 17:55:17 hifi-stue startx[1318]: Current version of pixman: 0.36.0 Feb 14 17:55:17 hifi-stue startx[1318]: Before reporting problems, check http://wiki.x.org Feb 14 17:55:17 hifi-stue startx[1318]: to make sure that you have the latest version. Feb 14 17:55:17 hifi-stue startx[1318]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 14 17:55:17 hifi-stue startx[1318]: (++) from command line, (!!) notice, (II) informational, Feb 14 17:55:17 hifi-stue startx[1318]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 14 17:55:17 hifi-stue startx[1318]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Feb 14 17:55:17 2025 Feb 14 17:55:17 hifi-stue startx[1318]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 14 17:55:17 hifi-stue startx[1318]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 14 17:55:17 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:17+01:00" level=info msg="generated new device id: 0819fdaad3fcf4a8137c7b8b705f3674843bebe2" Feb 14 17:55:17 hifi-stue volumio[1019]: error: MPD error: The expression evaluated to a falsy value: Feb 14 17:55:17 hifi-stue volumio[1019]: assert.ok(self.idling) Feb 14 17:55:17 hifi-stue volumio[1019]: error: The expression evaluated to a falsy value: Feb 14 17:55:17 hifi-stue volumio[1019]: assert.ok(self.idling) Feb 14 17:55:17 hifi-stue volumio[1019]: error: MPD error: The expression evaluated to a falsy value: Feb 14 17:55:17 hifi-stue volumio[1019]: assert.ok(self.idling) Feb 14 17:55:17 hifi-stue volumio[1019]: error: The expression evaluated to a falsy value: Feb 14 17:55:17 hifi-stue volumio[1019]: assert.ok(self.idling) Feb 14 17:55:17 hifi-stue volumio[1019]: error: updateQueue error: null Feb 14 17:55:17 hifi-stue volumio[1019]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 14 17:55:18 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 14 17:55:18 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:18+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 14 17:55:18 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:18+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 14 17:55:18 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:18+01:00" level=debug msg="zeroconf server listening on port 39099" Feb 14 17:55:19 hifi-stue volumio[1019]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 14 17:55:20 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:55:20] [connect] Successful connection Feb 14 17:55:20 hifi-stue volumio-remote-updater[645]: [2025-02-14 17:55:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739552120 101 Feb 14 17:55:20 hifi-stue volumio[1019]: 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 Feb 14 17:55:20 hifi-stue volumio[1019]: info: go-librespot daemon successfully initialized Feb 14 17:55:22 hifi-stue volumio[1019]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 14 17:55:23 hifi-stue volumio[1019]: info: Initializing connection to go-librespot Websocket Feb 14 17:55:23 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:23+01:00" level=debug msg="new websocket client" Feb 14 17:55:24 hifi-stue volumio[1019]: info: Connection to go-librespot Websocket established Feb 14 17:55:25 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:25+01:00" level=debug msg="obtained new client token: AAAJ/2tYEehVQQcrCczb/8+CRWlIn3i7bVmXhyAQcrZZfqTfVVq2Pw1yn0EmFwnadRl7EheCgrIMxAcRlDUY6lKZOPWq6qp3u0c7k++qv4LjFfWZTl6ldlOw6GgzK1NIGUZULdBg2sCxxJW+bPzmZ3ohyy7qeN9jVCSYnxZGWVdNtpelifAVxssxMirJunE97k0s2niLxH7mEwwS7GEtfnzWoP73rBZpjqMN7zuwqzGqiSL2E3wyZfQnXEes3Q==" Feb 14 17:55:25 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:25+01:00" level=debug msg="completed keyexchange" Feb 14 17:55:25 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:25+01:00" level=debug msg="completed challenge" Feb 14 17:55:25 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:25+01:00" level=debug msg="authenticated as bjorn1234567890" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="authenticated as bjorn1234567890" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="dealer connection opened" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=info msg="accepted zeroconf user bjorn1234567890 from Pixel 8 BK" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="received connection id: MDE4OTA0M2ItYmMyZi00NmUwLTgyYzUtMjZjOTYyNzNiZDgxK2RlYWxlcit0Y3A6Ly8wYWNhNTkwYS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMjFERjlDOTAxMDkxMTVCNkYwNkNFQTI3OEQyODJGMzI1NDU4NUVGRUI4QzBBM0EwRUU1NkEwNDU1MDU0Q0RCNA==" Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 14 17:55:26 hifi-stue volumio[1019]: info: Adding plugin bluetooth to MyMusic Plugins Feb 14 17:55:26 hifi-stue volumio[1019]: info: Adding plugin multiroom to MyMusic Plugins Feb 14 17:55:26 hifi-stue volumio[1019]: info: Adding plugin metavolumio to MyMusic Plugins Feb 14 17:55:26 hifi-stue volumio[1019]: info: Adding plugin cd_controller to MyMusic Plugins Feb 14 17:55:26 hifi-stue volumio[1019]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 14 17:55:26 hifi-stue volumio[1019]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 14 17:55:26 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="handling transfer player command from b9a4f6ac1c70009194f0dc0c6c2ff619cb843b44" Feb 14 17:55:26 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:26+01:00" level=debug msg="loading track spotify:track:2z9FUHRkbkV4BObNzbHsFU (paused: true, position: 117360ms)" Feb 14 17:55:27 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:27+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 14 17:55:27 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:27+01:00" level=trace msg="emitting websocket event: will_play" Feb 14 17:55:27 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:27+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Feb 14 17:55:27 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:27+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2z9FUHRkbkV4BObNzbHsFU" Feb 14 17:55:27 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:27+01:00" level=debug msg="requested aes key for file 9d3f7efd13ec9ea957f134abb9b4f3f1cfb0d75f, gid: 2z9FUHRkbkV4BObNzbHsFU" Feb 14 17:55:27 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:27+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1362" Feb 14 17:55:27 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:27+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1126" Feb 14 17:55:27 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:27+01:00" level=debug msg="fetched first chunk of 18, total size is 8929995 bytes" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="fetched chunk 1/17, size: 524288" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="fetched chunk 3/17, size: 524288" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="fetched chunk 10/17, size: 524288" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="fetched chunk 9/17, size: 524288" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=trace msg="seek to 117360ms (diff: 153ms, samples: 5175576, bytes: 5054859)" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="fetched chunk 2/17, size: 524288" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="fetched chunk 11/17, size: 524288" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="fetched chunk 12/17, size: 524288" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=info msg="loaded track \"Born With a Broken Heart\" (uri: spotify:track:2z9FUHRkbkV4BObNzbHsFU, paused: true, position: 117360ms, duration: 208956ms)" Feb 14 17:55:28 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 14 17:55:28 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 14 17:55:28 hifi-stue volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=trace msg="emitting websocket event: metadata" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=trace msg="emitting websocket event: active" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="sending successful reply for delaer request" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 14 17:55:28 hifi-stue volumio[1019]: info: Starting MyVolumio Remote Streaming Endpoints Feb 14 17:55:28 hifi-stue volumio[1019]: info: MyVolumio login type: Token Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=trace msg="emitting websocket event: paused" Feb 14 17:55:28 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:28+01:00" level=debug msg="handling update_context player command from b9a4f6ac1c70009194f0dc0c6c2ff619cb843b44" Feb 14 17:55:28 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 14 17:55:28 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 14 17:55:29 hifi-stue systemd[1]: systemd-timedated.service: Succeeded. Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="sending successful reply for delaer request" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="update volume to 64880/65535" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="update volume to 61603/65535" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="update volume to 60948/65535" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="update volume to 60292/65535" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="update volume to 55705/65535" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="update volume to 50462/65535" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="update volume to 47841/65535" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:29 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:29+01:00" level=debug msg="update volume to 42598/65535" Feb 14 17:55:30 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:30+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:30 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:30+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:30 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:30+01:00" level=debug msg="update volume to 37355/65535" Feb 14 17:55:30 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:30+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:30 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:30+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:30 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:30+01:00" level=debug msg="update volume to 32767/65535" Feb 14 17:55:30 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:30+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:30 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:30+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:31 hifi-stue volumio[1019]: info: Starting Streaming Service Transparent Proxy Feb 14 17:55:31 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 14 17:55:31 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 14 17:55:31 hifi-stue volumio[1019]: info: Streaming services startup Feb 14 17:55:31 hifi-stue volumio[1019]: info: Starting Streaming Daemon Feb 14 17:55:31 hifi-stue sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 14 17:55:31 hifi-stue sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:31 hifi-stue sudo[1603]: pam_unix(sudo:session): session closed for user root Feb 14 17:55:31 hifi-stue volumio[1019]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 14 17:55:31 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:31+01:00" level=debug msg="update volume to 32112/65535" Feb 14 17:55:31 hifi-stue volumio[1019]: info: Getting Spotify volume Feb 14 17:55:32 hifi-stue volumio[1019]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2z9FUHRkbkV4BObNzbHsFU","play_origin":"playlist"}} Feb 14 17:55:32 hifi-stue volumio[1019]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2z9FUHRkbkV4BObNzbHsFU","name":"Born With a Broken Heart","artist_names":["Damiano David"],"album_name":"Born With a Broken Heart","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02c105fb55474f224476c23008","position":117360,"duration":208956}} Feb 14 17:55:32 hifi-stue volumio[1019]: SPOTIFY: received: {"type":"active","data":null} Feb 14 17:55:32 hifi-stue volumio[1019]: info: Aligning Spotify Volume to Volumio Volume Feb 14 17:55:32 hifi-stue volumio[1019]: info: CoreCommandRouter::volumioGetState Feb 14 17:55:32 hifi-stue volumio[1019]: info: CorePlayQueue::getTrack 0 Feb 14 17:55:32 hifi-stue volumio[1019]: info: Setting Spotify Volume from Volumio: 60 Feb 14 17:55:32 hifi-stue volumio[1019]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2z9FUHRkbkV4BObNzbHsFU","play_origin":"playlist"}} Feb 14 17:55:32 hifi-stue volumio[1019]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 17:55:32 hifi-stue volumio[1019]: TypeError: Cannot read property 'service' of undefined Feb 14 17:55:32 hifi-stue volumio[1019]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:322:50) Feb 14 17:55:32 hifi-stue volumio[1019]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:256:18) Feb 14 17:55:32 hifi-stue volumio[1019]: at WebSocket.message (/data/plugins/music_service/spop/index.js:175:14) Feb 14 17:55:32 hifi-stue volumio[1019]: at WebSocket.emit (events.js:315:20) Feb 14 17:55:32 hifi-stue volumio[1019]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1192:20) Feb 14 17:55:32 hifi-stue volumio[1019]: at Receiver.emit (events.js:315:20) Feb 14 17:55:32 hifi-stue volumio[1019]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:560:14) Feb 14 17:55:32 hifi-stue volumio[1019]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:478:17) Feb 14 17:55:32 hifi-stue volumio[1019]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:22) Feb 14 17:55:32 hifi-stue volumio[1019]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:180:18 Feb 14 17:55:32 hifi-stue volumio[1019]: at internal/process/task_queues.js:149:7 Feb 14 17:55:32 hifi-stue volumio[1019]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Feb 14 17:55:32 hifi-stue volumio[1019]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) Feb 14 17:55:32 hifi-stue volumio[1019]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Feb 14 17:55:32 hifi-stue volumio[1019]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 17:55:32 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:32+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:32 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:32+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:33 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:33+01:00" level=debug msg="update volume to 31457/65535" Feb 14 17:55:33 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:33+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:33 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:33+01:00" level=trace msg="emitting websocket event: volume" Feb 14 17:55:33 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:33+01:00" level=debug msg="update volume to 30801/65535" Feb 14 17:55:33 hifi-stue sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-14 17:54 Feb 14 17:55:33 hifi-stue sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:55:33 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:33+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 14 17:55:33 hifi-stue go-librespot[1321]: time="2025-02-14T17:55:33+01:00" level=trace msg="emitting websocket event: volume" 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"