-- Logs begin at Thu 2019-02-14 17:12:00 +07, end at Fri 2024-10-18 19:18:55 +07. -- Oct 18 19:17:01 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 18 19:17:01 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 19:17:01 volumio volumio-remote-updater[531]: No test mode Oct 18 19:17:01 volumio volumio-remote-updater[531]: No alpha test mode Oct 18 19:17:01 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 18 19:17:02 volumio volumio[815]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 19:17:02 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 19:17:05 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 18 19:17:05 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 19:17:05 volumio volumio-remote-updater[531]: No test mode Oct 18 19:17:05 volumio volumio-remote-updater[531]: No alpha test mode Oct 18 19:17:05 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 18 19:17:05 volumio volumio[815]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 19:17:05 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 19:17:08 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 18 19:17:08 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 19:17:08 volumio systemd[1]: systemd-timedated.service: Succeeded. Oct 18 19:17:08 volumio volumio-remote-updater[531]: No test mode Oct 18 19:17:08 volumio volumio-remote-updater[531]: No alpha test mode Oct 18 19:17:08 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 18 19:17:09 volumio volumio[815]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 19:17:09 volumio sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 19:17:09 volumio sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:09 volumio sudo[1591]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:09 volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 19:17:09 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:09 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:09 volumio volumio[815]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.47 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 18 19:17:09 volumio volumio[815]: info: Received Get System Info Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:17:09 volumio volumio[815]: info: Discovery: Getting this device information Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:17:09 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:17:09 volumio volumio-remote-updater[531]: No test mode Oct 18 19:17:09 volumio volumio-remote-updater[531]: No alpha test mode Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:17:09 volumio volumio[815]: info: Retrieving Cloud Streaming UI Oct 18 19:17:09 volumio volumio[815]: info: Getting Tidal Cloud Configuration Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:17:09 volumio volumio[815]: info: Getting Qobuz Cloud Configuration Oct 18 19:17:09 volumio volumio[815]: info: Asking plugin for UI Config Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:17:09 volumio volumio[815]: info: Getting Spotify Cloud Configuration Oct 18 19:17:09 volumio volumio[815]: info: Asking plugin for UI Config Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:17:09 volumio volumio[815]: info: Saving Spotify Acccount Oct 18 19:17:09 volumio volumio[815]: info: Got Tidal Cloud Configuration Oct 18 19:17:09 volumio volumio[815]: info: Got it Oct 18 19:17:09 volumio volumio[815]: info: Got it Oct 18 19:17:09 volumio volumio[815]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:17:09 volumio volumio[815]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 18 19:17:09 volumio volumio[815]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 19:17:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 19:17:10 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 18 19:17:10 volumio volumio[815]: info: Loading i18n strings for locale th Oct 18 19:17:10 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 18 19:17:10 volumio volumio[815]: info: Setting timezone to Asia/Bangkok Oct 18 19:17:10 volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 18 19:17:10 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:10 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:10 volumio sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Oct 18 19:17:10 volumio sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:10 volumio sudo[1604]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:10 volumio sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 18 19:17:10 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:10 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:10 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Oct 18 19:17:10 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:10 volumio dbus-daemon[530]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.31' (uid=0 pid=1611 comm="/usr/bin/timedatectl set-timezone Asia/Bangkok ") Oct 18 19:17:10 volumio systemd[1]: Starting Time & Date Service... Oct 18 19:17:10 volumio dbus-daemon[530]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 18 19:17:10 volumio systemd[1]: Started Time & Date Service. Oct 18 19:17:10 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:11 volumio volumio[815]: Updating browse sources language Oct 18 19:17:11 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:17:11 volumio volumio[815]: info: Fetching Streaming Services browse cache Oct 18 19:17:11 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 18 19:17:11 volumio volumio[815]: info: MyVolumio login type: Token Oct 18 19:17:12 volumio volumio[815]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 18 19:17:13 volumio volumio[815]: info: MyVolumio token set successfully Oct 18 19:17:13 volumio volumio[815]: info: MYVOLUMIO: Adding device Oct 18 19:17:13 volumio volumio[815]: info: MYVOLUMIO: Evaluating Server Oct 18 19:17:13 volumio volumio[815]: info: MyVolumio status changed Oct 18 19:17:13 volumio volumio[815]: info: Streaming services startup Oct 18 19:17:13 volumio volumio[815]: info: Starting Streaming Daemon Oct 18 19:17:13 volumio volumio[815]: info: Removing browser output: myVolumio user plan is not superstar Oct 18 19:17:13 volumio volumio[815]: info: Removing audio output: Oct 18 19:17:13 volumio volumio[815]: info: Stoppping Tunnel 1 Oct 18 19:17:13 volumio volumio[815]: info: Setting Geolocation for MyVolumio to as1 Oct 18 19:17:13 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:13 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:13 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:14 volumio sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 18 19:17:14 volumio sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 18 19:17:14 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:14 volumio sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:14 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:14 volumio volumio[815]: error: Cannot start Volumio Streaming Daemon Oct 18 19:17:14 volumio volumio[815]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 18 19:17:14 volumio volumio[815]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 18 19:17:14 volumio sudo[1634]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:14 volumio volumio[815]: info: Remote SSH Stopped Oct 18 19:17:14 volumio volumio[815]: info: Successfully Added MyVolumio device Oct 18 19:17:14 volumio volumio[815]: info: Updating MyVolumio device info Oct 18 19:17:14 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:14 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:14 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:15 volumio volumio[815]: info: Successfully Updated MyVolumio device Oct 18 19:17:18 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Oct 18 19:17:18 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Oct 18 19:17:18 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 18 19:17:18 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:18 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 18 19:17:19 volumio volumio[815]: info: Preparing to save Alsa Options, stopping services first Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:17:19 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::volumioPause Oct 18 19:17:19 volumio volumio[815]: info: CoreStateMachine::pause Oct 18 19:17:19 volumio volumio[815]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI Out"},"disallowPush":true} Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:19 volumio volumio[815]: info: Enabling PI HDMI Output Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:19 volumio volumio[815]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 18 19:17:19 volumio volumio[815]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 18 19:17:19 volumio volumio[815]: info: Disabling external Volume Control Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 18 19:17:19 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 18 19:17:19 volumio sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 18 19:17:19 volumio sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:19 volumio volumio[815]: info: Preparing to generate the ALSA configuration file Oct 18 19:17:19 volumio sudo[1652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 18 19:17:19 volumio sudo[1652]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:19 volumio sudo[1652]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:20 volumio volumio[815]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 18 19:17:20 volumio volumio[815]: wlan0 Interface doesn't support scanning : Device or resource busy Oct 18 19:17:20 volumio volumio[815]: info: Cannot use regular scanning, forcing with ap-force Oct 18 19:17:20 volumio sudo[1655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 18 19:17:20 volumio sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:20 volumio sudo[1655]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:20 volumio volumio[815]: command failed: Device or resource busy (-16) Oct 18 19:17:20 volumio volumio[815]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 18 19:17:20 volumio volumio[815]: command failed: Device or resource busy (-16) Oct 18 19:17:20 volumio volumio[815]: info: Raspberry PI HDMI Output Enabled Oct 18 19:17:20 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store Oct 18 19:17:20 volumio volumio[815]: info: VolumeController:: Volume=86 Mute =false Oct 18 19:17:20 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:20 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:17:20 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:17:20 volumio volumio[815]: info: Asound.conf file unchanged, so no further update is needed Oct 18 19:17:20 volumio volumio[815]: info: Output device has changed, restarting MPD Oct 18 19:17:20 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:20 volumio sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 19:17:20 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:20 volumio volumio[815]: info: Output device has changed, restarting Shairport Sync Oct 18 19:17:20 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:20 volumio sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 19:17:20 volumio sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:20 volumio systemd[1]: Stopping Music Player Daemon... Oct 18 19:17:20 volumio volumio[815]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:20 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 18 19:17:20 volumio sudo[1649]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:22 volumio systemd[1]: mpd.service: Succeeded. Oct 18 19:17:22 volumio systemd[1]: Stopped Music Player Daemon. Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 18 19:17:22 volumio systemd[1]: Starting Music Player Daemon... Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:22 volumio volumio[815]: info: MPD Permissions set Oct 18 19:17:22 volumio volumio[815]: info: Alsa Settings successfully stored Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:22 volumio sudo[1676]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 19:17:22 volumio sudo[1676]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:22 volumio volumio[815]: info: Starting Shairport Sync Oct 18 19:17:22 volumio sudo[1676]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:22 volumio sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 19:17:22 volumio sudo[1686]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 18 19:17:22 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 18 19:17:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 18 19:17:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 18 19:17:23 volumio sudo[1686]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:23 volumio volumio[815]: info: Shairport-Sync Started Oct 18 19:17:23 volumio volumio[815]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 18 19:17:23 volumio volumio[815]: info: CoreStateMachine::getcurrentVolume Oct 18 19:17:23 volumio volumio[815]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 19:17:23 volumio volumio[815]: info: VolumeController:: Volume=86 Mute =false Oct 18 19:17:23 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:17:23 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:23 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:17:23 volumio volumio[815]: info: CoreStateMachine::updateTrackBlock Oct 18 19:17:23 volumio volumio[815]: info: CorePlayQueue::getTrackBlock Oct 18 19:17:23 volumio volumio[815]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 19:17:24 volumio volumio[815]: info: VolumeController:: Volume=86 Mute =false Oct 18 19:17:24 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:17:24 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:24 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:17:24 volumio mpd[1685]: Oct 18 19:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 18 19:17:24 volumio systemd[1]: Started Music Player Daemon. Oct 18 19:17:24 volumio sudo[1664]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:24 volumio volumio[815]: error: updateQueue error: null Oct 18 19:17:33 volumio kernel: usb 1-1.2: new high-speed USB device number 9 using dwc_otg Oct 18 19:17:33 volumio kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=3008, bcdDevice= 5.2d Oct 18 19:17:33 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Oct 18 19:17:33 volumio kernel: usb 1-1.2: Product: iFi (by AMR) HD USB Audio Oct 18 19:17:33 volumio kernel: usb 1-1.2: Manufacturer: iFi (by AMR) Oct 18 19:17:33 volumio kernel: usb 1-1.2: 1:2 : unsupported format bits 0x100000000 Oct 18 19:17:37 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 18 19:17:37 volumio volumio[815]: info: Preparing to save Alsa Options, stopping services first Oct 18 19:17:37 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:17:37 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:37 volumio volumio[815]: info: CoreCommandRouter::volumioPause Oct 18 19:17:37 volumio volumio[815]: info: CoreStateMachine::pause Oct 18 19:17:37 volumio volumio[815]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI Out"},"disallowPush":true} Oct 18 19:17:37 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:37 volumio volumio[815]: info: Enabling PI HDMI Output Oct 18 19:17:37 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:37 volumio volumio[815]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 18 19:17:38 volumio volumio[815]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 18 19:17:38 volumio volumio[815]: info: Disabling external Volume Control Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:17:38 volumio volumio[815]: info: Preparing to generate the ALSA configuration file Oct 18 19:17:38 volumio volumio[815]: info: Raspberry PI HDMI Output Enabled Oct 18 19:17:38 volumio sudo[1753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store Oct 18 19:17:38 volumio sudo[1753]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:38 volumio sudo[1753]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 18 19:17:38 volumio volumio[815]: info: Asound.conf file unchanged, so no further update is needed Oct 18 19:17:38 volumio volumio[815]: info: Output device has changed, restarting MPD Oct 18 19:17:38 volumio volumio[815]: info: Output device has changed, restarting Shairport Sync Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:38 volumio sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 19:17:38 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:38 volumio sudo[1762]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:38 volumio sudo[1764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 19:17:38 volumio sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:38 volumio systemd[1]: Stopping Music Player Daemon... Oct 18 19:17:38 volumio volumio[815]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 19:17:38 volumio volumio[815]: info: MPD Permissions set Oct 18 19:17:38 volumio volumio[815]: info: Alsa Settings successfully stored Oct 18 19:17:38 volumio volumio[815]: info: VolumeController:: Volume=86 Mute =false Oct 18 19:17:38 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:17:38 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:17:38 volumio systemd[1]: mpd.service: Succeeded. Oct 18 19:17:38 volumio systemd[1]: Stopped Music Player Daemon. Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:38 volumio volumio[815]: info: Starting Shairport Sync Oct 18 19:17:38 volumio systemd[1]: Starting Music Player Daemon... Oct 18 19:17:38 volumio sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 19:17:38 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 18 19:17:38 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 18 19:17:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 18 19:17:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 18 19:17:38 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:38 volumio volumio[815]: info: Shairport-Sync Started Oct 18 19:17:38 volumio sudo[1770]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 19:17:38 volumio sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:38 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 18 19:17:38 volumio sudo[1770]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:38 volumio systemd-udevd[1737]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Oct 18 19:17:38 volumio volumio[815]: info: Oct 18 19:17:38 volumio volumio[815]: ---------------------------- USB Audio Device Attached Oct 18 19:17:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 18 19:17:39 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Oct 18 19:17:39 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Oct 18 19:17:39 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 18 19:17:39 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:39 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:40 volumio mpd[1784]: Oct 18 19:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 18 19:17:40 volumio systemd[1]: Started Music Player Daemon. Oct 18 19:17:40 volumio sudo[1764]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:40 volumio volumio[815]: error: updateQueue error: null Oct 18 19:17:41 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 18 19:17:41 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 19:17:41 volumio systemd[1]: systemd-timedated.service: Succeeded. Oct 18 19:17:41 volumio volumio-remote-updater[531]: No test mode Oct 18 19:17:41 volumio volumio-remote-updater[531]: No alpha test mode Oct 18 19:17:41 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 18 19:17:41 volumio volumio[815]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 19:17:41 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 18 19:17:42 volumio volumio[815]: info: CoreStateMachine::getcurrentVolume Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 19:17:42 volumio volumio[815]: info: VolumeController:: Volume=86 Mute =false Oct 18 19:17:42 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:17:42 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:17:42 volumio volumio[815]: info: CoreStateMachine::updateTrackBlock Oct 18 19:17:42 volumio volumio[815]: info: CorePlayQueue::getTrackBlock Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 19:17:42 volumio volumio[815]: info: VolumeController:: Volume=86 Mute =false Oct 18 19:17:42 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:17:42 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 18 19:17:42 volumio volumio[815]: info: Preparing to save Alsa Options, stopping services first Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:17:42 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::volumioPause Oct 18 19:17:42 volumio volumio[815]: info: CoreStateMachine::pause Oct 18 19:17:42 volumio volumio[815]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"iFi (by AMR) HD USB Audio"},"disallowPush":true} Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:42 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:43 volumio volumio[815]: info: Device 5 does not have any Mixer Control Available Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 18 19:17:43 volumio volumio[815]: info: Updating Volume Controller Parameters: Device: 5 Name: iFi (by AMR) HD USB Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 18 19:17:43 volumio volumio[815]: info: Disabling external Volume Control Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:17:43 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:17:43 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:17:43 volumio volumio[815]: info: Preparing to generate the ALSA configuration file Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 18 19:17:43 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 18 19:17:43 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:43 volumio sudo[1809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 18 19:17:43 volumio sudo[1809]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:43 volumio sudo[1809]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 18 19:17:43 volumio volumio[815]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 18 19:17:43 volumio volumio[815]: wlan0 Interface doesn't support scanning : Device or resource busy Oct 18 19:17:43 volumio volumio[815]: info: Cannot use regular scanning, forcing with ap-force Oct 18 19:17:43 volumio sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 18 19:17:43 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:43 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:43 volumio volumio[815]: command failed: Device or resource busy (-16) Oct 18 19:17:43 volumio volumio[815]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 18 19:17:43 volumio volumio[815]: command failed: Device or resource busy (-16) Oct 18 19:17:43 volumio volumio[815]: info: Asound.conf file written Oct 18 19:17:43 volumio sudo[1820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 18 19:17:43 volumio sudo[1820]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:43 volumio sudo[1820]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:43 volumio volumio[815]: No state is present for card Audio Oct 18 19:17:43 volumio volumio[815]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3008" "" "" Oct 18 19:17:43 volumio volumio[815]: Hardware is initialized using a generic method Oct 18 19:17:43 volumio volumio[815]: No state is present for card Audio Oct 18 19:17:43 volumio volumio[815]: info: Output device has changed, restarting MPD Oct 18 19:17:43 volumio volumio[815]: info: Output device has changed, restarting Shairport Sync Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:43 volumio sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 19:17:43 volumio sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:43 volumio sudo[1825]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:43 volumio sudo[1827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 19:17:43 volumio sudo[1827]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:43 volumio systemd[1]: Stopping Music Player Daemon... Oct 18 19:17:43 volumio volumio[815]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 19:17:43 volumio volumio[815]: info: Output device has changed, restarting MPD Oct 18 19:17:43 volumio systemd[1]: mpd.service: Succeeded. Oct 18 19:17:43 volumio systemd[1]: Stopped Music Player Daemon. Oct 18 19:17:43 volumio volumio[815]: info: Output device has changed, restarting Shairport Sync Oct 18 19:17:43 volumio sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:43 volumio systemd[1]: Starting Music Player Daemon... Oct 18 19:17:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:43 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:43 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:43 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:43 volumio sudo[1837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 19:17:43 volumio sudo[1837]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:44 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 18 19:17:44 volumio systemd[1]: mpd.service: Succeeded. Oct 18 19:17:44 volumio systemd[1]: Stopped Music Player Daemon. Oct 18 19:17:44 volumio systemd[1]: Starting Music Player Daemon... Oct 18 19:17:44 volumio volumio[815]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 19:17:44 volumio volumio[815]: info: MPD Permissions set Oct 18 19:17:44 volumio volumio[815]: info: MPD Permissions set Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:17:44 volumio volumio[815]: info: Starting Shairport Sync Oct 18 19:17:44 volumio sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 19:17:44 volumio sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:44 volumio sudo[1843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 19:17:44 volumio sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:44 volumio sudo[1843]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:44 volumio sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 19:17:44 volumio sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:44 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 18 19:17:44 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 18 19:17:44 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 18 19:17:44 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 18 19:17:44 volumio sudo[1855]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:44 volumio sudo[1853]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:44 volumio volumio[815]: info: Shairport-Sync Started Oct 18 19:17:44 volumio volumio[815]: info: Shairport-Sync Started Oct 18 19:17:46 volumio mpd[1859]: Oct 18 19:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 18 19:17:46 volumio systemd[1]: Started Music Player Daemon. Oct 18 19:17:46 volumio sudo[1837]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:46 volumio sudo[1827]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:46 volumio volumio[815]: error: MPD error: The expression evaluated to a falsy value: Oct 18 19:17:46 volumio volumio[815]: assert.ok(self.idling) Oct 18 19:17:46 volumio volumio[815]: error: The expression evaluated to a falsy value: Oct 18 19:17:46 volumio volumio[815]: assert.ok(self.idling) Oct 18 19:17:46 volumio volumio[815]: error: updateQueue error: null Oct 18 19:17:47 volumio volumio[815]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 18 19:17:47 volumio volumio[815]: info: CoreStateMachine::getcurrentVolume Oct 18 19:17:47 volumio volumio[815]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 19:17:47 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:17:47 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:17:47 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:17:52 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Oct 18 19:17:52 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Oct 18 19:17:52 volumio sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 19:17:52 volumio sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:52 volumio sudo[1867]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:52 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Oct 18 19:17:52 volumio volumio[815]: info: Saving new wireless network Oct 18 19:17:52 volumio sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Oct 18 19:17:52 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:53 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:53 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 18 19:17:53 volumio volumio[815]: info: Discovery: Restarting Advertising due to device name change Oct 18 19:17:53 volumio sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 18 19:17:53 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:53 volumio systemd[1]: Starting Wireless Services... Oct 18 19:17:54 volumio volumio[815]: info: Discovery: A device disappeared from network Oct 18 19:17:54 volumio volumio[815]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Oct 18 19:17:54 volumio volumio[815]: info: Discovery: A device disappeared from network Oct 18 19:17:54 volumio volumio[815]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Oct 18 19:17:54 volumio wireless.js[1875]: Cleaning previous... Oct 18 19:17:54 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 18 19:17:55 volumio systemd[1]: hostapd.service: Succeeded. Oct 18 19:17:55 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 18 19:17:55 volumio sudo[1889]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 18 19:17:55 volumio sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:55 volumio avahi-daemon[524]: Withdrawing address record for 192.168.211.1 on wlan0. Oct 18 19:17:55 volumio avahi-daemon[524]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 18 19:17:55 volumio avahi-daemon[524]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 18 19:17:55 volumio sudo[1889]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:55 volumio sudo[1891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 18 19:17:55 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:55 volumio sudo[1891]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:55 volumio wireless.js[1875]: Stopped aP Oct 18 19:17:55 volumio sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 18 19:17:55 volumio sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:55 volumio sudo[1898]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:55 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 18 19:17:55 volumio sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 18 19:17:55 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:55 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:55 volumio sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 18 19:17:55 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:56 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:56 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 18 19:17:56 volumio sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:56 volumio sudo[1909]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:56 volumio wireless.js[1875]: SETTING APPROPRIATE REG DOMAIN: US Oct 18 19:17:56 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 18 19:17:56 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:56 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:56 volumio sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Oct 18 19:17:56 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:56 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:56 volumio wireless.js[1875]: SUCCESSFULLY SET NEW REGDOMAIN: US Oct 18 19:17:56 volumio wireless.js[1875]: Start wireless flow Oct 18 19:17:56 volumio wireless.js[1875]: Stopped hotspot (if there).. Oct 18 19:17:56 volumio sudo[1921]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 18 19:17:56 volumio sudo[1921]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:56 volumio sudo[1921]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:56 volumio sudo[1923]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 18 19:17:56 volumio sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:57 volumio sudo[1923]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:57 volumio wireless.js[1875]: DHCP IP Oct 18 19:17:57 volumio wireless.js[1875]: Start ap Oct 18 19:17:57 volumio sudo[1926]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 18 19:17:57 volumio sudo[1926]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:57 volumio wpa_supplicant[1925]: Successfully initialized wpa_supplicant Oct 18 19:17:57 volumio dhcpcd[1927]: dev: loaded udev Oct 18 19:17:57 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 18 19:17:57 volumio dhcpcd-run-hooks[1934]: wlan0: starting wpa_supplicant Oct 18 19:17:57 volumio dhcpcd-run-hooks[1939]: wlan0: failed to start wpa_supplicant Oct 18 19:17:57 volumio dhcpcd-run-hooks[1940]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Oct 18 19:17:57 volumio dhcpcd[1927]: wlan0: connected to Access Point `' Oct 18 19:17:57 volumio dhcpcd[1927]: wlan0: waiting for carrier Oct 18 19:17:57 volumio dhcpcd[1927]: DUID 00:01:00:01:2e:7c:57:b9:9c:eb:e8:1b:82:45 Oct 18 19:17:57 volumio dhcpcd[1927]: eth0: IAID e8:1b:82:45 Oct 18 19:17:57 volumio dhcpcd[1927]: eth0: adding address fe80::b026:3bf5:c373:94ca Oct 18 19:17:57 volumio dhcpcd[1927]: ipv6_addaddr1: Permission denied Oct 18 19:17:58 volumio wpa_supplicant[1938]: wlan0: Trying to associate with SSID 'Vas_2.4G' Oct 18 19:17:58 volumio dhcpcd[1927]: eth0: soliciting a DHCP lease Oct 18 19:17:58 volumio wireless.js[1875]: trying... Oct 18 19:17:58 volumio dhcpcd[1927]: eth0: offered 192.168.1.79 from 192.168.1.1 Oct 18 19:17:58 volumio sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:17:58 volumio sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:58 volumio sudo[1960]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:58 volumio dhcpcd[1927]: eth0: leased 192.168.1.79 for 86400 seconds Oct 18 19:17:58 volumio dhcpcd[1927]: eth0: adding route to 192.168.1.0/24 Oct 18 19:17:58 volumio dhcpcd[1927]: eth0: adding default route via 192.168.1.1 Oct 18 19:17:58 volumio volumio[815]: info: Discovery: Started advertising with name: Volumio Oct 18 19:17:58 volumio dhcpcd[1927]: forked to background, child pid 1983 Oct 18 19:17:58 volumio sudo[1926]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:58 volumio dhcpcd[1983]: eth0: soliciting an IPv6 router Oct 18 19:17:58 volumio dhcpcd[1983]: wlan0: carrier acquired Oct 18 19:17:58 volumio dhcpcd[1983]: wlan0: connected to Access Point `Vas_2.4G' Oct 18 19:17:58 volumio wpa_supplicant[1938]: wlan0: Associated with c0:bc:9a:6d:56:c8 Oct 18 19:17:58 volumio wpa_supplicant[1938]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:bc:9a:6d:56:c8 completed [id=0 id_str=] Oct 18 19:17:58 volumio wpa_supplicant[1938]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 18 19:17:58 volumio dhcpcd[1983]: wlan0: IAID 67:8e:f4:05 Oct 18 19:17:58 volumio dhcpcd[1983]: wlan0: adding address fe80::37ac:ebe8:f4d4:4463 Oct 18 19:17:58 volumio dhcpcd[1983]: ipv6_addaddr1: Permission denied Oct 18 19:17:58 volumio dhcpcd[1983]: wlan0: carrier lost Oct 18 19:17:58 volumio dhcpcd[1983]: wlan0: carrier acquired Oct 18 19:17:58 volumio dhcpcd[1983]: wlan0: IAID 67:8e:f4:05 Oct 18 19:17:58 volumio dhcpcd[1983]: wlan0: soliciting a DHCP lease Oct 18 19:17:59 volumio ntpd[710]: Soliciting pool server 2406:3100:1010:c03::149 Oct 18 19:17:59 volumio wireless.js[1875]: trying... Oct 18 19:17:59 volumio sudo[2004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:17:59 volumio sudo[2004]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:17:59 volumio sudo[2004]: pam_unix(sudo:session): session closed for user root Oct 18 19:17:59 volumio dhcpcd[1983]: wlan0: soliciting an IPv6 router Oct 18 19:17:59 volumio volumio[815]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 18 19:18:00 volumio ntpd[710]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=261 secs Oct 18 19:18:00 volumio wireless.js[1875]: trying... Oct 18 19:18:00 volumio sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:18:00 volumio sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:00 volumio sudo[2008]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:01 volumio dhcpcd[1983]: wlan0: offered 192.168.1.40 from 192.168.1.1 Oct 18 19:18:01 volumio dhcpcd[1983]: wlan0: probing address 192.168.1.40/24 Oct 18 19:18:01 volumio wireless.js[1875]: trying... Oct 18 19:18:01 volumio sudo[2011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:18:01 volumio sudo[2011]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:01 volumio sudo[2011]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:02 volumio wireless.js[1875]: trying... Oct 18 19:18:02 volumio sudo[2014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:18:02 volumio sudo[2014]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:02 volumio sudo[2014]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:03 volumio wireless.js[1875]: trying... Oct 18 19:18:03 volumio sudo[2017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:18:03 volumio sudo[2017]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:03 volumio sudo[2017]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:04 volumio wireless.js[1875]: trying... Oct 18 19:18:04 volumio sudo[2020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:18:04 volumio sudo[2020]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:04 volumio sudo[2020]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:05 volumio wireless.js[1875]: trying... Oct 18 19:18:05 volumio sudo[2023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:18:05 volumio sudo[2023]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:05 volumio sudo[2023]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:05 volumio dhcpcd[1983]: wlan0: leased 192.168.1.40 for 86400 seconds Oct 18 19:18:05 volumio avahi-daemon[524]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.40. Oct 18 19:18:05 volumio avahi-daemon[524]: New relevant interface wlan0.IPv4 for mDNS. Oct 18 19:18:05 volumio dhcpcd[1983]: wlan0: adding route to 192.168.1.0/24 Oct 18 19:18:05 volumio avahi-daemon[524]: Registering new address record for 192.168.1.40 on wlan0.IPv4. Oct 18 19:18:05 volumio dhcpcd[1983]: wlan0: adding default route via 192.168.1.1 Oct 18 19:18:05 volumio dnsmasq[806]: reading /etc/resolv.conf Oct 18 19:18:05 volumio dnsmasq[806]: using nameserver 192.168.1.1#53 Oct 18 19:18:05 volumio dnsmasq[806]: using nameserver 208.67.222.222#53 Oct 18 19:18:05 volumio dnsmasq[806]: using nameserver 208.67.220.220#53 Oct 18 19:18:06 volumio wireless.js[1875]: trying... Oct 18 19:18:06 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 18 19:18:06 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:06 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:06 volumio wireless.js[1875]: Connected to: ----Vas_2.4G Oct 18 19:18:06 volumio wireless.js[1875]: ---- Oct 18 19:18:06 volumio sudo[2050]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 19:18:06 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:06 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:06 volumio wireless.js[1875]: ... joined AP, wlan0 IPv4 is 192.168.1.40, ipV6 is undefined Oct 18 19:18:06 volumio wireless.js[1875]: It's done! AP Oct 18 19:18:06 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 18 19:18:06 volumio systemd[1]: Started Wireless Services. Oct 18 19:18:06 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:06 volumio volumio[815]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 18 19:18:07 volumio ntpd[710]: Listen normally on 5 wlan0 192.168.1.40:123 Oct 18 19:18:07 volumio ntpd[710]: new interface(s) found: waking up resolver Oct 18 19:18:11 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Oct 18 19:18:11 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Oct 18 19:18:11 volumio sudo[2056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 19:18:11 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:11 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:11 volumio sudo[2059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 18 19:18:11 volumio sudo[2059]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:11 volumio sudo[2059]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:11 volumio nmbd[649]: [2024/10/18 19:18:11.851402, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 18 19:18:11 volumio nmbd[649]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Oct 18 19:18:16 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Oct 18 19:18:16 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Oct 18 19:18:16 volumio volumio[815]: info: Refreshing Cached IP Addresses Oct 18 19:18:16 volumio sudo[2065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 18 19:18:16 volumio sudo[2065]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:16 volumio sudo[2067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 19:18:16 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:16 volumio sudo[2065]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:16 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:16 volumio volumio[815]: error: Upnp client error: Error: This socket has been ended by the other party Oct 18 19:18:16 volumio sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 19:18:16 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:16 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:18 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:18 volumio volumio[815]: info: Retrieving Cloud Streaming UI Oct 18 19:18:18 volumio volumio[815]: info: Getting Tidal Cloud Configuration Oct 18 19:18:18 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:18 volumio volumio[815]: info: Getting Qobuz Cloud Configuration Oct 18 19:18:18 volumio volumio[815]: info: Asking plugin for UI Config Oct 18 19:18:18 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:18 volumio volumio[815]: info: Getting Spotify Cloud Configuration Oct 18 19:18:18 volumio volumio[815]: info: Asking plugin for UI Config Oct 18 19:18:18 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:18 volumio volumio[815]: info: Saving Spotify Acccount Oct 18 19:18:18 volumio volumio[815]: info: Got Tidal Cloud Configuration Oct 18 19:18:18 volumio volumio[815]: info: Got it Oct 18 19:18:18 volumio volumio[815]: info: Got it Oct 18 19:18:18 volumio volumio[815]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 18 19:18:18 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:18 volumio volumio[815]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 18 19:18:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Oct 18 19:18:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Oct 18 19:18:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Oct 18 19:18:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Oct 18 19:18:22 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Oct 18 19:18:24 volumio volumio[815]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Oct 18 19:18:24 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Oct 18 19:18:24 volumio volumio[815]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Oct 18 19:18:25 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 18 19:18:25 volumio volumio[815]: info: CoreCommandRouter::Reload Ui Oct 18 19:18:25 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Oct 18 19:18:25 volumio volumio[815]: info: Wizard terminated Successfully Oct 18 19:18:25 volumio volumio[815]: info: CoreCommandRouter::volumiosetStartupVolume Oct 18 19:18:25 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:18:26 volumio sudo[2081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 19:18:26 volumio sudo[2081]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:26 volumio sudo[2081]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:26 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 19:18:26 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:26 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:26 volumio sudo[2087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 18 19:18:26 volumio sudo[2087]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:26 volumio sudo[2087]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:26 volumio volumio[815]: info: Upmpdcli Daemon Started Oct 18 19:18:27 volumio sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 19:18:27 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:27 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:27 volumio sudo[2093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 19:18:27 volumio sudo[2093]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:27 volumio sudo[2093]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:27 volumio volumio[815]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.47 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 18 19:18:27 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 19:18:27 volumio volumio[815]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 19:18:27 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:28 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 18 19:18:28 volumio volumio[815]: info: Received Get System Info Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:18:28 volumio volumio[815]: info: Discovery: Getting this device information Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:28 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:28 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:28 volumio volumio[815]: info: Listing playlists Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 19:18:28 volumio sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 19:18:28 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:28 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:28 volumio sudo[2099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 19:18:28 volumio sudo[2099]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:18:28 volumio sudo[2099]: pam_unix(sudo:session): session closed for user root Oct 18 19:18:28 volumio volumio[815]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.47 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:28 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 18 19:18:28 volumio volumio[815]: info: Received Get System Info Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:18:28 volumio volumio[815]: info: Discovery: Getting this device information Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:28 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:28 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:28 volumio volumio[815]: info: Listing playlists Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 18 19:18:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 19:18:29 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 18 19:18:29 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 18 19:18:30 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 18 19:18:30 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 19:18:30 volumio volumio[815]: info: Received Get System Info Oct 18 19:18:30 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:18:30 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:18:30 volumio volumio[815]: info: Discovery: Getting this device information Oct 18 19:18:30 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:30 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:30 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:18:31 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Oct 18 19:18:32 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 19:18:32 volumio volumio[815]: info: Received Get System Info Oct 18 19:18:32 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:18:32 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:18:32 volumio volumio[815]: info: Discovery: Getting this device information Oct 18 19:18:32 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:32 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:32 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:18:32 volumio volumio[815]: info: CoreCommandRouter::volumioPlay Oct 18 19:18:32 volumio volumio[815]: info: CoreStateMachine::play index undefined Oct 18 19:18:32 volumio volumio[815]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 19:18:32 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:37 volumio volumio[815]: info: CoreCommandRouter::volumioGetQueue Oct 18 19:18:37 volumio volumio[815]: info: CoreStateMachine::getQueue Oct 18 19:18:37 volumio volumio[815]: info: CorePlayQueue::getQueue Oct 18 19:18:38 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 19:18:40 volumio volumio[815]: info: CoreCommandRouter::Close All Modals sent Oct 18 19:18:40 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Oct 18 19:18:43 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 18 19:18:43 volumio volumio[815]: info: CURURI: playlists Oct 18 19:18:43 volumio volumio[815]: info: Listing playlists Oct 18 19:18:43 volumio volumio[815]: info: Preload queue cleared Oct 18 19:18:46 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 19:18:46 volumio volumio[815]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 18 19:18:46 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 18 19:18:46 volumio volumio[815]: info: Received Get System Version Oct 18 19:18:46 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 19:18:46 volumio volumio[815]: info: Received Get System Info Oct 18 19:18:46 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:18:46 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:18:46 volumio volumio[815]: info: Discovery: Getting this device information Oct 18 19:18:46 volumio volumio[815]: info: CoreCommandRouter::volumioGetState Oct 18 19:18:46 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:46 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:49 volumio volumio[815]: info: Retrieving Cloud Streaming UI Oct 18 19:18:49 volumio volumio[815]: info: Getting Tidal Cloud Configuration Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:49 volumio volumio[815]: info: Getting Qobuz Cloud Configuration Oct 18 19:18:49 volumio volumio[815]: info: Asking plugin for UI Config Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:49 volumio volumio[815]: info: Getting Spotify Cloud Configuration Oct 18 19:18:49 volumio volumio[815]: info: Asking plugin for UI Config Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:49 volumio volumio[815]: info: Saving Spotify Acccount Oct 18 19:18:49 volumio volumio[815]: info: Got Tidal Cloud Configuration Oct 18 19:18:49 volumio volumio[815]: info: Got it Oct 18 19:18:49 volumio volumio[815]: info: Got it Oct 18 19:18:49 volumio volumio[815]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:18:49 volumio volumio[815]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:18:49 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 18 19:18:51 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand rescan Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:18:51 volumio volumio[815]: info: Oct 18 19:18:51 volumio volumio[815]: ---------------------------- MPD announces state update: update Oct 18 19:18:51 volumio volumio[815]: info: sendMpdCommand rescan took 8 milliseconds Oct 18 19:18:51 volumio volumio[815]: info: ControllerMpd::getState Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:18:51 volumio volumio[815]: info: Oct 18 19:18:51 volumio volumio[815]: ---------------------------- MPD announces state update: update Oct 18 19:18:51 volumio volumio[815]: info: ControllerMpd::getState Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:18:51 volumio volumio[815]: info: sendMpdCommand status took 22 milliseconds Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::parseState Oct 18 19:18:51 volumio volumio[815]: info: Command Router : Notfying DB Updatetrue Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:18:51 volumio volumio[815]: info: Oct 18 19:18:51 volumio volumio[815]: ---------------------------- MPD announces state update: update Oct 18 19:18:51 volumio volumio[815]: info: ControllerMpd::getState Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:18:51 volumio volumio[815]: info: sendMpdCommand status took 85 milliseconds Oct 18 19:18:51 volumio volumio[815]: info: sendMpdCommand status took 84 milliseconds Oct 18 19:18:51 volumio volumio[815]: info: sendMpdCommand status took 81 milliseconds Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::parseState Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::parseState Oct 18 19:18:51 volumio volumio[815]: info: Command Router : Notfying DB Updatefalse Oct 18 19:18:51 volumio volumio[815]: info: CoreCommandRouter::Close All Modals sent Oct 18 19:18:51 volumio volumio[815]: verbose: ControllerMpd::parseState Oct 18 19:18:51 volumio volumio[815]: info: ControllerMpd::pushState Oct 18 19:18:51 volumio volumio[815]: info: CoreCommandRouter::servicePushState Oct 18 19:18:51 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:51 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:51 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:51 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:51 volumio volumio[815]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 18 19:18:51 volumio volumio[815]: verbose: CURRENT POSITION 0 Oct 18 19:18:51 volumio volumio[815]: info: CoreStateMachine::syncState stateService stop Oct 18 19:18:51 volumio volumio[815]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:18:51 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:51 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:51 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:51 volumio volumio[815]: info: No code Oct 18 19:18:51 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:51 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:51 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:51 volumio volumio[815]: info: ControllerMpd::pushState Oct 18 19:18:51 volumio volumio[815]: info: CoreCommandRouter::servicePushState Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 18 19:18:52 volumio volumio[815]: verbose: CURRENT POSITION 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::syncState stateService stop Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: No code Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: ------------------------------ 220ms Oct 18 19:18:52 volumio volumio[815]: info: ------------------------------ 240ms Oct 18 19:18:52 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:18:52 volumio volumio[815]: info: Oct 18 19:18:52 volumio volumio[815]: ---------------------------- MPD announces state update: update Oct 18 19:18:52 volumio volumio[815]: info: ControllerMpd::getState Oct 18 19:18:52 volumio volumio[815]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:18:52 volumio volumio[815]: info: sendMpdCommand status took 178 milliseconds Oct 18 19:18:52 volumio volumio[815]: info: sendMpdCommand status took 177 milliseconds Oct 18 19:18:52 volumio volumio[815]: info: sendMpdCommand status took 17 milliseconds Oct 18 19:18:52 volumio volumio[815]: info: sendMpdCommand status took 16 milliseconds Oct 18 19:18:52 volumio volumio[815]: verbose: ControllerMpd::parseState Oct 18 19:18:52 volumio volumio[815]: info: Command Router : Notfying DB Updatefalse Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::Close All Modals sent Oct 18 19:18:52 volumio volumio[815]: verbose: ControllerMpd::parseState Oct 18 19:18:52 volumio volumio[815]: verbose: ControllerMpd::parseState Oct 18 19:18:52 volumio volumio[815]: info: Command Router : Notfying DB Updatefalse Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::Close All Modals sent Oct 18 19:18:52 volumio volumio[815]: verbose: ControllerMpd::parseState Oct 18 19:18:52 volumio volumio[815]: info: ControllerMpd::pushState Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::servicePushState Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 18 19:18:52 volumio volumio[815]: verbose: CURRENT POSITION 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::syncState stateService stop Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: No code Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: ControllerMpd::pushState Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::servicePushState Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 18 19:18:52 volumio volumio[815]: verbose: CURRENT POSITION 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::syncState stateService stop Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: No code Oct 18 19:18:52 volumio volumio[815]: info: CoreStateMachine::pushState Oct 18 19:18:52 volumio volumio[815]: info: CorePlayQueue::getTrack 0 Oct 18 19:18:52 volumio volumio[815]: info: CoreCommandRouter::volumioPushState Oct 18 19:18:52 volumio volumio[815]: info: ------------------------------ 323ms Oct 18 19:18:52 volumio volumio[815]: info: ------------------------------ 163ms Oct 18 19:18:53 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:18:54 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 18 19:18:54 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 18 19:18:54 volumio volumio[815]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 19:18:54 volumio volumio[815]: Error: getaddrinfo -3007 Oct 18 19:18:54 volumio volumio[815]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Oct 18 19:18:54 volumio volumio[815]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Oct 18 19:18:54 volumio volumio[815]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Oct 18 19:18:54 volumio volumio[815]: code: -3007, Oct 18 19:18:54 volumio volumio[815]: errno: -3007, Oct 18 19:18:54 volumio volumio[815]: syscall: 'getaddrinfo' Oct 18 19:18:54 volumio volumio[815]: } Oct 18 19:18:54 volumio volumio[815]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 19:18:55 volumio sudo[2165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 19:17 Oct 18 19:18:55 volumio sudo[2165]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"