-- 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"