-- Logs begin at Thu 2024-07-18 14:17:11 EST, end at Thu 2024-07-18 19:22:24 EST. -- Jul 18 19:21:03 volumioasus sudo[1726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 19:21:03 volumioasus sudo[1726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:03 volumioasus sudo[1726]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:03 volumioasus sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 19:21:03 volumioasus sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:03 volumioasus sudo[1728]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:03 volumioasus volumio[806]: verbose: New Socket.io Connection to 192.168.68.105 from 192.168.68.102 UA: okhttp/4.9.2 Total Clients: 6 Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetState Jul 18 19:21:03 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 18 19:21:03 volumioasus volumio[806]: info: Received Get System Info Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 19:21:03 volumioasus volumio[806]: info: Discovery: Getting this device information Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetState Jul 18 19:21:03 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetState Jul 18 19:21:03 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:03 volumioasus volumio[806]: info: Listing playlists Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 18 19:21:03 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 18 19:21:04 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 18 19:21:04 volumioasus volumio[806]: info: Received Get System Info Jul 18 19:21:04 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 19:21:04 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 19:21:04 volumioasus volumio[806]: info: Discovery: Getting this device information Jul 18 19:21:04 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetState Jul 18 19:21:04 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:04 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 19:21:06 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 18 19:21:06 volumioasus volumio[806]: info: Received Get System Info Jul 18 19:21:06 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 19:21:06 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 19:21:06 volumioasus volumio[806]: info: Discovery: Getting this device information Jul 18 19:21:06 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetState Jul 18 19:21:06 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:06 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 19:21:11 volumioasus volumio[806]: info: CoreCommandRouter::volumioPlay Jul 18 19:21:11 volumioasus volumio[806]: info: CoreStateMachine::play index undefined Jul 18 19:21:11 volumioasus volumio[806]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 19:21:11 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:11 volumioasus volumio[806]: info: CoreStateMachine::startPlaybackTimer Jul 18 19:21:11 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:11 volumioasus volumio[806]: info: [1721348471004] ControllerWebradio::clearAddPlayTrack Jul 18 19:21:11 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 19:21:11 volumioasus volumio[806]: info: sendMpdCommand stop took 1 milliseconds Jul 18 19:21:11 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 19:21:11 volumioasus volumio[806]: info: sendMpdCommand clear took 0 milliseconds Jul 18 19:21:11 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" Jul 18 19:21:11 volumioasus volumio[806]: info: Jul 18 19:21:11 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:21:11 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:21:11 volumioasus volumio[806]: info: Jul 18 19:21:11 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:21:11 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:21:11 volumioasus volumio[806]: info: Jul 18 19:21:11 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:21:11 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:21:11 volumioasus volumio[806]: error: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 18 19:21:20 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 19:21:21 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 18 19:21:21 volumioasus volumio[806]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 18 19:21:21 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 18 19:21:21 volumioasus volumio[806]: info: Received Get System Version Jul 18 19:21:21 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 19:21:21 volumioasus volumio[806]: info: Received Get System Info Jul 18 19:21:21 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 19:21:21 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 19:21:21 volumioasus volumio[806]: info: Discovery: Getting this device information Jul 18 19:21:21 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetState Jul 18 19:21:21 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:21 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 19:21:23 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" Jul 18 19:21:23 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:21:23 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:21:23 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:21:23 volumioasus volumio[806]: info: ------------------------------ 12992ms Jul 18 19:21:23 volumioasus volumio[806]: info: ------------------------------ 12991ms Jul 18 19:21:23 volumioasus volumio[806]: info: ------------------------------ 12991ms Jul 18 19:21:23 volumioasus volumio[806]: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:21:23 volumioasus volumio[806]: info: Jul 18 19:21:23 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:21:23 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:21:23 volumioasus volumio[806]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" took 1 milliseconds Jul 18 19:21:23 volumioasus volumio[806]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 19:21:23 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand play Jul 18 19:21:24 volumioasus volumio[806]: info: Jul 18 19:21:24 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:21:24 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:21:24 volumioasus volumio[806]: info: Jul 18 19:21:24 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:21:24 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:21:24 volumioasus volumio[806]: info: ------------------------------ 3ms Jul 18 19:21:24 volumioasus volumio[806]: info: sendMpdCommand play took 3 milliseconds Jul 18 19:21:24 volumioasus volumio[806]: info: ------------------------------ 3ms Jul 18 19:21:28 volumioasus volumio[806]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 18 19:21:28 volumioasus volumio[806]: info: Preparing to save Alsa Options, stopping services first Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetState Jul 18 19:21:28 volumioasus volumio[806]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"D10"}} Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 19:21:28 volumioasus volumio[806]: info: Setting default mixerSoftMaster for Softvolume device Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 18 19:21:28 volumioasus volumio[806]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 18 19:21:28 volumioasus volumio[806]: info: Disabling external Volume Control Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 19:21:28 volumioasus volumio[806]: info: Enable softmixer device for audio device undefined Jul 18 19:21:28 volumioasus volumio[806]: info: Software Volume ALSA configuration written Jul 18 19:21:28 volumioasus volumio[806]: info: Preparing to generate the ALSA configuration file Jul 18 19:21:28 volumioasus volumio[806]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 18 19:21:28 volumioasus volumio[806]: info: Reading ALSA contributions from plugins. Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 19:21:28 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:21:28 volumioasus volumio[806]: info: Asound.conf file written Jul 18 19:21:28 volumioasus sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 18 19:21:28 volumioasus sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:28 volumioasus sudo[1818]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:28 volumioasus volumio[806]: Found hardware: "HDA-Intel" "Realtek ALC269VC" "HDA:10ec0269,1c6c122e,00100203" "0x1c6c" "0x122e" Jul 18 19:21:28 volumioasus volumio[806]: Hardware is initialized using a generic method Jul 18 19:21:28 volumioasus volumio[806]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #1 (No such file or directory) Jul 18 19:21:28 volumioasus volumio[806]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #5 (No such file or directory) Jul 18 19:21:28 volumioasus volumio[806]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #21 (No such file or directory) Jul 18 19:21:28 volumioasus volumio[806]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #22 (No such file or directory) Jul 18 19:21:28 volumioasus volumio[806]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #23 (No such file or directory) Jul 18 19:21:28 volumioasus volumio[806]: No state is present for card D10 Jul 18 19:21:28 volumioasus volumio[806]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" "" Jul 18 19:21:28 volumioasus volumio[806]: Hardware is initialized using a generic method Jul 18 19:21:28 volumioasus volumio[806]: No state is present for card D10 Jul 18 19:21:28 volumioasus volumio[806]: info: Output device has changed, restarting MPD Jul 18 19:21:28 volumioasus sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 19:21:28 volumioasus sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:28 volumioasus volumio[806]: info: Output device has changed, restarting Shairport Sync Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 19:21:28 volumioasus sudo[1826]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:28 volumioasus sudo[1829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 19:21:28 volumioasus sudo[1829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:28 volumioasus volumio[806]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 19:21:28 volumioasus volumio[806]: info: Output device has changed, restarting MPD Jul 18 19:21:28 volumioasus systemd[1]: Stopping Music Player Daemon... Jul 18 19:21:28 volumioasus volumio[806]: info: Output device has changed, restarting Shairport Sync Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 19:21:28 volumioasus sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 19:21:28 volumioasus sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:28 volumioasus sudo[1835]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:28 volumioasus sudo[1837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 19:21:28 volumioasus sudo[1837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:28 volumioasus volumio[806]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 19:21:28 volumioasus volumio[806]: info: MPD Permissions set Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 18 19:21:28 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 19:21:28 volumioasus volumio[806]: info: Starting Shairport Sync Jul 18 19:21:28 volumioasus volumio[806]: info: Starting Shairport Sync Jul 18 19:21:28 volumioasus sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 18 19:21:28 volumioasus sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:28 volumioasus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 18 19:21:28 volumioasus systemd[1]: shairport-sync.service: Succeeded. Jul 18 19:21:28 volumioasus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 18 19:21:28 volumioasus sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 18 19:21:28 volumioasus sudo[1859]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:28 volumioasus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 18 19:21:28 volumioasus sudo[1856]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:29 volumioasus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 18 19:21:29 volumioasus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 18 19:21:29 volumioasus systemd[1]: shairport-sync.service: Succeeded. Jul 18 19:21:29 volumioasus volumio[806]: info: Shairport-Sync Started Jul 18 19:21:29 volumioasus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 18 19:21:29 volumioasus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 18 19:21:29 volumioasus sudo[1859]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:29 volumioasus volumio[806]: info: Shairport-Sync Started Jul 18 19:21:29 volumioasus volumio[806]: error: Upnp client error: Error: read ECONNRESET Jul 18 19:21:29 volumioasus systemd[1]: mpd.service: Succeeded. Jul 18 19:21:29 volumioasus systemd[1]: Stopped Music Player Daemon. Jul 18 19:21:29 volumioasus systemd[1]: Starting Music Player Daemon... Jul 18 19:21:29 volumioasus mpd[1867]: Jul 18 19:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 19:21:29 volumioasus systemd[1]: Started Music Player Daemon. Jul 18 19:21:29 volumioasus sudo[1837]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:29 volumioasus sudo[1829]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:29 volumioasus volumio[806]: error: MPD error: The expression evaluated to a falsy value: Jul 18 19:21:29 volumioasus volumio[806]: assert.ok(self.idling) Jul 18 19:21:29 volumioasus volumio[806]: error: The expression evaluated to a falsy value: Jul 18 19:21:29 volumioasus volumio[806]: assert.ok(self.idling) Jul 18 19:21:29 volumioasus volumio[806]: info: VolumeController::SetAlsaVolume0 Jul 18 19:21:29 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:21:29 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 19:21:29 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:21:29 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:21:29 volumioasus volumio[806]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 18 19:21:29 volumioasus volumio[806]: info: VolumeController::SetAlsaVolume0 Jul 18 19:21:29 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:21:29 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:21:29 volumioasus volumio[806]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 18 19:21:32 volumioasus volumio[806]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 18 19:21:32 volumioasus volumio[806]: info: CoreStateMachine::getcurrentVolume Jul 18 19:21:32 volumioasus volumio[806]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 19:21:32 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:21:32 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 19:21:32 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:21:32 volumioasus volumio[806]: info: CoreStateMachine::updateTrackBlock Jul 18 19:21:32 volumioasus volumio[806]: info: CorePlayQueue::getTrackBlock Jul 18 19:21:32 volumioasus volumio[806]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 19:21:32 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:21:32 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:21:36 volumioasus volumio[806]: info: VolumeController::SetAlsaVolume+ Jul 18 19:21:36 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:21:36 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 19:21:36 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:21:36 volumioasus volumio[806]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 18 19:21:39 volumioasus volumio[806]: info: VolumeController::SetAlsaVolume+ Jul 18 19:21:39 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:21:39 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 19:21:39 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:21:39 volumioasus volumio[806]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 18 19:21:49 volumioasus volumio[806]: info: CoreCommandRouter::volumioPlay Jul 18 19:21:49 volumioasus volumio[806]: info: CoreStateMachine::play index undefined Jul 18 19:21:49 volumioasus volumio[806]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 19:21:49 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:49 volumioasus volumio[806]: info: CoreStateMachine::startPlaybackTimer Jul 18 19:21:49 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:21:49 volumioasus volumio[806]: info: [1721348509854] ControllerWebradio::clearAddPlayTrack Jul 18 19:21:49 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 19:21:49 volumioasus volumio[806]: info: sendMpdCommand stop took 1 milliseconds Jul 18 19:21:49 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 19:21:49 volumioasus volumio[806]: info: Jul 18 19:21:49 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:21:49 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:21:49 volumioasus volumio[806]: info: sendMpdCommand clear took 1 milliseconds Jul 18 19:21:49 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" Jul 18 19:21:49 volumioasus volumio[806]: info: Jul 18 19:21:49 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:21:49 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:21:49 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:21:49 volumioasus volumio[806]: info: ------------------------------ 1ms Jul 18 19:21:57 volumioasus volumio[806]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 19:21:57 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 19:21:57 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 18 19:21:57 volumioasus sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 18 19:21:57 volumioasus sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:57 volumioasus sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 18 19:21:57 volumioasus sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:57 volumioasus sudo[1937]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:57 volumioasus sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 18 19:21:57 volumioasus sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:57 volumioasus sudo[1945]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:57 volumioasus sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 18 19:21:57 volumioasus sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:57 volumioasus sudo[1951]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:57 volumioasus sudo[1957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 19:21:57 volumioasus sudo[1932]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:57 volumioasus sudo[1957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:57 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 18 19:21:57 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 18 19:21:57 volumioasus sudo[1957]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:57 volumioasus sudo[1959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 19:21:57 volumioasus sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:21:57 volumioasus sudo[1959]: pam_unix(sudo:session): session closed for user root Jul 18 19:21:57 volumioasus sudo[1964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 19:21:57 volumioasus sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:01 volumioasus sudo[1964]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:02 volumioasus volumio[806]: info: CoreCommandRouter::volumioPlay Jul 18 19:22:02 volumioasus volumio[806]: info: CoreStateMachine::play index undefined Jul 18 19:22:02 volumioasus volumio[806]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 19:22:02 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:22:02 volumioasus volumio[806]: info: CoreStateMachine::startPlaybackTimer Jul 18 19:22:02 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:22:02 volumioasus volumio[806]: info: [1721348522242] ControllerWebradio::clearAddPlayTrack Jul 18 19:22:02 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 19:22:03 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" Jul 18 19:22:03 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:22:03 volumioasus volumio[806]: info: Jul 18 19:22:03 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:22:03 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:22:03 volumioasus volumio[806]: info: ------------------------------ 13265ms Jul 18 19:22:03 volumioasus volumio[806]: info: sendMpdCommand stop took 878 milliseconds Jul 18 19:22:03 volumioasus volumio[806]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" took 1 milliseconds Jul 18 19:22:03 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 19:22:03 volumioasus volumio[806]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 19:22:03 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand play Jul 18 19:22:03 volumioasus volumio[806]: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:22:03 volumioasus volumio[806]: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:22:03 volumioasus volumio[806]: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:22:03 volumioasus volumio[806]: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:22:03 volumioasus volumio[806]: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:22:03 volumioasus volumio[806]: info: Jul 18 19:22:03 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:22:03 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:22:03 volumioasus volumio[806]: info: Jul 18 19:22:03 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:22:03 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:22:03 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:22:03 volumioasus volumio[806]: info: ------------------------------ 1ms Jul 18 19:22:03 volumioasus volumio[806]: info: sendMpdCommand clear took 1 milliseconds Jul 18 19:22:03 volumioasus volumio[806]: info: sendMpdCommand play took 2 milliseconds Jul 18 19:22:03 volumioasus volumio[806]: info: ------------------------------ 1ms Jul 18 19:22:03 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" Jul 18 19:22:03 volumioasus volumio[806]: info: Jul 18 19:22:03 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:22:03 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:22:03 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:22:03 volumioasus volumio[806]: info: ------------------------------ 1ms Jul 18 19:22:11 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" Jul 18 19:22:11 volumioasus volumio[806]: error: updateQueue error: null Jul 18 19:22:11 volumioasus volumio[806]: info: ------------------------------ 8560ms Jul 18 19:22:11 volumioasus volumio[806]: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:22:11 volumioasus volumio[806]: Upnp client error: Error: This socket has been ended by the other party Jul 18 19:22:11 volumioasus volumio[806]: info: Jul 18 19:22:11 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:22:11 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:22:11 volumioasus volumio[806]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3" took 1 milliseconds Jul 18 19:22:11 volumioasus volumio[806]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 19:22:11 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand play Jul 18 19:22:11 volumioasus volumio[806]: info: Jul 18 19:22:11 volumioasus volumio[806]: ---------------------------- MPD announces system playlist update Jul 18 19:22:11 volumioasus volumio[806]: info: Ignoring MPD Status Update Jul 18 19:22:11 volumioasus volumio[806]: info: ------------------------------ 1ms Jul 18 19:22:11 volumioasus volumio[806]: info: sendMpdCommand play took 1 milliseconds Jul 18 19:22:11 volumioasus volumio[806]: info: ------------------------------ 1ms Jul 18 19:22:14 volumioasus volumio[806]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Jul 18 19:22:14 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Jul 18 19:22:15 volumioasus sudo[2025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Jul 18 19:22:15 volumioasus sudo[2025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:15 volumioasus sudo[2025]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:15 volumioasus volumio[806]: info: Permissions for /etc/hostapd/hostapd.conf Jul 18 19:22:15 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 18 19:22:15 volumioasus volumio[806]: info: Discovery: Restarting Advertising due to device name change Jul 18 19:22:15 volumioasus sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 18 19:22:15 volumioasus sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:15 volumioasus systemd[1]: Stopping Wireless Services... Jul 18 19:22:15 volumioasus kernel: wlan0: deauthenticating from 52:91:e3:2e:c7:f2 by local choice (Reason: 3=DEAUTH_LEAVING) Jul 18 19:22:15 volumioasus dhcpcd[566]: wlan0: carrier lost Jul 18 19:22:15 volumioasus avahi-daemon[545]: Withdrawing address record for 192.168.68.105 on wlan0. Jul 18 19:22:15 volumioasus avahi-daemon[545]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.105. Jul 18 19:22:15 volumioasus wpa_supplicant[707]: wlan0: CTRL-EVENT-DISCONNECTED bssid=52:91:e3:2e:c7:f2 reason=3 locally_generated=1 Jul 18 19:22:15 volumioasus avahi-daemon[545]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 18 19:22:15 volumioasus volumio[806]: info: Discovery: A device disappeared from network Jul 18 19:22:15 volumioasus volumio[806]: info: Discovery: Device volumioasus disappeared from network Jul 18 19:22:15 volumioasus dhcpcd[566]: wlan0: deleting route to 192.168.68.0/22 Jul 18 19:22:15 volumioasus dhcpcd[566]: wlan0: deleting default route via 192.168.68.1 Jul 18 19:22:15 volumioasus wpa_supplicant[707]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 18 19:22:15 volumioasus wpa_supplicant[707]: wlan0: CTRL-EVENT-TERMINATING Jul 18 19:22:15 volumioasus systemd[1]: wireless.service: Succeeded. Jul 18 19:22:15 volumioasus systemd[1]: Stopped Wireless Services. Jul 18 19:22:15 volumioasus systemd[1]: Starting Wireless Services... Jul 18 19:22:15 volumioasus wireless.js[2056]: Cleaning previous... Jul 18 19:22:15 volumioasus sudo[2070]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 18 19:22:15 volumioasus sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:15 volumioasus sudo[2070]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:15 volumioasus sudo[2072]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 18 19:22:15 volumioasus sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:15 volumioasus sudo[2072]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:15 volumioasus wireless.js[2056]: Stopped aP Jul 18 19:22:15 volumioasus sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 18 19:22:15 volumioasus sudo[2079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:15 volumioasus sudo[2079]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:15 volumioasus sudo[2081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 18 19:22:15 volumioasus sudo[2081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:15 volumioasus sudo[2081]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:15 volumioasus sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 18 19:22:15 volumioasus sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:15 volumioasus sudo[2088]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:15 volumioasus sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 18 19:22:15 volumioasus sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:16 volumioasus sudo[2090]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:16 volumioasus wireless.js[2056]: Start wireless flow Jul 18 19:22:16 volumioasus wireless.js[2056]: Stopped hotspot (if there).. Jul 18 19:22:16 volumioasus sudo[2097]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 18 19:22:16 volumioasus sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:16 volumioasus sudo[2097]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:16 volumioasus sudo[2099]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 18 19:22:16 volumioasus sudo[2099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:16 volumioasus sudo[2099]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:16 volumioasus wireless.js[2056]: DHCP IP Jul 18 19:22:16 volumioasus wpa_supplicant[2101]: Successfully initialized wpa_supplicant Jul 18 19:22:16 volumioasus wireless.js[2056]: Start ap Jul 18 19:22:16 volumioasus sudo[2102]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 18 19:22:16 volumioasus sudo[2102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:16 volumioasus dhcpcd[2103]: sending commands to master dhcpcd process Jul 18 19:22:16 volumioasus dhcpcd[566]: control command: /sbin/dhcpcd Jul 18 19:22:16 volumioasus sudo[2102]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:16 volumioasus wpa_supplicant[2104]: wlan0: SME: Trying to authenticate with 52:91:e3:2e:c7:f2 (SSID='decoed1' freq=2417 MHz) Jul 18 19:22:16 volumioasus kernel: wlan0: authenticate with 52:91:e3:2e:c7:f2 Jul 18 19:22:16 volumioasus kernel: wlan0: 80 MHz not supported, disabling VHT Jul 18 19:22:16 volumioasus kernel: wlan0: send auth to 52:91:e3:2e:c7:f2 (try 1/3) Jul 18 19:22:16 volumioasus wpa_supplicant[2104]: wlan0: Trying to associate with 52:91:e3:2e:c7:f2 (SSID='decoed1' freq=2417 MHz) Jul 18 19:22:16 volumioasus kernel: wlan0: authenticated Jul 18 19:22:16 volumioasus kernel: wlan0: associate with 52:91:e3:2e:c7:f2 (try 1/3) Jul 18 19:22:16 volumioasus wpa_supplicant[2104]: wlan0: Associated with 52:91:e3:2e:c7:f2 Jul 18 19:22:16 volumioasus wpa_supplicant[2104]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 18 19:22:16 volumioasus wpa_supplicant[2104]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jul 18 19:22:16 volumioasus kernel: wlan0: RX AssocResp from 52:91:e3:2e:c7:f2 (capab=0x1c11 status=0 aid=5) Jul 18 19:22:16 volumioasus kernel: usb 1-3: rtl8xxxu_bss_info_changed: HT supported Jul 18 19:22:16 volumioasus kernel: wlan0: associated Jul 18 19:22:17 volumioasus kernel: wlan0: Limiting TX power to 35 (35 - 0) dBm as advertised by 52:91:e3:2e:c7:f2 Jul 18 19:22:17 volumioasus wireless.js[2056]: trying... Jul 18 19:22:17 volumioasus sudo[2108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 18 19:22:17 volumioasus sudo[2108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:17 volumioasus sudo[2108]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:17 volumioasus wpa_supplicant[2104]: wlan0: WPA: Key negotiation completed with 52:91:e3:2e:c7:f2 [PTK=CCMP GTK=CCMP] Jul 18 19:22:17 volumioasus wpa_supplicant[2104]: wlan0: CTRL-EVENT-CONNECTED - Connection to 52:91:e3:2e:c7:f2 completed [id=0 id_str=] Jul 18 19:22:17 volumioasus dhcpcd[566]: wlan0: carrier acquired Jul 18 19:22:17 volumioasus dhcpcd[566]: wlan0: IAID 0e:1f:3c:b0 Jul 18 19:22:17 volumioasus volumio[806]: info: Jul 18 19:22:17 volumioasus volumio[806]: ---------------------------- MPD announces state update: player Jul 18 19:22:17 volumioasus volumio[806]: info: ControllerMpd::getState Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand status Jul 18 19:22:17 volumioasus volumio[806]: info: Jul 18 19:22:17 volumioasus volumio[806]: ---------------------------- MPD announces state update: player Jul 18 19:22:17 volumioasus volumio[806]: info: ControllerMpd::getState Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand status Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 19:22:17 volumioasus volumio[806]: info: sendMpdCommand status took 2 milliseconds Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 19:22:17 volumioasus volumio[806]: info: sendMpdCommand status took 3 milliseconds Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::parseState Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::parseState Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 19:22:17 volumioasus volumio[806]: info: sendMpdCommand clearerror took 4 milliseconds Jul 18 19:22:17 volumioasus volumio[806]: info: sendMpdCommand clearerror took 3 milliseconds Jul 18 19:22:17 volumioasus volumio[806]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 19:22:17 volumioasus volumio[806]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::parseTrackInfo Jul 18 19:22:17 volumioasus volumio[806]: verbose: ControllerMpd::parseTrackInfo Jul 18 19:22:17 volumioasus volumio[806]: info: ControllerMpd::pushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::servicePushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:22:17 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:22:17 volumioasus volumio[806]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LOS40_ASO_MORON.mp3","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3","trackType":"mp3"} Jul 18 19:22:17 volumioasus volumio[806]: verbose: CURRENT POSITION 0 Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::syncState stateService stop Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::syncState currentStatus stop Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:22:17 volumioasus volumio[806]: info: No code Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:22:17 volumioasus volumio[806]: info: ControllerMpd::pushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::servicePushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:22:17 volumioasus volumio[806]: info: CorePlayQueue::getTrack 0 Jul 18 19:22:17 volumioasus volumio[806]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LOS40_ASO_MORON.mp3","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/LOS40_ASO_MORON.mp3","trackType":"mp3"} Jul 18 19:22:17 volumioasus volumio[806]: verbose: CURRENT POSITION 0 Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::syncState stateService stop Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::syncState currentStatus stop Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:22:17 volumioasus volumio[806]: info: No code Jul 18 19:22:17 volumioasus volumio[806]: info: CoreStateMachine::pushState Jul 18 19:22:17 volumioasus volumio[806]: info: CoreCommandRouter::volumioPushState Jul 18 19:22:17 volumioasus volumio[806]: info: ------------------------------ 24ms Jul 18 19:22:17 volumioasus volumio[806]: info: ------------------------------ 23ms Jul 18 19:22:17 volumioasus dhcpcd[566]: wlan0: rebinding lease of 192.168.68.105 Jul 18 19:22:17 volumioasus dhcpcd[566]: wlan0: soliciting an IPv6 router Jul 18 19:22:18 volumioasus wireless.js[2056]: trying... Jul 18 19:22:18 volumioasus sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 18 19:22:18 volumioasus sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:18 volumioasus sudo[2115]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:18 volumioasus dhcpcd[566]: wlan0: probing address 192.168.68.105/22 Jul 18 19:22:18 volumioasus ntpd[795]: Deleting interface #3 wlan0, 192.168.68.105#123, interface stats: received=80, sent=84, dropped=0, active_time=300 secs Jul 18 19:22:18 volumioasus ntpd[795]: 192.36.143.130 local addr 192.168.68.105 -> Jul 18 19:22:18 volumioasus ntpd[795]: 194.0.5.123 local addr 192.168.68.105 -> Jul 18 19:22:18 volumioasus ntpd[795]: 216.238.66.57 local addr 192.168.68.105 -> Jul 18 19:22:18 volumioasus ntpd[795]: 132.248.30.3 local addr 192.168.68.105 -> Jul 18 19:22:18 volumioasus ntpd[795]: 45.231.168.6 local addr 192.168.68.105 -> Jul 18 19:22:18 volumioasus ntpd[795]: 216.238.85.87 local addr 192.168.68.105 -> Jul 18 19:22:18 volumioasus ntpd[795]: 132.248.3.29 local addr 192.168.68.105 -> Jul 18 19:22:19 volumioasus wireless.js[2056]: trying... Jul 18 19:22:19 volumioasus sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 18 19:22:19 volumioasus sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:19 volumioasus sudo[2120]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:20 volumioasus volumio[806]: info: Discovery: Started advertising with name: VolumioAsus Jul 18 19:22:20 volumioasus wireless.js[2056]: trying... Jul 18 19:22:20 volumioasus sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 18 19:22:20 volumioasus sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:20 volumioasus sudo[2126]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:21 volumioasus wireless.js[2056]: trying... Jul 18 19:22:21 volumioasus sudo[2131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 18 19:22:21 volumioasus sudo[2131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:21 volumioasus sudo[2131]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:22 volumioasus wireless.js[2056]: trying... Jul 18 19:22:22 volumioasus sudo[2136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 18 19:22:22 volumioasus sudo[2136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:22 volumioasus sudo[2136]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:23 volumioasus wireless.js[2056]: trying... Jul 18 19:22:23 volumioasus sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 18 19:22:23 volumioasus sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:23 volumioasus sudo[2141]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:23 volumioasus dhcpcd[566]: wlan0: leased 192.168.68.105 for 7200 seconds Jul 18 19:22:23 volumioasus dhcpcd[566]: wlan0: adding route to 192.168.68.0/22 Jul 18 19:22:23 volumioasus dhcpcd[566]: wlan0: adding default route via 192.168.68.1 Jul 18 19:22:23 volumioasus avahi-daemon[545]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.105. Jul 18 19:22:23 volumioasus avahi-daemon[545]: New relevant interface wlan0.IPv4 for mDNS. Jul 18 19:22:23 volumioasus avahi-daemon[545]: Registering new address record for 192.168.68.105 on wlan0.IPv4. Jul 18 19:22:23 volumioasus ntpd[795]: ntpd exiting on signal 15 (Terminated) Jul 18 19:22:23 volumioasus systemd[1]: Stopping Network Time Service... Jul 18 19:22:23 volumioasus systemd[1]: ntp.service: Succeeded. Jul 18 19:22:23 volumioasus systemd[1]: Stopped Network Time Service. Jul 18 19:22:23 volumioasus systemd[1]: Starting Network Time Service... Jul 18 19:22:23 volumioasus ntpd[2175]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 18 19:22:23 volumioasus ntpd[2175]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 18 19:22:23 volumioasus systemd[1]: Started Network Time Service. Jul 18 19:22:23 volumioasus ntpd[2181]: proto: precision = 0.053 usec (-24) Jul 18 19:22:23 volumioasus ntpd[2181]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 18 19:22:23 volumioasus ntpd[2181]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 18 19:22:23 volumioasus ntpd[2181]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 570 days ago Jul 18 19:22:23 volumioasus ntpd[2181]: Listen and drop on 0 v6wildcard [::]:123 Jul 18 19:22:23 volumioasus ntpd[2181]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 18 19:22:23 volumioasus ntpd[2181]: Listen normally on 2 lo 127.0.0.1:123 Jul 18 19:22:23 volumioasus ntpd[2181]: Listen normally on 3 wlan0 192.168.68.105:123 Jul 18 19:22:23 volumioasus ntpd[2181]: Listening on routing socket on fd #20 for interface updates Jul 18 19:22:23 volumioasus ntpd[2181]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 18 19:22:23 volumioasus ntpd[2181]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 18 19:22:24 volumioasus wireless.js[2056]: trying... Jul 18 19:22:24 volumioasus sudo[2187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 18 19:22:24 volumioasus sudo[2187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:24 volumioasus sudo[2187]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:24 volumioasus wireless.js[2056]: Connected to: ----decoed1 Jul 18 19:22:24 volumioasus wireless.js[2056]: ---- Jul 18 19:22:24 volumioasus sudo[2190]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 19:22:24 volumioasus sudo[2190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 19:22:24 volumioasus sudo[2190]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:24 volumioasus wireless.js[2056]: ... joined AP, wlan0 IPv4 is 192.168.68.105, ipV6 is undefined Jul 18 19:22:24 volumioasus wireless.js[2056]: It's done! AP Jul 18 19:22:24 volumioasus iwconfig[2194]: Error for wireless request "Set Power Management" (8B2C) : Jul 18 19:22:24 volumioasus iwconfig[2194]: SET failed on device wlan0 ; Operation not supported. Jul 18 19:22:24 volumioasus systemd[1]: Started Wireless Services. Jul 18 19:22:24 volumioasus sudo[2028]: pam_unix(sudo:session): session closed for user root Jul 18 19:22:24 volumioasus volumio[806]: info: Discovery: adding 97db22b5-b3be-4f07-b6cc-d407c79a0573 Jul 18 19:22:24 volumioasus volumio[806]: info: Discovery: Found device VolumioAsus Jul 18 19:22:24 volumioasus volumio[806]: info: CoreCommandRouter::volumioGetState Jul 18 19:22:24 volumioasus volumio[806]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 19:22:24 volumioasus volumio[806]: Error: The value NaN is not a number Jul 18 19:22:24 volumioasus volumio[806]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jul 18 19:22:24 volumioasus volumio[806]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jul 18 19:22:24 volumioasus volumio[806]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jul 18 19:22:24 volumioasus volumio[806]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jul 18 19:22:24 volumioasus volumio[806]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jul 18 19:22:24 volumioasus volumio[806]: at Browser.emit (events.js:400:28) Jul 18 19:22:24 volumioasus volumio[806]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 18 19:22:24 volumioasus volumio[806]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 18 19:22:24 volumioasus volumio[806]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jul 18 19:22:24 volumioasus volumio[806]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jul 18 19:22:24 volumioasus volumio[806]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jul 18 19:22:24 volumioasus volumio[806]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jul 18 19:22:24 volumioasus volumio[806]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jul 18 19:22:24 volumioasus volumio[806]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 19:22:24 volumioasus sudo[2204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 19:21 Jul 18 19:22:24 volumioasus sudo[2204]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"