-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-04-27 21:26:59 CEST. -- Apr 27 21:25:04 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-DISCONNECTED bssid=2c:c8:1b:3c:59:eb reason=2 Apr 27 21:25:04 speaker2 dhcpcd[1067]: wlan0: carrier lost Apr 27 21:25:04 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 27 21:25:05 speaker2 avahi-daemon[848]: Withdrawing address record for 10.13.10.52 on wlan0. Apr 27 21:25:05 speaker2 avahi-daemon[848]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.13.10.52. Apr 27 21:25:05 speaker2 avahi-daemon[848]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 27 21:25:05 speaker2 dhcpcd[1067]: wlan0: deleting route to 10.13.10.0/24 Apr 27 21:25:05 speaker2 dhcpcd[1067]: wlan0: deleting default route via 10.13.10.1 Apr 27 21:25:05 speaker2 volumio[1162]: info: Discovery: A device disappeared from network Apr 27 21:25:05 speaker2 volumio[1162]: info: Discovery: Device speaker2 disappeared from network Apr 27 21:25:06 speaker2 ntpd[981]: Deleting interface #4 wlan0, 10.13.10.52#123, interface stats: received=50, sent=71, dropped=0, active_time=130 secs Apr 27 21:25:06 speaker2 ntpd[981]: 62.112.195.26 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 62.112.193.9 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 193.227.197.2 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 62.112.195.56 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 193.6.222.20 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 82.141.152.3 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 80.249.164.129 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 89.107.253.4 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 81.0.124.200 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 195.228.75.149 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 80.249.164.126 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 195.111.92.55 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 81.0.116.13 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 193.6.222.47 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 194.38.104.150 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 193.224.163.77 local addr 10.13.10.52 -> Apr 27 21:25:06 speaker2 ntpd[981]: 188.156.114.7 local addr 10.13.10.52 -> Apr 27 21:25:07 speaker2 volumio[1162]: info: Volumio Network Manager: Network status updated: 0 Apr 27 21:25:09 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:25:09 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:25:09 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:25:09 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:25:09 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:25:09 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:25:09 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:25:11 speaker2 wpa_supplicant[1055]: wlan0: Trying to associate with SSID 'KisCsirkeAC' Apr 27 21:25:11 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 27 21:25:12 speaker2 nmbd[924]: [2025/04/27 21:25:12.356231, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 27 21:25:12 speaker2 nmbd[924]: Packet send failed to 10.13.10.255(138) ERRNO=Network is unreachable Apr 27 21:25:12 speaker2 nmbd[924]: [2025/04/27 21:25:12.356547, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Apr 27 21:25:12 speaker2 nmbd[924]: reload_interfaces: No subnets to listen to. Waiting.. Apr 27 21:25:15 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:25:15 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:25:15 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:25:15 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:25:15 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:25:15 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:25:15 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:25:17 speaker2 wpa_supplicant[1055]: wlan0: Trying to associate with SSID 'KisCsirkeAC' Apr 27 21:25:17 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 27 21:25:22 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:25:22 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:25:22 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:25:22 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:25:22 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:25:22 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:25:22 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:25:23 speaker2 go-librespot[1453]: time="2025-04-27T21:25:23+02:00" level=error msg="did not receive last pong from dealer, 90s passed" Apr 27 21:25:24 speaker2 wpa_supplicant[1055]: wlan0: Trying to associate with SSID 'KisCsirkeAC' Apr 27 21:25:24 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 27 21:25:29 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:25:29 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:25:29 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:25:29 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:25:29 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:25:29 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:25:29 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:25:32 speaker2 wpa_supplicant[1055]: wlan0: Trying to associate with SSID 'KisCsirkeAC' Apr 27 21:25:32 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 27 21:25:32 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="KisCsirkeAC" auth_failures=2 duration=23 reason=CONN_FAILED Apr 27 21:25:40 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:25:40 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:25:40 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:25:40 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:25:40 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:25:40 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:25:41 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:25:43 speaker2 wpa_supplicant[1055]: wlan0: Trying to associate with SSID 'KisCsirke' Apr 27 21:25:43 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 27 21:25:46 speaker2 kernel: usb 3-2: USB disconnect, device number 3 Apr 27 21:25:46 speaker2 volumio[1162]: info: Apr 27 21:25:46 speaker2 volumio[1162]: ---------------------------- USB Audio Device Detached Apr 27 21:25:46 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 27 21:25:46 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:25:46 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 27 21:25:48 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:25:48 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:25:48 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:25:48 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:25:48 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:25:48 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:25:48 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:25:50 speaker2 wpa_supplicant[1055]: wlan0: Trying to associate with SSID 'KisCsirke' Apr 27 21:25:51 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 27 21:25:51 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="KisCsirke" auth_failures=1 duration=10 reason=CONN_FAILED Apr 27 21:25:51 speaker2 kernel: usb 3-2: new high-speed USB device number 4 using xhci-hcd Apr 27 21:25:51 speaker2 kernel: usb 3-2: New USB device found, idVendor=16d0, idProduct=0a09, bcdDevice= 1.01 Apr 27 21:25:51 speaker2 kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 27 21:25:51 speaker2 kernel: usb 3-2: Product: TothTechnika-DAC-2025 Apr 27 21:25:51 speaker2 kernel: usb 3-2: Manufacturer: TothTechnika Apr 27 21:25:51 speaker2 kernel: usb 3-2: SerialNumber: 9EEAF724B3C28E28 Apr 27 21:25:51 speaker2 systemd-udevd[2951]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Apr 27 21:25:51 speaker2 volumio[1162]: info: Apr 27 21:25:51 speaker2 volumio[1162]: ---------------------------- USB Audio Device Attached Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::Close All Modals sent Apr 27 21:25:51 speaker2 volumio[1162]: info: Preparing to save Alsa Options, stopping services first Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPause Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreStateMachine::pause Apr 27 21:25:51 speaker2 volumio[1162]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"TothTechnika-DAC-2025","alsacard":"TothTechnikaDAC"},"i2s":false} Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:25:51 speaker2 volumio[1162]: info: Setting default mixerSoftMaster for Softvolume device Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 27 21:25:51 speaker2 volumio[1162]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 27 21:25:51 speaker2 volumio[1162]: info: Disabling external Volume Control Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 21:25:51 speaker2 volumio[1162]: info: Enable softmixer device for audio device undefined Apr 27 21:25:51 speaker2 volumio[1162]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Apr 27 21:25:51 speaker2 volumio[1162]: info: Software Volume ALSA configuration written Apr 27 21:25:51 speaker2 volumio[1162]: info: Preparing to generate the ALSA configuration file Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 21:25:51 speaker2 volumio[1162]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 27 21:25:51 speaker2 volumio[1162]: info: Reading ALSA contributions from plugins. Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:25:51 speaker2 volumio[1162]: info: Asound.conf file written Apr 27 21:25:51 speaker2 sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 27 21:25:51 speaker2 sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:25:51 speaker2 sudo[2978]: pam_unix(sudo:session): session closed for user root Apr 27 21:25:51 speaker2 volumio[1162]: No state is present for card TothTechnikaDAC Apr 27 21:25:51 speaker2 volumio[1162]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:0a09" "" "" Apr 27 21:25:51 speaker2 volumio[1162]: Hardware is initialized using a generic method Apr 27 21:25:51 speaker2 volumio[1162]: No state is present for card TothTechnikaDAC Apr 27 21:25:51 speaker2 volumio[1162]: info: Output device has changed, restarting MPD Apr 27 21:25:51 speaker2 sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 21:25:51 speaker2 sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:25:51 speaker2 sudo[2983]: pam_unix(sudo:session): session closed for user root Apr 27 21:25:51 speaker2 volumio[1162]: info: Output device has changed, restarting Shairport Sync Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:25:51 speaker2 sudo[2986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 21:25:51 speaker2 sudo[2986]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:25:51 speaker2 systemd[1]: Stopping Music Player Daemon... Apr 27 21:25:51 speaker2 volumio[1162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 21:25:51 speaker2 volumio[1162]: info: Output device has changed, restarting MPD Apr 27 21:25:51 speaker2 systemd[1]: mpd.service: Succeeded. Apr 27 21:25:51 speaker2 systemd[1]: Stopped Music Player Daemon. Apr 27 21:25:51 speaker2 sudo[2993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 21:25:51 speaker2 volumio[1162]: info: Output device has changed, restarting Shairport Sync Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 21:25:51 speaker2 volumio[1162]: info: MPD Permissions set Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 systemd[1]: Starting Music Player Daemon... Apr 27 21:25:51 speaker2 sudo[2993]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:25:51 speaker2 sudo[2993]: pam_unix(sudo:session): session closed for user root Apr 27 21:25:51 speaker2 sudo[2996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 21:25:51 speaker2 sudo[2996]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 27 21:25:51 speaker2 systemd[1]: mpd.service: Succeeded. Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 systemd[1]: Stopped Music Player Daemon. Apr 27 21:25:51 speaker2 systemd[1]: Starting Music Player Daemon... Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:25:51 speaker2 volumio[1162]: info: Starting Shairport Sync Apr 27 21:25:51 speaker2 volumio[1162]: info: Starting Shairport Sync Apr 27 21:25:51 speaker2 sudo[3011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 21:25:51 speaker2 sudo[3011]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:25:51 speaker2 sudo[3006]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 27 21:25:51 speaker2 sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:25:51 speaker2 sudo[3006]: pam_unix(sudo:session): session closed for user root Apr 27 21:25:51 speaker2 sudo[3014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 21:25:51 speaker2 sudo[3014]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:25:51 speaker2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 21:25:51 speaker2 systemd[1]: shairport-sync.service: Succeeded. Apr 27 21:25:51 speaker2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 21:25:51 speaker2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 21:25:51 speaker2 sudo[3011]: pam_unix(sudo:session): session closed for user root Apr 27 21:25:51 speaker2 sudo[3014]: pam_unix(sudo:session): session closed for user root Apr 27 21:25:51 speaker2 volumio[1162]: info: Shairport-Sync Started Apr 27 21:25:51 speaker2 volumio[1162]: info: Shairport-Sync Started Apr 27 21:25:51 speaker2 volumio[1162]: (node:1162) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 27 21:25:51 speaker2 volumio[1162]: (Use `node --trace-warnings ...` to show where the warning was created) Apr 27 21:25:51 speaker2 volumio[1162]: (node:1162) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 27 21:25:51 speaker2 volumio[1162]: (node:1162) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 27 21:25:51 speaker2 volumio[1162]: (node:1162) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 27 21:25:51 speaker2 volumio[1162]: (node:1162) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 27 21:25:52 speaker2 mpd[3016]: Apr 27 21:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 27 21:25:52 speaker2 systemd[1]: Started Music Player Daemon. Apr 27 21:25:52 speaker2 sudo[2986]: pam_unix(sudo:session): session closed for user root Apr 27 21:25:52 speaker2 sudo[2996]: pam_unix(sudo:session): session closed for user root Apr 27 21:25:52 speaker2 volumio[1162]: error: MPD error: The expression evaluated to a falsy value: Apr 27 21:25:52 speaker2 volumio[1162]: assert.ok(self.idling) Apr 27 21:25:52 speaker2 volumio[1162]: error: The expression evaluated to a falsy value: Apr 27 21:25:52 speaker2 volumio[1162]: assert.ok(self.idling) Apr 27 21:25:52 speaker2 volumio[1162]: error: updateQueue error: null Apr 27 21:25:52 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 27 21:25:53 speaker2 go-librespot[1453]: time="2025-04-27T21:25:53+02:00" level=error msg="did not receive last pong from dealer, 120s passed" Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreStateMachine::getcurrentVolume Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreStateMachine::updateTrackBlock Apr 27 21:25:55 speaker2 volumio[1162]: info: CorePlayQueue::getTrackBlock Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:25:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:25:59 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:25:59 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:25:59 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:25:59 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:25:59 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:26:00 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:26:00 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:26:02 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="KisCsirkeAC" Apr 27 21:26:02 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="KisCsirke" Apr 27 21:26:02 speaker2 wpa_supplicant[1055]: wlan0: Trying to associate with SSID 'KisCsirkeAC' Apr 27 21:26:02 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 27 21:26:02 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="KisCsirkeAC" auth_failures=3 duration=46 reason=CONN_FAILED Apr 27 21:26:10 speaker2 dhcpcd[1067]: eth0: carrier acquired Apr 27 21:26:10 speaker2 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Apr 27 21:26:10 speaker2 dhcpcd[1067]: eth0: IAID 67:73:e0:ac Apr 27 21:26:10 speaker2 dhcpcd[1067]: eth0: adding address fe80::2969:a5a0:c71b:f4e9 Apr 27 21:26:10 speaker2 dhcpcd[1067]: ipv6_addaddr1: Permission denied Apr 27 21:26:10 speaker2 ifplugd(eth0)[973]: Link beat detected. Apr 27 21:26:10 speaker2 ifplugd(eth0)[973]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 27 21:26:10 speaker2 dhcpcd[1067]: eth0: soliciting an IPv6 router Apr 27 21:26:10 speaker2 dhcpcd[3088]: sending commands to master dhcpcd process Apr 27 21:26:10 speaker2 dhcpcd[1067]: control command: /sbin/dhcpcd eth0 Apr 27 21:26:10 speaker2 ifplugd(eth0)[973]: client: sending commands to master dhcpcd process Apr 27 21:26:10 speaker2 ifplugd(eth0)[973]: Program executed successfully. Apr 27 21:26:10 speaker2 dhcpcd[1067]: eth0: rebinding lease of 10.13.10.49 Apr 27 21:26:15 speaker2 dhcpcd[1067]: eth0: probing address 10.13.10.49/24 Apr 27 21:26:16 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:26:16 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:26:16 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:26:16 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:26:16 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:26:16 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:26:16 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:26:18 speaker2 wpa_supplicant[1055]: wlan0: Failed to initiate sched scan Apr 27 21:26:20 speaker2 dhcpcd[1067]: eth0: leased 10.13.10.49 for 2851200 seconds Apr 27 21:26:20 speaker2 avahi-daemon[848]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.13.10.49. Apr 27 21:26:20 speaker2 avahi-daemon[848]: New relevant interface eth0.IPv4 for mDNS. Apr 27 21:26:20 speaker2 dhcpcd[1067]: eth0: adding route to 10.13.10.0/24 Apr 27 21:26:20 speaker2 dhcpcd[1067]: eth0: adding default route via 10.13.10.1 Apr 27 21:26:20 speaker2 avahi-daemon[848]: Registering new address record for 10.13.10.49 on eth0.IPv4. Apr 27 21:26:21 speaker2 sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 21:26:21 speaker2 sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:21 speaker2 sudo[3153]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:21 speaker2 sudo[3156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 21:26:21 speaker2 sudo[3156]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:21 speaker2 sudo[3156]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:21 speaker2 volumio[1162]: verbose: New Socket.io Connection to 10.13.10.49 from 10.13.10.14 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 5 Apr 27 21:26:21 speaker2 volumio[1162]: info: Discovery: adding d3b197d0-89b0-427b-93d5-82de886fcd61 Apr 27 21:26:21 speaker2 volumio[1162]: info: Discovery: Found device Speaker2 Apr 27 21:26:21 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:21 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 21:26:21 speaker2 go-librespot[1453]: time="2025-04-27T21:26:21+02:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:1oQhjTUTPHXrvtGk2lsUO1" Apr 27 21:26:21 speaker2 go-librespot[1453]: time="2025-04-27T21:26:21+02:00" level=debug msg="fetched chunk 11/19, size: 524288" uri="spotify:track:1oQhjTUTPHXrvtGk2lsUO1" Apr 27 21:26:21 speaker2 go-librespot[1453]: time="2025-04-27T21:26:21+02:00" level=debug msg="fetched chunk 12/19, size: 524288" uri="spotify:track:1oQhjTUTPHXrvtGk2lsUO1" Apr 27 21:26:21 speaker2 go-librespot[1453]: time="2025-04-27T21:26:21+02:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:1oQhjTUTPHXrvtGk2lsUO1" Apr 27 21:26:22 speaker2 sudo[3159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 21:26:22 speaker2 sudo[3159]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:22 speaker2 sudo[3159]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:22 speaker2 sudo[3162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 21:26:22 speaker2 sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:22 speaker2 sudo[3162]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:22 speaker2 volumio[1162]: verbose: New Socket.io Connection to 10.13.10.49 from 10.13.10.14 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 6 Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 21:26:22 speaker2 volumio[1162]: info: Received Get System Info Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:26:22 speaker2 volumio[1162]: info: Discovery: Getting this device information Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:22 speaker2 volumio[1162]: info: Listing playlists Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 27 21:26:22 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 21:26:22 speaker2 ntpd[981]: Listen normally on 5 eth0 10.13.10.49:123 Apr 27 21:26:22 speaker2 ntpd[981]: new interface(s) found: waking up resolver Apr 27 21:26:23 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:23 speaker2 go-librespot[1453]: time="2025-04-27T21:26:23+02:00" level=error msg="did not receive last pong from dealer, 150s passed" Apr 27 21:26:23 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 27 21:26:24 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 21:26:24 speaker2 volumio[1162]: info: Received Get System Info Apr 27 21:26:24 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:26:24 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:26:24 speaker2 volumio[1162]: info: Discovery: Getting this device information Apr 27 21:26:24 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:24 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:26:24 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 27 21:26:27 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:26:27 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:26:27 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:26:27 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:26:27 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:26:27 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:26:27 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:26:27 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 21:26:27 speaker2 volumio[1162]: info: Received Get System Info Apr 27 21:26:27 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:26:27 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:26:27 speaker2 volumio[1162]: info: Discovery: Getting this device information Apr 27 21:26:27 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:27 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:26:27 speaker2 volumio[1162]: info: Volumio Network Manager: Network status updated: 1 Apr 27 21:26:29 speaker2 ntpd[981]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 27 21:26:30 speaker2 wpa_supplicant[1055]: wlan0: Failed to initiate sched scan Apr 27 21:26:32 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 21:26:38 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:26:38 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:26:38 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:26:38 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:26:38 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:26:38 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:26:38 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:26:39 speaker2 sudo[3235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 21:26:39 speaker2 sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:39 speaker2 sudo[3235]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:39 speaker2 sudo[3238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 21:26:39 speaker2 sudo[3238]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:39 speaker2 sudo[3238]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:39 speaker2 volumio[1162]: verbose: New Socket.io Connection to 10.13.10.49 from 10.13.10.14 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 6 Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 21:26:40 speaker2 volumio[1162]: info: Received Get System Info Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:26:40 speaker2 volumio[1162]: info: Discovery: Getting this device information Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:40 speaker2 volumio[1162]: info: Listing playlists Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 21:26:40 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:41 speaker2 wpa_supplicant[1055]: wlan0: Failed to initiate sched scan Apr 27 21:26:41 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 27 21:26:41 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 21:26:41 speaker2 volumio[1162]: info: Received Get System Info Apr 27 21:26:41 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:26:41 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:26:41 speaker2 volumio[1162]: info: Discovery: Getting this device information Apr 27 21:26:41 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:41 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:26:42 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 21:26:42 speaker2 volumio[1162]: info: Received Get System Info Apr 27 21:26:42 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:26:42 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:26:42 speaker2 volumio[1162]: info: Discovery: Getting this device information Apr 27 21:26:42 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:42 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 27 21:26:44 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 21:26:49 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Apr 27 21:26:49 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Apr 27 21:26:49 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Apr 27 21:26:49 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 27 21:26:49 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 27 21:26:49 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 21:26:50 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 27 21:26:50 speaker2 volumio[1162]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 27 21:26:50 speaker2 volumio[1162]: info: Preparing to save Alsa Options, stopping services first Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPause Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreStateMachine::pause Apr 27 21:26:50 speaker2 volumio[1162]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"TothTechnika-DAC-2025"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:26:50 speaker2 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 27 21:26:50 speaker2 volumio[1162]: info: Setting default mixerSoftMaster for Softvolume device Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 27 21:26:50 speaker2 volumio[1162]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 27 21:26:50 speaker2 volumio[1162]: info: Disabling external Volume Control Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 21:26:50 speaker2 volumio[1162]: info: Enable softmixer device for audio device undefined Apr 27 21:26:50 speaker2 volumio[1162]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 21:26:50 speaker2 volumio[1162]: info: Software Volume ALSA configuration written Apr 27 21:26:50 speaker2 volumio[1162]: info: Preparing to generate the ALSA configuration file Apr 27 21:26:50 speaker2 volumio[1162]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 27 21:26:50 speaker2 volumio[1162]: info: Reading ALSA contributions from plugins. Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:26:50 speaker2 volumio[1162]: info: Asound.conf file unchanged, so no further update is needed Apr 27 21:26:50 speaker2 volumio[1162]: info: Output device has changed, restarting MPD Apr 27 21:26:50 speaker2 sudo[3278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 21:26:50 speaker2 sudo[3278]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:50 speaker2 sudo[3278]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:50 speaker2 volumio[1162]: info: Output device has changed, restarting Shairport Sync Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:26:50 speaker2 sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 21:26:50 speaker2 sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:50 speaker2 systemd[1]: Stopping Music Player Daemon... Apr 27 21:26:50 speaker2 volumio[1162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 21:26:50 speaker2 systemd[1]: mpd.service: Succeeded. Apr 27 21:26:50 speaker2 systemd[1]: Stopped Music Player Daemon. Apr 27 21:26:50 speaker2 volumio[1162]: info: MPD Permissions set Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 21:26:50 speaker2 volumio[1162]: info: Starting Shairport Sync Apr 27 21:26:50 speaker2 sudo[3292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 21:26:50 speaker2 sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:50 speaker2 systemd[1]: Starting Music Player Daemon... Apr 27 21:26:50 speaker2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 21:26:50 speaker2 systemd[1]: shairport-sync.service: Succeeded. Apr 27 21:26:50 speaker2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 21:26:50 speaker2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 21:26:50 speaker2 sudo[3292]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:50 speaker2 volumio[1162]: info: Shairport-Sync Started Apr 27 21:26:50 speaker2 sudo[3286]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 27 21:26:50 speaker2 sudo[3286]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:26:50 speaker2 sudo[3286]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:50 speaker2 mpd[3298]: Apr 27 21:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 27 21:26:50 speaker2 systemd[1]: Started Music Player Daemon. Apr 27 21:26:50 speaker2 sudo[3281]: pam_unix(sudo:session): session closed for user root Apr 27 21:26:50 speaker2 volumio[1162]: error: updateQueue error: null Apr 27 21:26:52 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="KisCsirkeAC" Apr 27 21:26:52 speaker2 wpa_supplicant[1055]: wlan0: Trying to associate with SSID 'KisCsirkeAC' Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 10.13.10.52:57562->104.199.65.9:4070: read: connection timed out" Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: carrier acquired Apr 27 21:26:53 speaker2 wpa_supplicant[1055]: wlan0: Associated with c4:ad:34:ae:fa:39 Apr 27 21:26:53 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-CONNECTED - Connection to c4:ad:34:ae:fa:39 completed [id=0 id_str=] Apr 27 21:26:53 speaker2 wpa_supplicant[1055]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: connected to Access Point `KisCsirkeAC' Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: IAID 67:73:e0:ad Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: carrier lost Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: carrier acquired Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: IAID 67:73:e0:ad Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=debug msg="completed keyexchange" Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=debug msg="completed challenge" Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=info msg="authenticated AP as blq1ke9vc8e4p95mn6sqlt9j8" Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=debug msg="re-established accesspoint connection" Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=trace msg="received accesspoint ping" Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: soliciting an IPv6 router Apr 27 21:26:53 speaker2 go-librespot[1453]: time="2025-04-27T21:26:53+02:00" level=error msg="did not receive last pong from dealer, 180s passed" Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: rebinding lease of 10.13.10.52 Apr 27 21:26:53 speaker2 dhcpcd[1067]: wlan0: probing address 10.13.10.52/24 Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreStateMachine::getcurrentVolume Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreStateMachine::updateTrackBlock Apr 27 21:26:54 speaker2 volumio[1162]: info: CorePlayQueue::getTrackBlock Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:26:54 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:26:55 speaker2 volumio[1162]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:55 speaker2 volumio[1162]: info: Restoring Previous Volume level: false true Apr 27 21:26:55 speaker2 volumio[1162]: info: VolumeController::SetAlsaVolume100 Apr 27 21:26:55 speaker2 volumio[1162]: info: Volume configurations have been set Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 27 21:26:55 speaker2 volumio[1162]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 27 21:26:55 speaker2 volumio[1162]: info: Disabling external Volume Control Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:26:55 speaker2 volumio[1162]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 21:26:55 speaker2 volumio[1162]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Apr 27 21:26:55 speaker2 volumio[1162]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:26:55 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:26:58 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 21:26:58 speaker2 volumio[1162]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 27 21:26:58 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 27 21:26:58 speaker2 volumio[1162]: info: Received Get System Version Apr 27 21:26:58 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 21:26:58 speaker2 volumio[1162]: info: Received Get System Info Apr 27 21:26:58 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:26:58 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:26:58 speaker2 volumio[1162]: info: Discovery: Getting this device information Apr 27 21:26:58 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:58 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:26:58 speaker2 dhcpcd[1067]: wlan0: leased 10.13.10.52 for 2851200 seconds Apr 27 21:26:58 speaker2 avahi-daemon[848]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.13.10.52. Apr 27 21:26:58 speaker2 dhcpcd[1067]: wlan0: adding route to 10.13.10.0/24 Apr 27 21:26:58 speaker2 dhcpcd[1067]: wlan0: adding default route via 10.13.10.1 Apr 27 21:26:58 speaker2 avahi-daemon[848]: New relevant interface wlan0.IPv4 for mDNS. Apr 27 21:26:58 speaker2 avahi-daemon[848]: Registering new address record for 10.13.10.52 on wlan0.IPv4. Apr 27 21:26:59 speaker2 volumio[1162]: info: CoreCommandRouter::volumioVolatilePlay Apr 27 21:26:59 speaker2 volumio[1162]: info: CoreStateMachine::volatilePlay Apr 27 21:26:59 speaker2 volumio[1162]: info: Spotify Play Apr 27 21:26:59 speaker2 volumio[1162]: info: Sending Spotify command to local API: /player/resume Apr 27 21:26:59 speaker2 volumio[1162]: info: VolumeController::SetAlsaVolumeNaN Apr 27 21:26:59 speaker2 volumio[1162]: info: CoreStateMachine::pushState Apr 27 21:26:59 speaker2 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:26:59 speaker2 volumio[1162]: info: CoreCommandRouter::volumioPushState Apr 27 21:26:59 speaker2 volumio[1162]: info: Discovery: this is already registered, d3b197d0-89b0-427b-93d5-82de886fcd61 Apr 27 21:26:59 speaker2 volumio[1162]: info: Discovery: Found device Speaker2 Apr 27 21:26:59 speaker2 volumio[1162]: info: CoreCommandRouter::volumioGetState Apr 27 21:26:59 speaker2 volumio[1162]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 21:26:59 speaker2 volumio[1162]: Error: The value NaN is not a number Apr 27 21:26:59 speaker2 volumio[1162]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 27 21:26:59 speaker2 volumio[1162]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 27 21:26:59 speaker2 volumio[1162]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 27 21:26:59 speaker2 volumio[1162]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 27 21:26:59 speaker2 volumio[1162]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 27 21:26:59 speaker2 volumio[1162]: at Browser.emit (events.js:315:20) Apr 27 21:26:59 speaker2 volumio[1162]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 27 21:26:59 speaker2 volumio[1162]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 27 21:26:59 speaker2 volumio[1162]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 27 21:26:59 speaker2 volumio[1162]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 27 21:26:59 speaker2 volumio[1162]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 27 21:26:59 speaker2 volumio[1162]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 27 21:26:59 speaker2 volumio[1162]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 27 21:26:59 speaker2 volumio[1162]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 21:26:59 speaker2 sudo[3393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-27 21:25 Apr 27 21:26:59 speaker2 sudo[3393]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"