-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Thu 2024-07-25 21:49:44 BST. --
Jul 25 21:48:00 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 25 21:48:00 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:00 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:00 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 25 21:48:00 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:00 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:00 volumio volumio[874]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.193 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jul 25 21:48:00 volumio volumio[874]: info: Received Get System Info
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 25 21:48:00 volumio volumio[874]: info: Discovery: Getting this device information
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 25 21:48:00 volumio volumio-remote-updater[626]: No test mode
Jul 25 21:48:00 volumio volumio-remote-updater[626]: No alpha test mode
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:00 volumio volumio[874]: info: Retrieving Cloud Streaming UI
Jul 25 21:48:00 volumio volumio[874]: info: Getting Tidal Cloud Configuration
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:00 volumio volumio[874]: info: Getting Qobuz Cloud Configuration
Jul 25 21:48:00 volumio volumio[874]: info: Asking plugin for UI Config
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:00 volumio volumio[874]: info: Getting Spotify Cloud Configuration
Jul 25 21:48:00 volumio volumio[874]: info: Asking plugin for UI Config
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:00 volumio volumio[874]: info: Saving Spotify Acccount
Jul 25 21:48:00 volumio volumio[874]: info: Got Tidal Cloud Configuration
Jul 25 21:48:00 volumio volumio[874]: info: Got it
Jul 25 21:48:00 volumio volumio[874]: info: Got it
Jul 25 21:48:00 volumio volumio[874]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:00 volumio volumio[874]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 25 21:48:00 volumio volumio[874]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Jul 25 21:48:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 25 21:48:01 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jul 25 21:48:01 volumio volumio[874]: info: Loading i18n strings for locale en
Jul 25 21:48:01 volumio volumio[874]: Updating browse sources language
Jul 25 21:48:01 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 25 21:48:01 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jul 25 21:48:01 volumio volumio[874]: info: Setting timezone to Europe/London
Jul 25 21:48:01 volumio sudo[1980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jul 25 21:48:01 volumio sudo[1980]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:01 volumio sudo[1980]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:01 volumio sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime
Jul 25 21:48:01 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:01 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:01 volumio sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jul 25 21:48:01 volumio sudo[1986]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:01 volumio sudo[1986]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:01 volumio sudo[1989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London
Jul 25 21:48:01 volumio sudo[1989]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:01 volumio dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=1990 comm="/usr/bin/timedatectl set-timezone Europe/London ")
Jul 25 21:48:02 volumio systemd[1]: Starting Time & Date Service...
Jul 25 21:48:02 volumio dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1'
Jul 25 21:48:02 volumio systemd[1]: Started Time & Date Service.
Jul 25 21:48:02 volumio sudo[1989]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:02 volumio volumio[874]: info: Fetching Streaming Services browse cache
Jul 25 21:48:02 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 25 21:48:02 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Jul 25 21:48:02 volumio volumio[874]: info: MyVolumio login type: Token
Jul 25 21:48:03 volumio volumio[874]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jul 25 21:48:03 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 25 21:48:03 volumio volumio[874]: info: MyVolumio token set successfully
Jul 25 21:48:03 volumio volumio[874]: info: MYVOLUMIO: Adding device
Jul 25 21:48:03 volumio volumio[874]: info: MYVOLUMIO: Evaluating Server
Jul 25 21:48:03 volumio volumio[874]: info: MyVolumio status changed
Jul 25 21:48:03 volumio volumio[874]: info: Streaming services startup
Jul 25 21:48:03 volumio volumio[874]: info: Starting Streaming Daemon
Jul 25 21:48:03 volumio volumio[874]: info: Removing browser output: myVolumio user plan is not superstar
Jul 25 21:48:03 volumio volumio[874]: info: Removing audio output:
Jul 25 21:48:03 volumio volumio[874]: info: Stoppping Tunnel 1
Jul 25 21:48:03 volumio sudo[2010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jul 25 21:48:03 volumio sudo[2010]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:03 volumio sudo[2013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jul 25 21:48:03 volumio sudo[2013]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:03 volumio sudo[2010]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:03 volumio volumio[874]: error: Cannot start Volumio Streaming Daemon
Jul 25 21:48:03 volumio volumio[874]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jul 25 21:48:03 volumio volumio[874]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jul 25 21:48:03 volumio sudo[2013]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:03 volumio volumio[874]: info: Remote SSH Stopped
Jul 25 21:48:04 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jul 25 21:48:04 volumio volumio[874]: info: Loading i18n strings for locale en
Jul 25 21:48:04 volumio volumio[874]: Updating browse sources language
Jul 25 21:48:04 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 25 21:48:04 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jul 25 21:48:04 volumio volumio[874]: info: Setting timezone to Europe/London
Jul 25 21:48:04 volumio sudo[2016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jul 25 21:48:04 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:04 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:04 volumio sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime
Jul 25 21:48:04 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:04 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:04 volumio sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jul 25 21:48:04 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:04 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:04 volumio sudo[2025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London
Jul 25 21:48:04 volumio sudo[2025]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:04 volumio sudo[2025]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:04 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 25 21:48:04 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 25 21:48:04 volumio sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 25 21:48:04 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:05 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 25 21:48:05 volumio volumio[874]: info: Fetching Streaming Services browse cache
Jul 25 21:48:05 volumio volumio[874]: info: Setting Geolocation for MyVolumio to eu3
Jul 25 21:48:05 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:05 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:05 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:06 volumio volumio[874]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jul 25 21:48:06 volumio volumio[874]: info: Updating MyVolumio device info
Jul 25 21:48:06 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:07 volumio volumio[874]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jul 25 21:48:08 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:10 volumio volumio[874]: info: MYVOLUMIO: Adding device
Jul 25 21:48:10 volumio volumio[874]: info: MYVOLUMIO: Evaluating Server
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings
Jul 25 21:48:12 volumio volumio[874]: info: System name has changed, restarting Shairport Sync
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:12 volumio volumio[874]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 25 21:48:12 volumio volumio[874]: info: Updating MyVolumio device info
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:12 volumio volumio[874]: info: Discovery: Restarting Advertising due to device name change
Jul 25 21:48:12 volumio sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Jul 25 21:48:12 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:12 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices
Jul 25 21:48:12 volumio sudo[2052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts
Jul 25 21:48:12 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:12 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:12 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 25 21:48:12 volumio volumio[874]: info: Permissions for /etc/hosts set
Jul 25 21:48:12 volumio systemd[1]: upmpdcli.service: Succeeded.
Jul 25 21:48:12 volumio volumio[874]: info: Starting Shairport Sync
Jul 25 21:48:12 volumio sudo[2057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hostname volumiopi4
Jul 25 21:48:12 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:12 volumio volumio[874]: info: Hostname now is volumiopi4
Jul 25 21:48:12 volumiopi4 sudo[2057]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:12 volumiopi4 sudo[2059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 25 21:48:12 volumiopi4 sudo[2059]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:12 volumiopi4 volumio[874]: info: Setting Geolocation for MyVolumio to eu3
Jul 25 21:48:12 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:12 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:12 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:12 volumiopi4 volumio[874]: info: New hostname set
Jul 25 21:48:12 volumiopi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jul 25 21:48:12 volumiopi4 sudo[2063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/
Jul 25 21:48:12 volumiopi4 systemd[1]: shairport-sync.service: Succeeded.
Jul 25 21:48:12 volumiopi4 sudo[2063]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:12 volumiopi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jul 25 21:48:12 volumiopi4 sudo[2063]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:12 volumiopi4 volumio[874]: info: Permissions for /etc/avahi/services/volumio.service
Jul 25 21:48:12 volumiopi4 avahi-daemon[607]: Files changed, reloading.
Jul 25 21:48:12 volumiopi4 avahi-daemon[607]: Loading service file /services/volumio.service.
Jul 25 21:48:12 volumiopi4 volumio[874]: info: Avahi name changed to volumiopi4
Jul 25 21:48:12 volumiopi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jul 25 21:48:12 volumiopi4 sudo[2059]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:12 volumiopi4 volumio[874]: info: Shairport-Sync Started
Jul 25 21:48:12 volumiopi4 volumio[874]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jul 25 21:48:12 volumiopi4 volumio[874]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jul 25 21:48:13 volumiopi4 volumio[874]: info: Discovery: A device disappeared from network
Jul 25 21:48:13 volumiopi4 volumio[874]: info: Discovery: Device volumio disappeared from network
Jul 25 21:48:13 volumiopi4 volumio[874]: info: Discovery: A device disappeared from network
Jul 25 21:48:13 volumiopi4 avahi-daemon[607]: Service "VolumioPi4" (/services/volumio.service) successfully established.
Jul 25 21:48:13 volumiopi4 volumio[874]: info: Updating MyVolumio device info
Jul 25 21:48:13 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:13 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:13 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:13 volumiopi4 volumio[874]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Preparing to save Alsa Options, stopping services first
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioPause
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreStateMachine::pause
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI Out"},"disallowPush":true}
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Enabling PI HDMI Output
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Disabling external Volume Control
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Preparing to generate the ALSA configuration file
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Raspberry PI HDMI Output Enabled
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Asound.conf file unchanged, so no further update is needed
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Output device has changed, restarting MPD
Jul 25 21:48:15 volumiopi4 sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store
Jul 25 21:48:15 volumiopi4 sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:15 volumiopi4 sudo[2080]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:15 volumiopi4 sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 25 21:48:15 volumiopi4 sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Output device has changed, restarting Shairport Sync
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:15 volumiopi4 sudo[2083]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:15 volumiopi4 sudo[2085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 25 21:48:15 volumiopi4 sudo[2085]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:15 volumiopi4 systemd[1]: Stopping Music Player Daemon...
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 25 21:48:15 volumiopi4 sudo[2092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 25 21:48:15 volumiopi4 sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 25 21:48:15 volumiopi4 systemd[1]: mpd.service: Succeeded.
Jul 25 21:48:15 volumiopi4 systemd[1]: Stopped Music Player Daemon.
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Jul 25 21:48:15 volumiopi4 systemd[1]: Starting Music Player Daemon...
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 25 21:48:15 volumiopi4 volumio[874]: info: MPD Permissions set
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Alsa Settings successfully stored
Jul 25 21:48:15 volumiopi4 volumio[874]: info: VolumeController:: Volume=86 Mute =false
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreStateMachine::pushState
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioPushState
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 25 21:48:15 volumiopi4 sudo[2098]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jul 25 21:48:15 volumiopi4 sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 sudo[2098]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 sudo[2103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 25 21:48:15 volumiopi4 sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:15 volumiopi4 sudo[2103]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:15 volumiopi4 volumio[874]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Jul 25 21:48:15 volumiopi4 volumio[874]: wlan0 Interface doesn't support scanning : Device or resource busy
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Cannot use regular scanning, forcing with ap-force
Jul 25 21:48:15 volumiopi4 sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Jul 25 21:48:15 volumiopi4 sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:15 volumiopi4 sudo[2112]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:15 volumiopi4 volumio[874]: command failed: Device or resource busy (-16)
Jul 25 21:48:15 volumiopi4 volumio[874]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Jul 25 21:48:15 volumiopi4 volumio[874]: command failed: Device or resource busy (-16)
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Starting Shairport Sync
Jul 25 21:48:15 volumiopi4 sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 25 21:48:15 volumiopi4 sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:15 volumiopi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jul 25 21:48:15 volumiopi4 systemd[1]: shairport-sync.service: Succeeded.
Jul 25 21:48:15 volumiopi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jul 25 21:48:15 volumiopi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jul 25 21:48:15 volumiopi4 sudo[2115]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:15 volumiopi4 volumio[874]: info: Shairport-Sync Started
Jul 25 21:48:16 volumiopi4 mpd[2105]: Jul 25 21:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jul 25 21:48:16 volumiopi4 systemd[1]: Started Music Player Daemon.
Jul 25 21:48:16 volumiopi4 sudo[2085]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:16 volumiopi4 volumio[874]: error: updateQueue error: null
Jul 25 21:48:17 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Retrieving Cloud Streaming UI
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Getting Tidal Cloud Configuration
Jul 25 21:48:17 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Getting Qobuz Cloud Configuration
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Asking plugin for UI Config
Jul 25 21:48:17 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Getting Spotify Cloud Configuration
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Asking plugin for UI Config
Jul 25 21:48:17 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Saving Spotify Acccount
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Got Tidal Cloud Configuration
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Got it
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Got it
Jul 25 21:48:17 volumiopi4 volumio[874]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 25 21:48:17 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:17 volumiopi4 volumio[874]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 25 21:48:17 volumiopi4 volumio[874]: info: Discovery: Started advertising with name: VolumioPi4
Jul 25 21:48:18 volumiopi4 volumio[874]: info: Discovery: adding undefined
Jul 25 21:48:18 volumiopi4 volumio[874]: info: Discovery: Found device VolumioPi4
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:18 volumiopi4 volumio[874]: info: Discovery: this is already registered, undefined
Jul 25 21:48:18 volumiopi4 volumio[874]: info: Discovery: Found device VolumioPi4
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:18 volumiopi4 volumio[874]: verbose: New Socket.io Connection to 192.168.1.186:3000 from 192.168.1.254 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jul 25 21:48:18 volumiopi4 volumio[874]: info: Received Get System Version
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 25 21:48:18 volumiopi4 volumio[874]: info: Received Get System Info
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 25 21:48:18 volumiopi4 volumio[874]: info: Discovery: Getting this device information
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 25 21:48:19 volumiopi4 sudo[2092]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreStateMachine::getcurrentVolume
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioRetrievevolume
Jul 25 21:48:19 volumiopi4 volumio[874]: info: VolumeController:: Volume=86 Mute =false
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreStateMachine::pushState
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioPushState
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreStateMachine::updateTrackBlock
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CorePlayQueue::getTrackBlock
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioRetrievevolume
Jul 25 21:48:19 volumiopi4 volumio[874]: info: VolumeController:: Volume=86 Mute =false
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreStateMachine::pushState
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:19 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioPushState
Jul 25 21:48:20 volumiopi4 volumio[874]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object]
Jul 25 21:48:20 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings
Jul 25 21:48:20 volumiopi4 volumio[874]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined
Jul 25 21:48:22 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction
Jul 25 21:48:22 volumiopi4 volumio[874]: info: CoreCommandRouter::Reload Ui
Jul 25 21:48:22 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard
Jul 25 21:48:22 volumiopi4 volumio[874]: info: Wizard terminated Successfully
Jul 25 21:48:22 volumiopi4 volumio[874]: info: CoreCommandRouter::volumiosetStartupVolume
Jul 25 21:48:22 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 25 21:48:22 volumiopi4 sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf
Jul 25 21:48:22 volumiopi4 sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:22 volumiopi4 sudo[2182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL
Jul 25 21:48:22 volumiopi4 sudo[2182]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:22 volumiopi4 sudo[2182]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:22 volumiopi4 volumio[874]: info: Internal perms successfully set
Jul 25 21:48:22 volumiopi4 sudo[2180]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:22 volumiopi4 volumio[874]: info: smb.conf Permissions set
Jul 25 21:48:22 volumiopi4 sudo[2186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service
Jul 25 21:48:22 volumiopi4 sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:22 volumiopi4 nmbd[734]: [2024/07/25 21:48:22.596402, 0] ../source3/nmbd/nmbd.c:58(terminate)
Jul 25 21:48:22 volumiopi4 nmbd[734]: Got SIGTERM: going down...
Jul 25 21:48:22 volumiopi4 systemd[1]: Stopping Samba NMB Daemon...
Jul 25 21:48:22 volumiopi4 systemd[1]: nmbd.service: Succeeded.
Jul 25 21:48:22 volumiopi4 sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 25 21:48:22 volumiopi4 sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:22 volumiopi4 systemd[1]: Stopped Samba NMB Daemon.
Jul 25 21:48:22 volumiopi4 sudo[2189]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:22 volumiopi4 sudo[2193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 25 21:48:22 volumiopi4 sudo[2193]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:22 volumiopi4 sudo[2193]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:22 volumiopi4 systemd[1]: Starting Samba NMB Daemon...
Jul 25 21:48:22 volumiopi4 nmbd[2195]: [2024/07/25 21:48:22.756065, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
Jul 25 21:48:22 volumiopi4 nmbd[2195]: started asyncdns process 2198
Jul 25 21:48:22 volumiopi4 nmbd[2195]: [2024/07/25 21:48:22.760411, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jul 25 21:48:22 volumiopi4 systemd[1]: Started Samba NMB Daemon.
Jul 25 21:48:22 volumiopi4 nmbd[2195]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Jul 25 21:48:22 volumiopi4 sudo[2186]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:22 volumiopi4 nmbd[2195]: [2024/07/25 21:48:22.770679, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jul 25 21:48:22 volumiopi4 nmbd[2195]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.186 for name WORKGROUP<1d>.
Jul 25 21:48:22 volumiopi4 nmbd[2195]: This response was from IP 192.168.1.92, reporting an IP address of 192.168.1.92.
Jul 25 21:48:22 volumiopi4 sudo[2197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jul 25 21:48:22 volumiopi4 sudo[2197]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:22 volumiopi4 sudo[2200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service
Jul 25 21:48:22 volumiopi4 sudo[2200]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:22 volumiopi4 systemd[1]: Started UPnP Renderer front-end to MPD.
Jul 25 21:48:22 volumiopi4 systemd[1]: Stopping Samba SMB Daemon...
Jul 25 21:48:22 volumiopi4 systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM
Jul 25 21:48:22 volumiopi4 sudo[2197]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:22 volumiopi4 systemd[1]: smbd.service: Succeeded.
Jul 25 21:48:22 volumiopi4 systemd[1]: Stopped Samba SMB Daemon.
Jul 25 21:48:22 volumiopi4 volumio[874]: info: Upmpdcli Daemon Started
Jul 25 21:48:22 volumiopi4 systemd[1]: Starting Samba SMB Daemon...
Jul 25 21:48:22 volumiopi4 volumio[2203]: writing RSA key
Jul 25 21:48:22 volumiopi4 sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 25 21:48:22 volumiopi4 sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:22 volumiopi4 sudo[2227]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:23 volumiopi4 sudo[2229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 25 21:48:23 volumiopi4 sudo[2229]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:23 volumiopi4 sudo[2229]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:23 volumiopi4 volumio[874]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.193 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Jul 25 21:48:23 volumiopi4 winbindd[900]: [2024/07/25 21:48:23.084947, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name)
Jul 25 21:48:23 volumiopi4 winbindd[900]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2805067378-3980124768-840177100-501
Jul 25 21:48:23 volumiopi4 winbindd[900]: [2024/07/25 21:48:23.085598, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name)
Jul 25 21:48:23 volumiopi4 winbindd[900]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2805067378-3980124768-840177100-514
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 25 21:48:23 volumiopi4 smbd[2205]: [2024/07/25 21:48:23.110707, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jul 25 21:48:23 volumiopi4 smbd[2205]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Jul 25 21:48:23 volumiopi4 systemd[1]: Started Samba SMB Daemon.
Jul 25 21:48:23 volumiopi4 sudo[2200]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:23 volumiopi4 volumio[874]: info: SAMBA Restarted
Jul 25 21:48:23 volumiopi4 sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 25 21:48:23 volumiopi4 sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:23 volumiopi4 sudo[2249]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 25 21:48:23 volumiopi4 sudo[2252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 25 21:48:23 volumiopi4 sudo[2252]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 25 21:48:23 volumiopi4 sudo[2252]: pam_unix(sudo:session): session closed for user root
Jul 25 21:48:23 volumiopi4 volumio[874]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.193 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 25 21:48:23 volumiopi4 volumio[874]: info: Received Get System Info
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 25 21:48:23 volumiopi4 volumio[874]: info: Discovery: Getting this device information
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:23 volumiopi4 volumio[874]: info: Listing playlists
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 25 21:48:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 25 21:48:24 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:24 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:24 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 25 21:48:25 volumiopi4 volumio[874]: info: Received Get System Info
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 25 21:48:25 volumiopi4 volumio[874]: info: Discovery: Getting this device information
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 25 21:48:25 volumiopi4 volumio[874]: info: Received Get System Info
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 25 21:48:25 volumiopi4 volumio[874]: info: Discovery: Getting this device information
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:25 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 25 21:48:28 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse
Jul 25 21:48:32 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule
Jul 25 21:48:32 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 25 21:48:32 volumiopi4 volumio-remote-updater[626]: No test mode
Jul 25 21:48:32 volumiopi4 volumio-remote-updater[626]: No alpha test mode
Jul 25 21:48:32 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jul 25 21:48:32 volumiopi4 volumio[874]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Jul 25 21:48:32 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 25 21:48:33 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jul 25 21:48:34 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule
Jul 25 21:48:34 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 25 21:48:34 volumiopi4 systemd[1]: systemd-timedated.service: Succeeded.
Jul 25 21:48:34 volumiopi4 volumio-remote-updater[626]: No test mode
Jul 25 21:48:34 volumiopi4 volumio-remote-updater[626]: No alpha test mode
Jul 25 21:48:34 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jul 25 21:48:34 volumiopi4 volumio[874]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Jul 25 21:48:34 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 25 21:48:35 volumiopi4 volumio[874]: info: CoreCommandRouter::Close All Modals sent
Jul 25 21:48:35 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted
Jul 25 21:48:38 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:38 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 25 21:48:38 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 25 21:48:38 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 25 21:48:38 volumiopi4 volumio[874]: info: Received Get System Version
Jul 25 21:48:38 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jul 25 21:48:41 volumiopi4 volumio[874]: info: Received Get System Version
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 25 21:48:41 volumiopi4 volumio[874]: info: Received Get System Info
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 25 21:48:41 volumiopi4 volumio[874]: info: Discovery: Getting this device information
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:41 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 25 21:48:54 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetState
Jul 25 21:48:54 volumiopi4 volumio[874]: info: CorePlayQueue::getTrack 0
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Retrieving Cloud Streaming UI
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Getting Tidal Cloud Configuration
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Getting Qobuz Cloud Configuration
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Asking plugin for UI Config
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Getting Spotify Cloud Configuration
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Asking plugin for UI Config
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Saving Spotify Acccount
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Got Tidal Cloud Configuration
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Got it
Jul 25 21:48:59 volumiopi4 volumio[874]: info: Got it
Jul 25 21:48:59 volumiopi4 volumio[874]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 25 21:48:59 volumiopi4 volumio[874]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:48:59 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jul 25 21:49:03 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:07 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:11 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:15 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:18 volumiopi4 volumio[874]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object]
Jul 25 21:49:18 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions
Jul 25 21:49:19 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:23 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:27 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:31 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:31 volumiopi4 volumio[874]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object]
Jul 25 21:49:31 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility
Jul 25 21:49:31 volumiopi4 volumio[874]: info: CoreCommandRouter::volumioUpdateToBrowseSources
Jul 25 21:49:31 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 25 21:49:35 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:38 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Jul 25 21:49:38 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Jul 25 21:49:39 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:40 volumiopi4 volumio[874]: info: Error browsing device SharedDrive2 for shares : Error: Command failed: echo volumio | smbclient -L SharedDrive2.local.
Jul 25 21:49:40 volumiopi4 volumio[874]: Unable to initialize messaging context
Jul 25 21:49:43 volumiopi4 volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 25 21:49:43 volumiopi4 volumio[874]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 25 21:49:43 volumiopi4 volumio[874]: Error: dns service error: unknown
Jul 25 21:49:43 volumiopi4 volumio[874]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30)
Jul 25 21:49:43 volumiopi4 volumio[874]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) {
Jul 25 21:49:43 volumiopi4 volumio[874]: errorCode: -65537
Jul 25 21:49:43 volumiopi4 volumio[874]: }
Jul 25 21:49:43 volumiopi4 volumio[874]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 25 21:49:44 volumiopi4 sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-25 21:48
Jul 25 21:49:44 volumiopi4 sudo[2339]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"