-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2025-04-16 22:40:24 CEST. -- Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 16 22:39:01 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 16 22:39:01 volumio volumio-remote-updater[663]: No test mode Apr 16 22:39:01 volumio volumio-remote-updater[663]: No alpha test mode Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 16 22:39:01 volumio volumio[920]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 22:39:01 volumio volumio[920]: info: Discovery: Getting this device information Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:39:01 volumio volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 16 22:39:01 volumio volumio[920]: info: Loading i18n strings for locale it Apr 16 22:39:01 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 16 22:39:01 volumio volumio[920]: info: Setting timezone to Europe/Rome Apr 16 22:39:01 volumio sudo[1940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 16 22:39:01 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:01 volumio sudo[1940]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:01 volumio sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Apr 16 22:39:01 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:01 volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:02 volumio sudo[1946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 16 22:39:02 volumio sudo[1946]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:02 volumio sudo[1946]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:02 volumio sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Apr 16 22:39:02 volumio sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:02 volumio dbus-daemon[670]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=1950 comm="/usr/bin/timedatectl set-timezone Europe/Rome ") Apr 16 22:39:02 volumio systemd[1]: Starting Time & Date Service... Apr 16 22:39:02 volumio dbus-daemon[670]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 16 22:39:02 volumio systemd[1]: Started Time & Date Service. Apr 16 22:39:02 volumio sudo[1949]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:02 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 16 22:39:02 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 16 22:39:02 volumio volumio[920]: info: Fetching Streaming Services browse cache Apr 16 22:39:02 volumio sudo[1953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 16 22:39:02 volumio sudo[1953]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:02 volumio volumio[920]: Updating browse sources language Apr 16 22:39:02 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 22:39:03 volumio sudo[1953]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:18 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 16 22:39:18 volumio volumio[920]: info: System name has changed, restarting Shairport Sync Apr 16 22:39:18 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:18 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:18 volumio volumio[920]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 22:39:18 volumio volumio[920]: info: Updating MyVolumio device info Apr 16 22:39:18 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:18 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:18 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:18 volumio volumio[920]: info: Discovery: Restarting Advertising due to device name change Apr 16 22:39:18 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Apr 16 22:39:18 volumio sudo[2031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 16 22:39:18 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:18 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:19 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 16 22:39:19 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:19 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 22:39:19 volumio systemd[1]: upmpdcli.service: Succeeded. Apr 16 22:39:19 volumio volumio[920]: info: Starting Shairport Sync Apr 16 22:39:19 volumio sudo[2036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 16 22:39:19 volumio sudo[2036]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:19 volumio sudo[2036]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:19 volumio sudo[2039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 22:39:19 volumio volumio[920]: info: Permissions for /etc/hosts set Apr 16 22:39:19 volumio sudo[2039]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 16 22:39:19 volumio volumio[920]: info: Hostname now is volumiosala Apr 16 22:39:19 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 16 22:39:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 16 22:39:19 volumio sudo[2042]: volumio : unable to resolve host volumio Apr 16 22:39:19 volumiosala systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 16 22:39:19 volumiosala volumio[920]: info: New hostname set Apr 16 22:39:19 volumiosala sudo[2042]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 16 22:39:19 volumiosala sudo[2042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hostname volumiosala Apr 16 22:39:19 volumiosala sudo[2042]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:19 volumiosala sudo[2042]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:19 volumiosala sudo[2045]: volumio : unable to resolve host volumio Apr 16 22:39:19 volumiosala sudo[2045]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 16 22:39:19 volumiosala sudo[2045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Apr 16 22:39:19 volumiosala sudo[2045]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:19 volumiosala sudo[2045]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:19 volumiosala volumio[920]: info: Permissions for /etc/avahi/services/volumio.service Apr 16 22:39:19 volumiosala sudo[2039]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:19 volumiosala avahi-daemon[669]: Files changed, reloading. Apr 16 22:39:19 volumiosala volumio[920]: info: Avahi name changed to volumiosala Apr 16 22:39:19 volumiosala avahi-daemon[669]: Loading service file /services/volumio.service. Apr 16 22:39:19 volumiosala volumio[920]: info: Shairport-Sync Started Apr 16 22:39:19 volumiosala volumio[920]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 16 22:39:19 volumiosala volumio[920]: info: Discovery: A device disappeared from network Apr 16 22:39:19 volumiosala volumio[920]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Apr 16 22:39:19 volumiosala volumio[920]: info: Discovery: A device disappeared from network Apr 16 22:39:19 volumiosala volumio[920]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Apr 16 22:39:20 volumiosala avahi-daemon[669]: Service "volumiosala" (/services/volumio.service) successfully established. Apr 16 22:39:23 volumiosala volumio[920]: info: Discovery: Started advertising with name: volumiosala Apr 16 22:39:24 volumiosala volumio[920]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 16 22:39:24 volumiosala volumio[920]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 16 22:39:24 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 22:39:24 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 22:39:24 volumiosala volumio[920]: info: Discovery: Getting this device information Apr 16 22:39:24 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:39:24 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:24 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 22:39:28 volumiosala sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Apr 16 22:39:28 volumiosala sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:28 volumiosala sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Apr 16 22:39:28 volumiosala sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:28 volumiosala sudo[2071]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:28 volumiosala volumio[920]: info: smb.conf Permissions set Apr 16 22:39:28 volumiosala sudo[2073]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:29 volumiosala volumio[920]: info: Internal perms successfully set Apr 16 22:39:29 volumiosala sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Apr 16 22:39:29 volumiosala sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:29 volumiosala nmbd[779]: [2025/04/16 22:39:29.113309, 0] ../source3/nmbd/nmbd.c:58(terminate) Apr 16 22:39:29 volumiosala nmbd[779]: Got SIGTERM: going down... Apr 16 22:39:29 volumiosala systemd[1]: Stopping Samba NMB Daemon... Apr 16 22:39:29 volumiosala sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 22:39:29 volumiosala sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:29 volumiosala sudo[2080]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:29 volumiosala systemd[1]: nmbd.service: Succeeded. Apr 16 22:39:29 volumiosala systemd[1]: Stopped Samba NMB Daemon. Apr 16 22:39:29 volumiosala sudo[2082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 22:39:29 volumiosala sudo[2082]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:29 volumiosala sudo[2082]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:29 volumiosala sudo[2085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 16 22:39:29 volumiosala sudo[2085]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:29 volumiosala systemd[1]: Starting Samba NMB Daemon... Apr 16 22:39:29 volumiosala systemd[1]: Started UPnP Renderer front-end to MPD. Apr 16 22:39:29 volumiosala sudo[2085]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:29 volumiosala volumio[920]: info: Upmpdcli Daemon Started Apr 16 22:39:29 volumiosala volumio[2090]: writing RSA key Apr 16 22:39:29 volumiosala nmbd[2089]: [2025/04/16 22:39:29.546041, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 16 22:39:29 volumiosala nmbd[2089]: started asyncdns process 2112 Apr 16 22:39:29 volumiosala nmbd[2089]: [2025/04/16 22:39:29.555396, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 16 22:39:29 volumiosala nmbd[2089]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 16 22:39:29 volumiosala systemd[1]: Started Samba NMB Daemon. Apr 16 22:39:29 volumiosala sudo[2077]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:29 volumiosala sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Apr 16 22:39:29 volumiosala sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:29 volumiosala systemd[1]: Stopping Samba SMB Daemon... Apr 16 22:39:29 volumiosala systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM Apr 16 22:39:29 volumiosala systemd[1]: smbd.service: Succeeded. Apr 16 22:39:29 volumiosala systemd[1]: Stopped Samba SMB Daemon. Apr 16 22:39:29 volumiosala systemd[1]: Starting Samba SMB Daemon... Apr 16 22:39:30 volumiosala winbindd[997]: [2025/04/16 22:39:30.279489, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Apr 16 22:39:30 volumiosala winbindd[997]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2446350647-1483648078-276251285-501 Apr 16 22:39:30 volumiosala winbindd[997]: [2025/04/16 22:39:30.280888, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Apr 16 22:39:30 volumiosala winbindd[997]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2446350647-1483648078-276251285-514 Apr 16 22:39:30 volumiosala smbd[2125]: [2025/04/16 22:39:30.315473, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 16 22:39:30 volumiosala systemd[1]: Started Samba SMB Daemon. Apr 16 22:39:30 volumiosala smbd[2125]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 16 22:39:30 volumiosala sudo[2117]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:30 volumiosala volumio[920]: info: SAMBA Restarted Apr 16 22:39:31 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 22:39:31 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 22:39:31 volumiosala volumio[920]: info: Discovery: Getting this device information Apr 16 22:39:31 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:39:31 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:31 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 22:39:32 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 16 22:39:32 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 16 22:39:32 volumiosala systemd[1]: systemd-timedated.service: Succeeded. Apr 16 22:39:32 volumiosala volumio-remote-updater[663]: No test mode Apr 16 22:39:32 volumiosala volumio-remote-updater[663]: No alpha test mode Apr 16 22:39:32 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 16 22:39:32 volumiosala volumio[920]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 16 22:39:32 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 16 22:39:39 volumiosala kernel: usb 1-1.2: new high-speed USB device number 4 using dwc_otg Apr 16 22:39:39 volumiosala kernel: usb 1-1.2: New USB device found, idVendor=2772, idProduct=0230, bcdDevice= 2.12 Apr 16 22:39:39 volumiosala kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 16 22:39:39 volumiosala kernel: usb 1-1.2: Product: Pre Box S2 Digital Apr 16 22:39:39 volumiosala kernel: usb 1-1.2: Manufacturer: Pro-Ject Apr 16 22:39:39 volumiosala kernel: input: Pro-Ject Pre Box S2 Digital as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.3/0003:2772:0230.0001/input/input0 Apr 16 22:39:39 volumiosala kernel: hid-generic 0003:2772:0230.0001: input,hidraw0: USB HID v1.10 Device [Pro-Ject Pre Box S2 Digital] on usb-3f980000.usb-1.2/input3 Apr 16 22:39:40 volumiosala kernel: usb 1-1.2: 1:2 : unsupported format bits 0x100000000 Apr 16 22:39:40 volumiosala kernel: usbcore: registered new interface driver snd-usb-audio Apr 16 22:39:40 volumiosala systemd-udevd[2151]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 16 22:39:40 volumiosala systemd-udevd[2151]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Apr 16 22:39:40 volumiosala volumio[920]: info: Apr 16 22:39:40 volumiosala volumio[920]: ---------------------------- USB Audio Device Attached Apr 16 22:39:40 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 16 22:39:49 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 16 22:39:49 volumiosala volumio[920]: info: Preparing to save Alsa Options, stopping services first Apr 16 22:39:49 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:39:49 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:49 volumiosala volumio[920]: info: CoreCommandRouter::volumioPause Apr 16 22:39:49 volumiosala volumio[920]: info: CoreStateMachine::pause Apr 16 22:39:49 volumiosala volumio[920]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI Out"},"disallowPush":true} Apr 16 22:39:49 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 22:39:49 volumiosala volumio[920]: info: Enabling PI HDMI Output Apr 16 22:39:49 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 22:39:49 volumiosala volumio[920]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Apr 16 22:39:49 volumiosala volumio[920]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 16 22:39:49 volumiosala volumio[920]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 16 22:39:49 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 16 22:39:49 volumiosala volumio[920]: info: Disabling external Volume Control Apr 16 22:39:49 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:39:49 volumiosala volumio[920]: info: Preparing to generate the ALSA configuration file Apr 16 22:39:49 volumiosala volumio[920]: info: Raspberry PI HDMI Output Enabled Apr 16 22:39:49 volumiosala sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store Apr 16 22:39:49 volumiosala volumio[920]: info: Asound.conf file unchanged, so no further update is needed Apr 16 22:39:49 volumiosala volumio[920]: info: Output device has changed, restarting MPD Apr 16 22:39:49 volumiosala sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:50 volumiosala sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 22:39:50 volumiosala volumio[920]: info: Output device has changed, restarting Shairport Sync Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:50 volumiosala sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:50 volumiosala sudo[2188]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:50 volumiosala sudo[2191]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:50 volumiosala sudo[2193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 22:39:50 volumiosala sudo[2193]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:50 volumiosala systemd[1]: Stopping Music Player Daemon... Apr 16 22:39:50 volumiosala volumio[920]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 22:39:50 volumiosala systemd[1]: mpd.service: Succeeded. Apr 16 22:39:50 volumiosala systemd[1]: Stopped Music Player Daemon. Apr 16 22:39:50 volumiosala systemd[1]: Starting Music Player Daemon... Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 16 22:39:50 volumiosala volumio[920]: info: MPD Permissions set Apr 16 22:39:50 volumiosala volumio[920]: info: Alsa Settings successfully stored Apr 16 22:39:50 volumiosala volumio[920]: info: VolumeController:: Volume=86 Mute =false Apr 16 22:39:50 volumiosala sudo[2200]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 22:39:50 volumiosala volumio[920]: info: CoreStateMachine::pushState Apr 16 22:39:50 volumiosala sudo[2200]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:50 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::volumioPushState Apr 16 22:39:50 volumiosala sudo[2200]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:50 volumiosala volumio[920]: info: Starting Shairport Sync Apr 16 22:39:50 volumiosala sudo[2213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 22:39:50 volumiosala sudo[2213]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:50 volumiosala systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 16 22:39:50 volumiosala systemd[1]: shairport-sync.service: Succeeded. Apr 16 22:39:50 volumiosala systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 16 22:39:50 volumiosala systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 16 22:39:50 volumiosala sudo[2213]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:50 volumiosala volumio[920]: info: Shairport-Sync Started Apr 16 22:39:52 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 16 22:39:52 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Apr 16 22:39:52 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 16 22:39:52 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:52 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 22:39:53 volumiosala mpd[2207]: Apr 16 22:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 22:39:53 volumiosala systemd[1]: Started Music Player Daemon. Apr 16 22:39:53 volumiosala sudo[2193]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:53 volumiosala volumio[920]: error: updateQueue error: null Apr 16 22:39:53 volumiosala volumio[920]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 16 22:39:53 volumiosala volumio[920]: info: CoreStateMachine::getcurrentVolume Apr 16 22:39:53 volumiosala volumio[920]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 22:39:53 volumiosala volumio[920]: info: VolumeController:: Volume=86 Mute =false Apr 16 22:39:53 volumiosala volumio[920]: info: CoreStateMachine::pushState Apr 16 22:39:53 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:53 volumiosala volumio[920]: info: CoreCommandRouter::volumioPushState Apr 16 22:39:53 volumiosala volumio[920]: info: CoreStateMachine::updateTrackBlock Apr 16 22:39:53 volumiosala volumio[920]: info: CorePlayQueue::getTrackBlock Apr 16 22:39:53 volumiosala volumio[920]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 22:39:54 volumiosala volumio[920]: info: VolumeController:: Volume=86 Mute =false Apr 16 22:39:54 volumiosala volumio[920]: info: CoreStateMachine::pushState Apr 16 22:39:54 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:54 volumiosala volumio[920]: info: CoreCommandRouter::volumioPushState Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 16 22:39:57 volumiosala volumio[920]: info: Preparing to save Alsa Options, stopping services first Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:39:57 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::volumioPause Apr 16 22:39:57 volumiosala volumio[920]: info: CoreStateMachine::pause Apr 16 22:39:57 volumiosala volumio[920]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"Pre Box S2 Digital"},"disallowPush":true} Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 22:39:57 volumiosala volumio[920]: info: Setting mixer Pre Box S2 Digital for card Pre Box S2 Digital Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 16 22:39:57 volumiosala volumio[920]: info: Updating Volume Controller Parameters: Device: 5 Name: Pre Box S2 Digital Mixer: Pre Box S2 Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 16 22:39:57 volumiosala volumio[920]: info: Disabling external Volume Control Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:39:57 volumiosala volumio[920]: info: Preparing to generate the ALSA configuration file Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 16 22:39:57 volumiosala sudo[2237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 16 22:39:57 volumiosala sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:57 volumiosala sudo[2239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 16 22:39:57 volumiosala sudo[2239]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:57 volumiosala sudo[2239]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:57 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 16 22:39:58 volumiosala volumio[920]: info: VolumeController:: Volume=100 Mute =false Apr 16 22:39:58 volumiosala volumio[920]: info: CoreStateMachine::pushState Apr 16 22:39:58 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::volumioPushState Apr 16 22:39:58 volumiosala volumio[920]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 16 22:39:58 volumiosala volumio[920]: wlan0 Interface doesn't support scanning : Device or resource busy Apr 16 22:39:58 volumiosala volumio[920]: info: Cannot use regular scanning, forcing with ap-force Apr 16 22:39:58 volumiosala sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 16 22:39:58 volumiosala sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:58 volumiosala sudo[2248]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:58 volumiosala volumio[920]: command failed: Device or resource busy (-16) Apr 16 22:39:58 volumiosala volumio[920]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 16 22:39:58 volumiosala volumio[920]: command failed: Device or resource busy (-16) Apr 16 22:39:58 volumiosala volumio[920]: info: Asound.conf file written Apr 16 22:39:58 volumiosala sudo[2251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 16 22:39:58 volumiosala sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:58 volumiosala sudo[2251]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:58 volumiosala volumio[920]: info: Output device has changed, restarting MPD Apr 16 22:39:58 volumiosala volumio[920]: info: Output device has changed, restarting Shairport Sync Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:58 volumiosala sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 22:39:58 volumiosala sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:58 volumiosala sudo[2256]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:58 volumiosala sudo[2258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 22:39:58 volumiosala sudo[2258]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:58 volumiosala systemd[1]: Stopping Music Player Daemon... Apr 16 22:39:58 volumiosala volumio[920]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 22:39:58 volumiosala volumio[920]: info: Output device has changed, restarting MPD Apr 16 22:39:58 volumiosala systemd[1]: mpd.service: Succeeded. Apr 16 22:39:58 volumiosala systemd[1]: Stopped Music Player Daemon. Apr 16 22:39:58 volumiosala volumio[920]: info: Output device has changed, restarting Shairport Sync Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:58 volumiosala sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 22:39:58 volumiosala sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:58 volumiosala systemd[1]: Starting Music Player Daemon... Apr 16 22:39:58 volumiosala sudo[2265]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:58 volumiosala sudo[2272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 22:39:58 volumiosala sudo[2272]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:58 volumiosala sudo[2237]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:58 volumiosala systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 16 22:39:58 volumiosala systemd[1]: mpd.service: Succeeded. Apr 16 22:39:58 volumiosala systemd[1]: Stopped Music Player Daemon. Apr 16 22:39:58 volumiosala volumio[920]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 22:39:58 volumiosala systemd[1]: Starting Music Player Daemon... Apr 16 22:39:58 volumiosala volumio[920]: info: MPD Permissions set Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:39:58 volumiosala volumio[920]: info: Starting Shairport Sync Apr 16 22:39:58 volumiosala volumio[920]: info: Starting Shairport Sync Apr 16 22:39:58 volumiosala sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 22:39:58 volumiosala sudo[2288]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 22:39:58 volumiosala sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:58 volumiosala sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:58 volumiosala sudo[2288]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:59 volumiosala sudo[2300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 22:39:59 volumiosala sudo[2300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:39:59 volumiosala systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 16 22:39:59 volumiosala systemd[1]: shairport-sync.service: Succeeded. Apr 16 22:39:59 volumiosala systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 16 22:39:59 volumiosala systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 16 22:39:59 volumiosala sudo[2298]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:59 volumiosala sudo[2300]: pam_unix(sudo:session): session closed for user root Apr 16 22:39:59 volumiosala volumio[920]: info: Shairport-Sync Started Apr 16 22:39:59 volumiosala volumio[920]: info: Shairport-Sync Started Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 22:40:01 volumiosala volumio[920]: info: Discovery: Getting this device information Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:40:01 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 22:40:01 volumiosala mpd[2304]: Apr 16 22:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 22:40:01 volumiosala systemd[1]: Started Music Player Daemon. Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 16 22:40:01 volumiosala sudo[2272]: pam_unix(sudo:session): session closed for user root Apr 16 22:40:01 volumiosala volumio[920]: info: CoreStateMachine::getcurrentVolume Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 22:40:01 volumiosala sudo[2258]: pam_unix(sudo:session): session closed for user root Apr 16 22:40:01 volumiosala volumio[920]: error: MPD error: The expression evaluated to a falsy value: Apr 16 22:40:01 volumiosala volumio[920]: assert.ok(self.idling) Apr 16 22:40:01 volumiosala volumio[920]: error: The expression evaluated to a falsy value: Apr 16 22:40:01 volumiosala volumio[920]: assert.ok(self.idling) Apr 16 22:40:01 volumiosala volumio[920]: error: updateQueue error: null Apr 16 22:40:01 volumiosala volumio[920]: info: VolumeController:: Volume=100 Mute =false Apr 16 22:40:01 volumiosala volumio[920]: info: CoreStateMachine::pushState Apr 16 22:40:01 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::volumioPushState Apr 16 22:40:01 volumiosala volumio[920]: info: CoreStateMachine::updateTrackBlock Apr 16 22:40:01 volumiosala volumio[920]: info: CorePlayQueue::getTrackBlock Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 22:40:01 volumiosala volumio[920]: info: VolumeController:: Volume=100 Mute =false Apr 16 22:40:01 volumiosala volumio[920]: info: CoreStateMachine::pushState Apr 16 22:40:01 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:40:01 volumiosala volumio[920]: info: CoreCommandRouter::volumioPushState Apr 16 22:40:05 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:05 volumiosala volumio[920]: info: Retrieving Cloud Streaming UI Apr 16 22:40:05 volumiosala volumio[920]: info: Getting Tidal Cloud Configuration Apr 16 22:40:05 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:05 volumiosala volumio[920]: info: Getting Qobuz Cloud Configuration Apr 16 22:40:05 volumiosala volumio[920]: info: Asking plugin for UI Config Apr 16 22:40:05 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:05 volumiosala volumio[920]: info: Getting Spotify Cloud Configuration Apr 16 22:40:05 volumiosala volumio[920]: info: Asking plugin for UI Config Apr 16 22:40:05 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:05 volumiosala volumio[920]: info: Saving Spotify Acccount Apr 16 22:40:05 volumiosala volumio[920]: info: Got Tidal Cloud Configuration Apr 16 22:40:05 volumiosala volumio[920]: info: Got it Apr 16 22:40:05 volumiosala volumio[920]: info: Got it Apr 16 22:40:05 volumiosala volumio[920]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 16 22:40:05 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:05 volumiosala volumio[920]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 16 22:40:08 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Apr 16 22:40:08 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 16 22:40:08 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Apr 16 22:40:08 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Apr 16 22:40:08 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 16 22:40:15 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 16 22:40:15 volumiosala volumio[920]: info: CoreCommandRouter::Reload Ui Apr 16 22:40:15 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Apr 16 22:40:15 volumiosala volumio[920]: info: Wizard terminated Successfully Apr 16 22:40:15 volumiosala volumio[920]: info: CoreCommandRouter::volumiosetStartupVolume Apr 16 22:40:15 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 22:40:16 volumiosala sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 22:40:16 volumiosala sudo[2379]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:40:16 volumiosala sudo[2379]: pam_unix(sudo:session): session closed for user root Apr 16 22:40:16 volumiosala sudo[2381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 22:40:16 volumiosala sudo[2381]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:40:16 volumiosala sudo[2381]: pam_unix(sudo:session): session closed for user root Apr 16 22:40:16 volumiosala volumio[920]: verbose: New Socket.io Connection to 192.168.1.199 from 192.168.1.17 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Apr 16 22:40:16 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 16 22:40:17 volumiosala sudo[2385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 22:40:17 volumiosala sudo[2385]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 16 22:40:17 volumiosala sudo[2385]: pam_unix(sudo:session): session closed for user root Apr 16 22:40:17 volumiosala sudo[2387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 22:40:17 volumiosala sudo[2387]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 22:40:17 volumiosala sudo[2387]: pam_unix(sudo:session): session closed for user root Apr 16 22:40:17 volumiosala volumio[920]: verbose: New Socket.io Connection to 192.168.1.199 from 192.168.1.17 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetVisibleSources Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:40:17 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 22:40:17 volumiosala volumio[920]: info: Received Get System Info Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 22:40:17 volumiosala volumio[920]: info: Discovery: Getting this device information Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:40:17 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:40:17 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:40:17 volumiosala volumio[920]: info: Listing playlists Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 22:40:17 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 16 22:40:18 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 16 22:40:19 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 16 22:40:19 volumiosala volumio[920]: info: Received Get System Info Apr 16 22:40:19 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 22:40:19 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 22:40:19 volumiosala volumio[920]: info: Discovery: Getting this device information Apr 16 22:40:19 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:40:19 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:40:19 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 22:40:19 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Apr 16 22:40:20 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 16 22:40:20 volumiosala volumio[920]: info: Received Get System Info Apr 16 22:40:20 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 22:40:20 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 22:40:20 volumiosala volumio[920]: info: Discovery: Getting this device information Apr 16 22:40:20 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetState Apr 16 22:40:20 volumiosala volumio[920]: info: CorePlayQueue::getTrack 0 Apr 16 22:40:20 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:21 volumiosala volumio[920]: info: Retrieving Cloud Streaming UI Apr 16 22:40:21 volumiosala volumio[920]: info: Getting Tidal Cloud Configuration Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:21 volumiosala volumio[920]: info: Getting Qobuz Cloud Configuration Apr 16 22:40:21 volumiosala volumio[920]: info: Asking plugin for UI Config Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:21 volumiosala volumio[920]: info: Getting Spotify Cloud Configuration Apr 16 22:40:21 volumiosala volumio[920]: info: Asking plugin for UI Config Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:21 volumiosala volumio[920]: info: Saving Spotify Acccount Apr 16 22:40:21 volumiosala volumio[920]: info: Got Tidal Cloud Configuration Apr 16 22:40:21 volumiosala volumio[920]: info: Got it Apr 16 22:40:21 volumiosala volumio[920]: info: Got it Apr 16 22:40:21 volumiosala volumio[920]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 22:40:21 volumiosala volumio[920]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetBrowseSources Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetBrowseSources Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::volumioGetBrowseSources Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 16 22:40:21 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 16 22:40:23 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 16 22:40:23 volumiosala volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 16 22:40:23 volumiosala volumio[920]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 22:40:23 volumiosala volumio[920]: Error: getaddrinfo -3007 Apr 16 22:40:23 volumiosala volumio[920]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 16 22:40:23 volumiosala volumio[920]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 16 22:40:23 volumiosala volumio[920]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 16 22:40:23 volumiosala volumio[920]: code: -3007, Apr 16 22:40:23 volumiosala volumio[920]: errno: -3007, Apr 16 22:40:23 volumiosala volumio[920]: syscall: 'getaddrinfo' Apr 16 22:40:23 volumiosala volumio[920]: } Apr 16 22:40:23 volumiosala volumio[920]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 22:40:24 volumiosala sudo[2416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-16 22:39 Apr 16 22:40:24 volumiosala sudo[2416]: 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"