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