-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sun 2025-06-29 22:00:37 CST. -- Jun 29 21:59:00 volumio-rpi sudo[2683]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 29 21:59:00 volumio-rpi sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:00 volumio-rpi sudo[2683]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:01 volumio-rpi mpd[2685]: Jun 29 21:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 29 21:59:01 volumio-rpi systemd[1]: Started Music Player Daemon. Jun 29 21:59:01 volumio-rpi sudo[2676]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:01 volumio-rpi volumio[972]: error: updateQueue error: null Jun 29 21:59:06 volumio-rpi volumio[972]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 29 21:59:06 volumio-rpi volumio[972]: info: Retrying Login Due to Network Error Jun 29 21:59:15 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 21:59:15 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 21:59:15 volumio-rpi volumio[972]: info: Discovery: Getting this device information Jun 29 21:59:15 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetState Jun 29 21:59:15 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:15 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 21:59:16 volumio-rpi volumio[972]: info: MyVolumio login type: Token Jun 29 21:59:16 volumio-rpi volumio[972]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 29 21:59:16 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 29 21:59:16 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetState Jun 29 21:59:16 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:16 volumio-rpi volumio[972]: info: Restoring Previous Volume level: 76 true false Jun 29 21:59:16 volumio-rpi volumio[972]: info: Volume configurations have been set Jun 29 21:59:16 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 29 21:59:16 volumio-rpi volumio[972]: info: Updating Volume Controller Parameters: Device: 2 Name: Audiophonics I-Sabre ES9028Q2M Mixer: Digital Max Vol: 50 Vol Curve; logarithmic Vol Steps: 1 Jun 29 21:59:16 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 29 21:59:16 volumio-rpi volumio[972]: info: Disabling external Volume Control Jun 29 21:59:16 volumio-rpi volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 21:59:16 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:17 volumio-rpi kernel: i2c-bcm2835 3f804000.i2c: i2c transfer timed out Jun 29 21:59:17 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 Jun 29 21:59:17 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -5 Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 29 21:59:17 volumio-rpi volumio[972]: info: Getting Alsa Cards List without I2S DAC Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 29 21:59:17 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:17 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:17 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetState Jun 29 21:59:17 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioPause Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreStateMachine::pause Jun 29 21:59:17 volumio-rpi volumio[972]: info: mpdhttpout --- Volumio set on pause Jun 29 21:59:17 volumio-rpi volumio[972]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Jun 29 21:59:17 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:17 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:17 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:17 volumio-rpi volumio[972]: info: VolumeController:: Volume=49 Mute =true Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreStateMachine::pushState Jun 29 21:59:17 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 21:59:17 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioPushState Jun 29 21:59:17 volumio-rpi volumio[972]: info: [ASDebug] CurState: stop PrevState: stop Jun 29 21:59:19 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 29 21:59:19 volumio-rpi sudo[2732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 29 21:59:19 volumio-rpi sudo[2732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:19 volumio-rpi systemd[1]: Stopping Music Player Daemon... Jun 29 21:59:19 volumio-rpi systemd[1]: mpd.service: Succeeded. Jun 29 21:59:19 volumio-rpi systemd[1]: Stopped Music Player Daemon. Jun 29 21:59:19 volumio-rpi systemd[1]: Starting Music Player Daemon... Jun 29 21:59:19 volumio-rpi sudo[2734]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 29 21:59:19 volumio-rpi sudo[2734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:20 volumio-rpi sudo[2734]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:20 volumio-rpi volumio[972]: info: VolumeController::SetAlsaVolume76 Jun 29 21:59:20 volumio-rpi volumio[972]: info: CoreStateMachine::pushState Jun 29 21:59:20 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:20 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 21:59:20 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioPushState Jun 29 21:59:20 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:20 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:20 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:20 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:20 volumio-rpi volumio[972]: info: [ASDebug] CurState: stop PrevState: stop Jun 29 21:59:21 volumio-rpi mpd[2736]: Jun 29 21:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 29 21:59:21 volumio-rpi systemd[1]: Started Music Player Daemon. Jun 29 21:59:21 volumio-rpi sudo[2732]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:21 volumio-rpi volumio[972]: error: updateQueue error: null Jun 29 21:59:21 volumio-rpi volumio[972]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Jun 29 21:59:21 volumio-rpi kernel: i2c-bcm2835 3f804000.i2c: i2c transfer timed out Jun 29 21:59:21 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 Jun 29 21:59:21 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -5 Jun 29 21:59:21 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:21 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:21 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:21 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:21 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:22 volumio-rpi volumio[972]: info: VolumeController::SetAlsaVolume45 Jun 29 21:59:22 volumio-rpi volumio[972]: info: CoreStateMachine::pushState Jun 29 21:59:22 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:22 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 21:59:22 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioPushState Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:22 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:22 volumio-rpi volumio[972]: info: [ASDebug] CurState: stop PrevState: stop Jun 29 21:59:22 volumio-rpi volumio[972]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Jun 29 21:59:23 volumio-rpi volumio[972]: info: VolumeController::SetAlsaVolume40 Jun 29 21:59:23 volumio-rpi volumio[972]: info: CoreStateMachine::pushState Jun 29 21:59:23 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:23 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 21:59:23 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioPushState Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:23 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:23 volumio-rpi volumio[972]: info: [ASDebug] CurState: stop PrevState: stop Jun 29 21:59:23 volumio-rpi volumio[972]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Jun 29 21:59:24 volumio-rpi volumio[972]: info: VolumeController::SetAlsaVolume31 Jun 29 21:59:24 volumio-rpi volumio[972]: info: CoreStateMachine::pushState Jun 29 21:59:24 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:24 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 21:59:24 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioPushState Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 21:59:24 volumio-rpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 21:59:24 volumio-rpi volumio[972]: info: [ASDebug] CurState: stop PrevState: stop Jun 29 21:59:24 volumio-rpi volumio[972]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 21:59:45 volumio-rpi volumio[972]: info: Discovery: Getting this device information Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetState Jun 29 21:59:45 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 29 21:59:45 volumio-rpi sudo[2798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 29 21:59:45 volumio-rpi sudo[2798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:45 volumio-rpi sudo[2803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 29 21:59:45 volumio-rpi sudo[2803]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:45 volumio-rpi sudo[2798]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:45 volumio-rpi sudo[2803]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:45 volumio-rpi sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 29 21:59:45 volumio-rpi sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:45 volumio-rpi sudo[2815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 29 21:59:45 volumio-rpi sudo[2815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:45 volumio-rpi sudo[2815]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:45 volumio-rpi sudo[2811]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:45 volumio-rpi sudo[2824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 29 21:59:45 volumio-rpi sudo[2824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 29 21:59:45 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 29 21:59:45 volumio-rpi sudo[2824]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:45 volumio-rpi sudo[2826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 29 21:59:45 volumio-rpi sudo[2826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:45 volumio-rpi sudo[2826]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:45 volumio-rpi sudo[2830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 29 21:59:45 volumio-rpi sudo[2830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:46 volumio-rpi volumio[972]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 29 21:59:46 volumio-rpi volumio[972]: info: Retrying Login Due to Network Error Jun 29 21:59:47 volumio-rpi sudo[2830]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:55 volumio-rpi volumio[972]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Jun 29 21:59:55 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Jun 29 21:59:56 volumio-rpi sudo[2895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Jun 29 21:59:56 volumio-rpi sudo[2895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:56 volumio-rpi sudo[2895]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:56 volumio-rpi volumio[972]: info: Permissions for /etc/hostapd/hostapd.conf Jun 29 21:59:56 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 29 21:59:56 volumio-rpi volumio[972]: info: Discovery: Restarting Advertising due to device name change Jun 29 21:59:56 volumio-rpi sudo[2898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 29 21:59:56 volumio-rpi sudo[2898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:56 volumio-rpi systemd[1]: wireless.service: Killing process 875 (wpa_supplicant) with signal SIGKILL. Jun 29 21:59:56 volumio-rpi systemd[1]: wireless.service: Killing process 893 (dhcpcd) with signal SIGKILL. Jun 29 21:59:56 volumio-rpi systemd[1]: Stopping Wireless Services... Jun 29 21:59:56 volumio-rpi volumio[972]: info: MyVolumio login type: Token Jun 29 21:59:56 volumio-rpi systemd[1]: wireless.service: Succeeded. Jun 29 21:59:56 volumio-rpi systemd[1]: Stopped Wireless Services. Jun 29 21:59:56 volumio-rpi systemd[1]: Starting Wireless Services... Jun 29 21:59:57 volumio-rpi volumio[972]: info: Discovery: A device disappeared from network Jun 29 21:59:57 volumio-rpi volumio[972]: info: Discovery: Device volumio-rpi disappeared from network Jun 29 21:59:57 volumio-rpi wireless.js[2900]: Cleaning previous... Jun 29 21:59:57 volumio-rpi sudo[2914]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 29 21:59:57 volumio-rpi sudo[2914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:58 volumio-rpi avahi-daemon[643]: Withdrawing address record for 192.168.1.20 on wlan0. Jun 29 21:59:58 volumio-rpi avahi-daemon[643]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.20. Jun 29 21:59:58 volumio-rpi avahi-daemon[643]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 29 21:59:58 volumio-rpi sudo[2914]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:58 volumio-rpi sudo[2916]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 29 21:59:58 volumio-rpi sudo[2916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:58 volumio-rpi sudo[2916]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:58 volumio-rpi wireless.js[2900]: Stopped aP Jun 29 21:59:58 volumio-rpi sudo[2924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 29 21:59:58 volumio-rpi sudo[2924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:58 volumio-rpi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 29 21:59:58 volumio-rpi sudo[2924]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:58 volumio-rpi sudo[2926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 29 21:59:58 volumio-rpi sudo[2926]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:58 volumio-rpi sudo[2926]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:58 volumio-rpi sudo[2933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 29 21:59:58 volumio-rpi sudo[2933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:58 volumio-rpi sudo[2933]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:58 volumio-rpi sudo[2935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 29 21:59:58 volumio-rpi sudo[2935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:59 volumio-rpi sudo[2935]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:59 volumio-rpi wireless.js[2900]: SETTING APPROPRIATE REG DOMAIN: CN Jun 29 21:59:59 volumio-rpi sudo[2940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 29 21:59:59 volumio-rpi sudo[2940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:59 volumio-rpi sudo[2940]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:59 volumio-rpi sudo[2942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Jun 29 21:59:59 volumio-rpi sudo[2942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:59 volumio-rpi sudo[2942]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:59 volumio-rpi wireless.js[2900]: SUCCESSFULLY SET NEW REGDOMAIN: CN Jun 29 21:59:59 volumio-rpi wireless.js[2900]: Start wireless flow Jun 29 21:59:59 volumio-rpi wireless.js[2900]: Stopped hotspot (if there).. Jun 29 21:59:59 volumio-rpi sudo[2947]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 29 21:59:59 volumio-rpi sudo[2947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 21:59:59 volumio-rpi sudo[2947]: pam_unix(sudo:session): session closed for user root Jun 29 21:59:59 volumio-rpi sudo[2949]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 29 21:59:59 volumio-rpi sudo[2949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:00 volumio-rpi sudo[2949]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:00 volumio-rpi wireless.js[2900]: DHCP IP Jun 29 22:00:00 volumio-rpi wireless.js[2900]: Start ap Jun 29 22:00:00 volumio-rpi sudo[2952]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 29 22:00:00 volumio-rpi sudo[2952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:00 volumio-rpi dhcpcd[2953]: main: control_open: Connection refused Jun 29 22:00:00 volumio-rpi wpa_supplicant[2951]: Successfully initialized wpa_supplicant Jun 29 22:00:00 volumio-rpi dhcpcd[2953]: dev: loaded udev Jun 29 22:00:00 volumio-rpi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 29 22:00:00 volumio-rpi dhcpcd-run-hooks[2978]: wlan0: starting wpa_supplicant Jun 29 22:00:00 volumio-rpi dhcpcd-run-hooks[2983]: wlan0: failed to start wpa_supplicant Jun 29 22:00:00 volumio-rpi dhcpcd-run-hooks[2984]: 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 Jun 29 22:00:00 volumio-rpi dhcpcd[2953]: wlan0: connected to Access Point `' Jun 29 22:00:00 volumio-rpi dhcpcd[2953]: no interfaces have a carrier Jun 29 22:00:00 volumio-rpi dhcpcd[3002]: eth0: waiting for carrier Jun 29 22:00:00 volumio-rpi dhcpcd[3002]: wlan0: waiting for carrier Jun 29 22:00:00 volumio-rpi dhcpcd[2953]: forked to background, child pid 3002 Jun 29 22:00:00 volumio-rpi sudo[2952]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:01 volumio-rpi volumio[972]: info: Volumio Network Manager: Network status updated: 0 Jun 29 22:00:01 volumio-rpi volumio[972]: info: Discovery: Started advertising with name: Volumio-rpi Jun 29 22:00:01 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:01 volumio-rpi sudo[3033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:01 volumio-rpi sudo[3033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:01 volumio-rpi sudo[3033]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:02 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:02 volumio-rpi sudo[3039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:02 volumio-rpi sudo[3039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:02 volumio-rpi sudo[3039]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:03 volumio-rpi ntpd[780]: Deleting interface #3 wlan0, 192.168.1.20#123, interface stats: received=86, sent=94, dropped=1, active_time=239 secs Jun 29 22:00:03 volumio-rpi ntpd[780]: 162.159.200.1 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi ntpd[780]: 193.182.111.142 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi ntpd[780]: 202.118.1.130 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi ntpd[780]: 193.182.111.12 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi ntpd[780]: 95.111.202.5 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi ntpd[780]: 5.79.108.34 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi ntpd[780]: 162.159.200.123 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi ntpd[780]: 193.182.111.141 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi ntpd[780]: 117.80.112.205 local addr 192.168.1.20 -> Jun 29 22:00:03 volumio-rpi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 29 22:00:03 volumio-rpi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 29 22:00:03 volumio-rpi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 29 22:00:03 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:03 volumio-rpi sudo[3042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:03 volumio-rpi sudo[3042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:03 volumio-rpi sudo[3042]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:03 volumio-rpi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 29 22:00:03 volumio-rpi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Jun 29 22:00:03 volumio-rpi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Jun 29 22:00:03 volumio-rpi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Jun 29 22:00:03 volumio-rpi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Jun 29 22:00:04 volumio-rpi wpa_supplicant[2982]: wlan0: Trying to associate with SSID 'ZTE-3933' Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: carrier acquired Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: connected to Access Point `ZTE-3933' Jun 29 22:00:04 volumio-rpi wpa_supplicant[2982]: wlan0: Associated with 80:2d:1a:5c:d8:4b Jun 29 22:00:04 volumio-rpi wpa_supplicant[2982]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:2d:1a:5c:d8:4b completed [id=0 id_str=] Jun 29 22:00:04 volumio-rpi wpa_supplicant[2982]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 29 22:00:04 volumio-rpi wpa_supplicant[2982]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: DUID 00:01:00:01:2f:9c:ac:67:d8:3a:dd:48:97:6b Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: IAID eb:0b:3d:50 Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: adding address fe80::1964:d220:5747:fc50 Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: ipv6_addaddr1: Permission denied Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: carrier lost Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: carrier acquired Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: IAID eb:0b:3d:50 Jun 29 22:00:04 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:04 volumio-rpi sudo[3064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:04 volumio-rpi sudo[3064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:04 volumio-rpi sudo[3064]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: rebinding lease of 192.168.1.20 Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: probing address 192.168.1.20/24 Jun 29 22:00:04 volumio-rpi dhcpcd[3002]: wlan0: soliciting an IPv6 router Jun 29 22:00:05 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:05 volumio-rpi sudo[3067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:05 volumio-rpi sudo[3067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:05 volumio-rpi sudo[3067]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:06 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:06 volumio-rpi sudo[3073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:06 volumio-rpi sudo[3073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:06 volumio-rpi sudo[3073]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:07 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:07 volumio-rpi sudo[3076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:07 volumio-rpi sudo[3076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:07 volumio-rpi sudo[3076]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:08 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:08 volumio-rpi sudo[3079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:08 volumio-rpi sudo[3079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:08 volumio-rpi sudo[3079]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:09 volumio-rpi dhcpcd[3002]: wlan0: leased 192.168.1.20 for 86400 seconds Jun 29 22:00:09 volumio-rpi avahi-daemon[643]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.20. Jun 29 22:00:09 volumio-rpi avahi-daemon[643]: New relevant interface wlan0.IPv4 for mDNS. Jun 29 22:00:09 volumio-rpi dhcpcd[3002]: wlan0: adding route to 192.168.1.0/24 Jun 29 22:00:09 volumio-rpi avahi-daemon[643]: Registering new address record for 192.168.1.20 on wlan0.IPv4. Jun 29 22:00:09 volumio-rpi dhcpcd[3002]: wlan0: adding default route via 192.168.1.1 Jun 29 22:00:09 volumio-rpi wireless.js[2900]: trying... Jun 29 22:00:09 volumio-rpi sudo[3093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 29 22:00:09 volumio-rpi sudo[3093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:09 volumio-rpi sudo[3093]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:09 volumio-rpi wireless.js[2900]: Connected to: ----ZTE-3933 Jun 29 22:00:09 volumio-rpi wireless.js[2900]: ---- Jun 29 22:00:09 volumio-rpi sudo[3105]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 29 22:00:09 volumio-rpi sudo[3105]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:09 volumio-rpi sudo[3105]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:09 volumio-rpi wireless.js[2900]: ... joined AP, wlan0 IPv4 is 192.168.1.20, ipV6 is undefined Jun 29 22:00:09 volumio-rpi wireless.js[2900]: It's done! AP Jun 29 22:00:09 volumio-rpi systemd[1]: Started Wireless Services. Jun 29 22:00:09 volumio-rpi sudo[2898]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:09 volumio-rpi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 29 22:00:10 volumio-rpi volumio[972]: info: Discovery: adding 6820a00a-30ed-4d93-af7f-63c038d8c9c0 Jun 29 22:00:10 volumio-rpi volumio[972]: info: Discovery: Found device Volumio-rpi Jun 29 22:00:10 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetState Jun 29 22:00:10 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 22:00:10 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 22:00:10 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 22:00:10 volumio-rpi volumio[972]: info: Discovery: Getting this device information Jun 29 22:00:10 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetState Jun 29 22:00:10 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 22:00:10 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 22:00:10 volumio-rpi volumio[972]: verbose: New Socket.io Connection to 192.168.1.20:3000 from 192.168.1.7 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jun 29 22:00:11 volumio-rpi ntpd[780]: Listen normally on 4 wlan0 192.168.1.20:123 Jun 29 22:00:11 volumio-rpi ntpd[780]: new interface(s) found: waking up resolver Jun 29 22:00:11 volumio-rpi volumio[972]: info: Volumio Network Manager: Network status updated: 2 Jun 29 22:00:14 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 29 22:00:14 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 29 22:00:14 volumio-rpi sudo[3131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 29 22:00:14 volumio-rpi sudo[3131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:14 volumio-rpi sudo[3131]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:14 volumio-rpi sudo[3134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 29 22:00:14 volumio-rpi sudo[3134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:14 volumio-rpi sudo[3134]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:15 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 22:00:15 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 22:00:15 volumio-rpi volumio[972]: info: Discovery: Getting this device information Jun 29 22:00:15 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetState Jun 29 22:00:15 volumio-rpi volumio[972]: info: CorePlayQueue::getTrack 0 Jun 29 22:00:15 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 22:00:15 volumio-rpi ntpd[780]: Soliciting pool server 116.203.151.74 Jun 29 22:00:19 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 29 22:00:19 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 29 22:00:19 volumio-rpi volumio[972]: info: Refreshing Cached IP Addresses Jun 29 22:00:19 volumio-rpi sudo[3141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 29 22:00:19 volumio-rpi sudo[3141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:19 volumio-rpi sudo[3141]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:19 volumio-rpi sudo[3143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 29 22:00:19 volumio-rpi sudo[3143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:00:19 volumio-rpi sudo[3143]: pam_unix(sudo:session): session closed for user root Jun 29 22:00:22 volumio-rpi ntpd[780]: Soliciting pool server 2001:67c:21bc:1e::123 Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:00:25 volumio-rpi volumio[972]: info: Retrieving Cloud Streaming UI Jun 29 22:00:25 volumio-rpi volumio[972]: info: Getting Tidal Cloud Configuration Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:00:25 volumio-rpi volumio[972]: info: Getting Qobuz Cloud Configuration Jun 29 22:00:25 volumio-rpi volumio[972]: info: Asking plugin for UI Config Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:00:25 volumio-rpi volumio[972]: info: Getting Spotify Cloud Configuration Jun 29 22:00:25 volumio-rpi volumio[972]: info: Asking plugin for UI Config Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:00:25 volumio-rpi volumio[972]: info: Saving Spotify Acccount Jun 29 22:00:25 volumio-rpi volumio[972]: info: Got Tidal Cloud Configuration Jun 29 22:00:25 volumio-rpi volumio[972]: info: Got it Jun 29 22:00:25 volumio-rpi volumio[972]: info: Got it Jun 29 22:00:25 volumio-rpi volumio[972]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:00:25 volumio-rpi volumio[972]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetBrowseSources Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetBrowseSources Jun 29 22:00:25 volumio-rpi volumio[972]: info: CoreCommandRouter::volumioGetBrowseSources Jun 29 22:00:26 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 29 22:00:26 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 29 22:00:26 volumio-rpi volumio[972]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 29 22:00:26 volumio-rpi volumio[972]: info: Retrying Login Due to Network Error Jun 29 22:00:30 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 29 22:00:31 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 29 22:00:31 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 29 22:00:33 volumio-rpi volumio[972]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L ARS2-2.local. Jun 29 22:00:33 volumio-rpi volumio[972]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L qnap-yaoming.local. Jun 29 22:00:33 volumio-rpi volumio[972]: info: Error browsing device qnap-yaoming for shares: Command failed: smbclient --no-pass --debuglevel=0 -L qnap-yaoming.local. Jun 29 22:00:33 volumio-rpi volumio[972]: Unable to initialize messaging context Jun 29 22:00:34 volumio-rpi volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 29 22:00:36 volumio-rpi volumio[972]: info: MyVolumio login type: Token Jun 29 22:00:36 volumio-rpi volumio[972]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 22:00:36 volumio-rpi volumio[972]: Error: dns service error: unknown Jun 29 22:00:36 volumio-rpi volumio[972]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jun 29 22:00:36 volumio-rpi volumio[972]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 29 22:00:36 volumio-rpi volumio[972]: errorCode: -65537 Jun 29 22:00:36 volumio-rpi volumio[972]: } Jun 29 22:00:36 volumio-rpi volumio[972]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 22:00:37 volumio-rpi sudo[3201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-29 21:59 Jun 29 22:00:37 volumio-rpi sudo[3201]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"