Jun 12 15:11:04 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 12 15:11:04 pikus volumio[938]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 12 15:11:04 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 12 15:11:04 pikus volumio[938]: info: Received Get System Version Jun 12 15:11:04 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 15:11:04 pikus volumio[938]: info: Received Get System Info Jun 12 15:11:04 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 15:11:04 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 15:11:04 pikus volumio[938]: info: Discovery: Getting this device information Jun 12 15:11:04 pikus volumio[938]: info: CoreCommandRouter::volumioGetState Jun 12 15:11:04 pikus volumio[938]: info: CorePlayQueue::getTrack 0 Jun 12 15:11:04 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 15:11:08 pikus volumio[938]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::volumioGetState Jun 12 15:11:08 pikus volumio[938]: info: CorePlayQueue::getTrack 0 Jun 12 15:11:08 pikus volumio[938]: info: Restoring Previous Volume level: 86 false true Jun 12 15:11:08 pikus volumio[938]: info: VolumeController::SetAlsaVolume100 Jun 12 15:11:08 pikus volumio[938]: info: Enable softmixer device for audio device number 1 Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::volumioStop Jun 12 15:11:08 pikus volumio[938]: info: CoreStateMachine::stop Jun 12 15:11:08 pikus volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 15:11:08 pikus volumio[938]: info: Enable softmixer device for audio device undefined Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 12 15:11:08 pikus volumio[938]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 12 15:11:08 pikus volumio[938]: info: Disabling external Volume Control Jun 12 15:11:08 pikus volumio[938]: info: Output device has changed, restarting MPD Jun 12 15:11:08 pikus volumio[938]: info: Output device has changed, restarting Shairport Sync Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 15:11:08 pikus sudo[2290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 15:11:08 pikus sudo[2290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:08 pikus sudo[2292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 15:11:08 pikus sudo[2290]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:08 pikus sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:08 pikus volumio[938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 15:11:08 pikus volumio[938]: info: Volume configurations have been set Jun 12 15:11:08 pikus systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 12 15:11:08 pikus volumio[938]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 12 15:11:08 pikus volumio[938]: info: Disabling external Volume Control Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 15:11:08 pikus volumio[938]: info: CoreStateMachine::pushState Jun 12 15:11:08 pikus volumio[938]: info: CorePlayQueue::getTrack 0 Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::volumioPushState Jun 12 15:11:08 pikus volumio[938]: info: CoreStateMachine::pushState Jun 12 15:11:08 pikus volumio[938]: info: CorePlayQueue::getTrack 0 Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::volumioPushState Jun 12 15:11:08 pikus volumio[938]: info: MPD Permissions set Jun 12 15:11:08 pikus volumio[938]: info: Software Volume ALSA configuration written Jun 12 15:11:08 pikus volumio[938]: info: Preparing to generate the ALSA configuration file Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 15:11:08 pikus volumio[938]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 12 15:11:08 pikus volumio[938]: info: Reading ALSA contributions from plugins. Jun 12 15:11:08 pikus volumio[938]: info: VolumeController:: Volume=100 Mute =false Jun 12 15:11:08 pikus volumio[938]: info: CoreStateMachine::pushState Jun 12 15:11:08 pikus volumio[938]: info: CorePlayQueue::getTrack 0 Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::volumioPushState Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:08 pikus volumio[938]: info: Starting Shairport Sync Jun 12 15:11:08 pikus volumio[938]: info: Asound.conf file written Jun 12 15:11:09 pikus sudo[2330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 15:11:09 pikus sudo[2333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 12 15:11:09 pikus sudo[2330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:09 pikus sudo[2333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:09 pikus sudo[2333]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:09 pikus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 12 15:11:09 pikus systemd[1]: shairport-sync.service: Deactivated successfully. Jun 12 15:11:09 pikus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 12 15:11:09 pikus systemd[1]: shairport-sync.service: Consumed 3.804s CPU time. Jun 12 15:11:09 pikus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 12 15:11:09 pikus sudo[2330]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:09 pikus volumio[938]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 12 15:11:09 pikus volumio[938]: No state is present for card b1 Jun 12 15:11:09 pikus volumio[938]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 12 15:11:09 pikus volumio[938]: /usr/share/alsa/init/bcm2835:14: control element not found Jun 12 15:11:09 pikus volumio[938]: No state is present for card b1 Jun 12 15:11:09 pikus volumio[938]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 12 15:11:09 pikus volumio[938]: No state is present for card Headphones Jun 12 15:11:09 pikus volumio[938]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 12 15:11:09 pikus volumio[938]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 12 15:11:09 pikus volumio[938]: No state is present for card Headphones Jun 12 15:11:09 pikus volumio[938]: info: Output device has changed, restarting MPD Jun 12 15:11:09 pikus volumio[938]: info: Output device has changed, restarting Shairport Sync Jun 12 15:11:09 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:09 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 15:11:09 pikus sudo[2356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 15:11:09 pikus sudo[2356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:09 pikus sudo[2358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 15:11:09 pikus sudo[2358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:09 pikus sudo[2356]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:09 pikus volumio[938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 15:11:11 pikus volumio[938]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 12 15:11:11 pikus volumio[938]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 12 15:11:11 pikus volumio[938]: No state is present for card b1 Jun 12 15:11:11 pikus volumio[938]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 12 15:11:11 pikus volumio[938]: /usr/share/alsa/init/bcm2835:14: control element not found Jun 12 15:11:11 pikus volumio[938]: No state is present for card b1 Jun 12 15:11:11 pikus volumio[938]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 12 15:11:11 pikus volumio[938]: No state is present for card Headphones Jun 12 15:11:11 pikus volumio[938]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 12 15:11:11 pikus volumio[938]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 12 15:11:11 pikus volumio[938]: No state is present for card Headphones Jun 12 15:11:11 pikus volumio[938]: info: Output device has changed, restarting MPD Jun 12 15:11:11 pikus volumio[938]: info: Output device has changed, restarting Shairport Sync Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 15:11:11 pikus sudo[2387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 15:11:11 pikus sudo[2387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:11 pikus sudo[2387]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:11 pikus sudo[2389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 15:11:11 pikus sudo[2389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:11 pikus volumio[938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 12 15:11:11 pikus volumio[938]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 12 15:11:11 pikus volumio[938]: info: Disabling external Volume Control Jun 12 15:11:11 pikus volumio[938]: info: MPD Permissions set Jun 12 15:11:11 pikus volumio[938]: info: MPD Permissions set Jun 12 15:11:11 pikus volumio[938]: info: Shairport-Sync Started Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 15:11:11 pikus volumio[938]: info: CoreStateMachine::pushState Jun 12 15:11:11 pikus volumio[938]: info: CorePlayQueue::getTrack 0 Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 15:11:11 pikus volumio[938]: info: CoreCommandRouter::volumioPushState Jun 12 15:11:11 pikus volumio[938]: info: Starting Shairport Sync Jun 12 15:11:11 pikus volumio[938]: info: Starting Shairport Sync Jun 12 15:11:11 pikus sudo[2417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 15:11:11 pikus sudo[2417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:11 pikus sudo[2419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 15:11:11 pikus sudo[2419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:11 pikus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 12 15:11:11 pikus systemd[1]: shairport-sync.service: Deactivated successfully. Jun 12 15:11:11 pikus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 12 15:11:11 pikus systemd[1]: shairport-sync.service: Consumed 1.188s CPU time. Jun 12 15:11:11 pikus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 12 15:11:11 pikus sudo[2417]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:11 pikus sudo[2419]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:11 pikus volumio[938]: info: Shairport-Sync Started Jun 12 15:11:11 pikus volumio[938]: info: Shairport-Sync Started Jun 12 15:11:11 pikus systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 12 15:11:12 pikus systemd[1]: mpd.service: Deactivated successfully. Jun 12 15:11:12 pikus systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 12 15:11:12 pikus systemd[1]: mpd.service: Consumed 8.606s CPU time. Jun 12 15:11:12 pikus systemd[1]: mpd.socket: Deactivated successfully. Jun 12 15:11:12 pikus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 12 15:11:12 pikus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 12 15:11:12 pikus volumio[938]: info: VolumeController::SetAlsaVolume86 Jun 12 15:11:12 pikus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 12 15:11:12 pikus systemd[1]: Starting mpd.service - Music Player Daemon... Jun 12 15:11:12 pikus volumio[938]: info: CoreStateMachine::pushState Jun 12 15:11:12 pikus volumio[938]: info: CorePlayQueue::getTrack 0 Jun 12 15:11:12 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 15:11:12 pikus volumio[938]: info: CoreCommandRouter::volumioPushState Jun 12 15:11:12 pikus sudo[2441]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 15:11:12 pikus sudo[2441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:11:12 pikus sudo[2441]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:12 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 12 15:11:12 pikus sudo[2445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 15:11:12 pikus sudo[2445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:13 pikus systemd[1]: mpd.service: Deactivated successfully. Jun 12 15:11:13 pikus systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 12 15:11:13 pikus systemd[1]: mpd.socket: Deactivated successfully. Jun 12 15:11:13 pikus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 12 15:11:13 pikus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 12 15:11:13 pikus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 12 15:11:13 pikus systemd[1]: Starting mpd.service - Music Player Daemon... Jun 12 15:11:13 pikus sudo[2448]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 15:11:13 pikus sudo[2448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:11:13 pikus sudo[2448]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus mpd[2450]: 2025-06-12T15:11:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 15:11:36 pikus systemd[1]: Started mpd.service - Music Player Daemon. Jun 12 15:11:36 pikus sudo[2358]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus sudo[2292]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus sudo[2445]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus volumio[938]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 15:11:36 pikus sudo[2389]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus volumio[938]: error: MPD error: The expression evaluated to a falsy value: Jun 12 15:11:36 pikus volumio[938]: assert.ok(self.idling) Jun 12 15:11:36 pikus volumio[938]: error: The expression evaluated to a falsy value: Jun 12 15:11:36 pikus volumio[938]: assert.ok(self.idling) Jun 12 15:11:36 pikus volumio[938]: error: MPD error: The expression evaluated to a falsy value: Jun 12 15:11:36 pikus volumio[938]: assert.ok(self.idling) Jun 12 15:11:36 pikus volumio[938]: error: The expression evaluated to a falsy value: Jun 12 15:11:36 pikus volumio[938]: assert.ok(self.idling) Jun 12 15:11:36 pikus volumio[938]: error: MPD error: The expression evaluated to a falsy value: Jun 12 15:11:36 pikus volumio[938]: assert.ok(self.idling) Jun 12 15:11:36 pikus volumio[938]: error: The expression evaluated to a falsy value: Jun 12 15:11:36 pikus volumio[938]: assert.ok(self.idling) Jun 12 15:11:36 pikus volumio[938]: error: updateQueue error: null Jun 12 15:11:36 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 15:11:36 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 12 15:11:36 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 12 15:11:36 pikus sudo[2495]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 15:11:36 pikus sudo[2495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:36 pikus sudo[2488]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 15:11:36 pikus sudo[2488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:36 pikus sudo[2479]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 15:11:36 pikus sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:36 pikus sudo[2484]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 15:11:36 pikus sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:36 pikus sudo[2497]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 15:11:36 pikus sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:36 pikus sudo[2497]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 12 15:11:36 pikus sudo[2475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 12 15:11:36 pikus sudo[2475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:36 pikus sudo[2484]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus sudo[2488]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus sudo[2479]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus sudo[2505]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 12 15:11:36 pikus sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:36 pikus sudo[2475]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:36 pikus sudo[2495]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:37 pikus sudo[2505]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:45 pikus volumio[938]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Jun 12 15:11:45 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Jun 12 15:11:45 pikus systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 12 15:11:45 pikus dbus-daemon[470]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.38' (uid=0 pid=2527 comm="timedatectl show --property=NTPSynchronized --valu") Jun 12 15:11:45 pikus systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 12 15:11:46 pikus dbus-daemon[470]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 12 15:11:46 pikus systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 12 15:11:46 pikus setdatetime-helper.sh[2526]: Time is not synchronized. Attempting to sync... Jun 12 15:11:46 pikus sudo[2536]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 12 Jun 2025 13:11:46 GMT#015' Jun 12 15:11:46 pikus sudo[2536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:11:46 pikus setdatetime-helper.sh[2537]: Thu Jun 12 15:11:46 CEST 2025 Jun 12 15:11:46 pikus systemd-journald[301]: Time jumped backwards, rotating. Jun 12 15:11:46 pikus systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 12 15:11:46 pikus setdatetime-helper.sh[2526]: Time synchronized successfully. Jun 12 15:11:46 pikus sudo[2536]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:46 pikus systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 12 15:11:51 pikus sudo[2544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Jun 12 15:11:51 pikus sudo[2544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:51 pikus sudo[2544]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:51 pikus volumio[938]: info: Permissions for /etc/hostapd/hostapd.conf Jun 12 15:11:51 pikus volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 12 15:11:51 pikus volumio[938]: info: Discovery: Restarting Advertising due to device name change Jun 12 15:11:51 pikus sudo[2557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 12 15:11:51 pikus sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:51 pikus systemd[1]: Stopping wireless.service - Wireless Services... Jun 12 15:11:51 pikus wpa_supplicant[858]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 12 15:11:51 pikus wpa_supplicant[858]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 12 15:11:51 pikus wpa_supplicant[858]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Jun 12 15:11:51 pikus wpa_supplicant[858]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Jun 12 15:11:51 pikus wpa_supplicant[858]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:08:f6:f5:60:f1 reason=3 locally_generated=1 Jun 12 15:11:51 pikus wpa_supplicant[858]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 12 15:11:52 pikus dhcpcd[543]: wlan0: carrier lost - roaming Jun 12 15:11:52 pikus dhcpcd[543]: wlan0: changing route to 10.0.2.0/24 Jun 12 15:11:52 pikus dhcpcd[543]: wlan0: changing default route via 10.0.2.1 Jun 12 15:11:52 pikus wpa_supplicant[858]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 12 15:11:52 pikus wpa_supplicant[858]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jun 12 15:11:52 pikus dhcpcd[543]: wlan0: carrier acquired Jun 12 15:11:52 pikus wpa_supplicant[858]: wlan0: CTRL-EVENT-TERMINATING Jun 12 15:11:53 pikus avahi-daemon[932]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 12 15:11:53 pikus avahi-daemon[932]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.2.120. Jun 12 15:11:53 pikus avahi-daemon[932]: Withdrawing address record for 10.0.2.120 on wlan0. Jun 12 15:11:53 pikus dhcpcd[543]: wlan0: IAID 01:8f:8b:ce Jun 12 15:11:53 pikus dhcpcd[543]: wlan0: carrier lost Jun 12 15:11:53 pikus volumio[938]: info: Discovery: A device disappeared from network Jun 12 15:11:53 pikus volumio[938]: info: Discovery: Device pikus disappeared from network Jun 12 15:11:53 pikus dhcpcd[543]: wlan0: deleting route to 10.0.2.0/24 Jun 12 15:11:53 pikus dhcpcd[543]: wlan0: deleting default route via 10.0.2.1 Jun 12 15:11:53 pikus volumio[938]: info: Discovery: A device disappeared from network Jun 12 15:11:54 pikus ntpd[714]: IO: Deleting interface #3 wlan0, 10.0.2.120#123, interface stats: received=256, sent=256, dropped=0, active_time=526 secs Jun 12 15:11:54 pikus ntpd[714]: PROTO: 89.161.47.136 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 89.161.47.131 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 212.127.95.218 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 193.59.134.156 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 213.222.217.10 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 46.175.224.7 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 162.159.200.123 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 54.37.233.160 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 193.25.222.136 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 89.250.197.242 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 156.17.20.254 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 162.159.200.1 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 178.215.228.24 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 193.70.94.182 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 91.212.242.21 unlink local addr 10.0.2.120 -> Jun 12 15:11:54 pikus ntpd[714]: PROTO: 51.68.141.5 unlink local addr 10.0.2.120 -> Jun 12 15:11:55 pikus systemd[1]: wireless.service: Deactivated successfully. Jun 12 15:11:55 pikus systemd[1]: Stopped wireless.service - Wireless Services. Jun 12 15:11:55 pikus systemd[1]: wireless.service: Consumed 48.924s CPU time. Jun 12 15:11:55 pikus systemd[1]: Starting wireless.service - Wireless Services... Jun 12 15:11:55 pikus systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 12 15:11:55 pikus systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 12 15:11:55 pikus systemd[1]: welcome.service: Deactivated successfully. Jun 12 15:11:55 pikus systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 12 15:11:55 pikus systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 12 15:11:55 pikus systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 12 15:11:55 pikus welcome[2602]: Resolved ip:[0] Jun 12 15:11:55 pikus systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 12 15:11:55 pikus systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 12 15:11:56 pikus volumio[938]: info: Discovery: Started advertising with name: pikus Jun 12 15:11:57 pikus volumio[938]: info: Discovery: adding 1c3ef993-6882-4f26-ac0c-9604c7d39887 Jun 12 15:11:57 pikus volumio[938]: info: Discovery: Found device pikus Jun 12 15:11:57 pikus volumio[938]: info: CoreCommandRouter::volumioGetState Jun 12 15:11:57 pikus volumio[938]: info: CorePlayQueue::getTrack 0 Jun 12 15:11:58 pikus sudo[2626]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 12 15:11:58 pikus sudo[2626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:11:58 pikus sudo[2626]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:58 pikus sudo[2628]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 12 15:11:58 pikus sudo[2628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:11:58 pikus sudo[2628]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:59 pikus sudo[2636]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 12 15:11:59 pikus sudo[2636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:59 pikus sudo[2636]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:59 pikus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 12 15:11:59 pikus sudo[2638]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 12 15:11:59 pikus sudo[2638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:59 pikus sudo[2638]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:59 pikus sudo[2646]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 12 15:11:59 pikus sudo[2646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:59 pikus sudo[2646]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:59 pikus sudo[2648]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 12 15:11:59 pikus sudo[2648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:59 pikus sudo[2648]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:59 pikus sudo[2654]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 12 15:11:59 pikus sudo[2654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:59 pikus sudo[2654]: pam_unix(sudo:session): session closed for user root Jun 12 15:11:59 pikus sudo[2656]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set PL Jun 12 15:11:59 pikus sudo[2656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:11:59 pikus sudo[2656]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:00 pikus sudo[2666]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 12 15:12:00 pikus sudo[2666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:12:00 pikus sudo[2666]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:00 pikus sudo[2668]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 12 15:12:00 pikus sudo[2668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:12:00 pikus sudo[2668]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:00 pikus sudo[2674]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 12 15:12:00 pikus sudo[2674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:12:00 pikus sudo[2674]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:00 pikus dhcpcd[543]: control_free: No such file or directory Jun 12 15:12:00 pikus wpa_supplicant[2673]: Successfully initialized wpa_supplicant Jun 12 15:12:00 pikus dhcpcd[543]: ps_ctl_dispatch: cannot handle another client Jun 12 15:12:00 pikus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 12 15:12:00 pikus wpa_supplicant[2673]: nl80211: kernel reports: Registration to specific type not supported Jun 12 15:12:01 pikus volumio[938]: info: Volumio Network Manager: Network status updated: 0 Jun 12 15:12:01 pikus wpa_supplicant[2678]: wlan0: Trying to associate with 3c:08:f6:f5:60:f1 (SSID='AI-404' freq=2412 MHz) Jun 12 15:12:01 pikus wpa_supplicant[2678]: wlan0: Associated with 3c:08:f6:f5:60:f1 Jun 12 15:12:01 pikus wpa_supplicant[2678]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 12 15:12:01 pikus wpa_supplicant[2678]: wlan0: WPA: Key negotiation completed with 3c:08:f6:f5:60:f1 [PTK=CCMP GTK=TKIP] Jun 12 15:12:01 pikus wpa_supplicant[2678]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:08:f6:f5:60:f1 completed [id=0 id_str=] Jun 12 15:12:01 pikus dhcpcd[543]: wlan0: carrier acquired Jun 12 15:12:01 pikus dhcpcd[543]: wlan0: IAID 01:8f:8b:ce Jun 12 15:12:01 pikus sudo[2696]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 15:12:01 pikus sudo[2696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:01 pikus sudo[2696]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:02 pikus dhcpcd[543]: wlan0: soliciting an IPv6 router Jun 12 15:12:02 pikus dhcpcd[543]: wlan0: soliciting a DHCP lease Jun 12 15:12:02 pikus dhcpcd[543]: wlan0: offered 10.0.2.120 from 10.0.2.1 Jun 12 15:12:02 pikus dhcpcd[543]: wlan0: probing address 10.0.2.120/24 Jun 12 15:12:02 pikus sudo[2702]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 15:12:02 pikus sudo[2702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:02 pikus sudo[2702]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:03 pikus sudo[2706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 15:12:03 pikus sudo[2706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:03 pikus sudo[2706]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:04 pikus sudo[2710]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 15:12:04 pikus sudo[2710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:04 pikus sudo[2710]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:05 pikus sudo[2715]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 15:12:05 pikus sudo[2715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:05 pikus sudo[2715]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:06 pikus sudo[2719]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 15:12:06 pikus sudo[2719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:06 pikus sudo[2719]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:07 pikus sudo[2723]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 15:12:07 pikus sudo[2723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:07 pikus sudo[2723]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:07 pikus dhcpcd[543]: wlan0: leased 10.0.2.120 for 6660 seconds Jun 12 15:12:07 pikus avahi-daemon[932]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.2.120. Jun 12 15:12:07 pikus dhcpcd[543]: wlan0: adding route to 10.0.2.0/24 Jun 12 15:12:07 pikus dhcpcd[543]: wlan0: adding default route via 10.0.2.1 Jun 12 15:12:07 pikus avahi-daemon[932]: New relevant interface wlan0.IPv4 for mDNS. Jun 12 15:12:07 pikus avahi-daemon[932]: Registering new address record for 10.0.2.120 on wlan0.IPv4. Jun 12 15:12:07 pikus systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 12 15:12:07 pikus systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 12 15:12:07 pikus systemd[1]: welcome.service: Deactivated successfully. Jun 12 15:12:07 pikus systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 12 15:12:07 pikus systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 12 15:12:07 pikus systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 12 15:12:07 pikus welcome[2738]: Resolved ip:[1] 10.0.2.120 Jun 12 15:12:07 pikus systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 12 15:12:07 pikus systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 12 15:12:08 pikus volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 15:12:08 pikus kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:406: inode #1696184: comm node: No space for directory leaf checksum. Please run e2fsck -D. Jun 12 15:12:08 pikus kernel: EXT4-fs error (device mmcblk0p3): htree_dirblock_to_tree:1083: inode #1696184: comm node: Directory block failed checksum Jun 12 15:12:08 pikus volumio[938]: Error: UNKNOWN: unknown error, scandir '/data/plugins//music_service' Jun 12 15:12:08 pikus volumio[938]: at Object.readdirSync (node:fs:1543:3) Jun 12 15:12:08 pikus volumio[938]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1634:35) Jun 12 15:12:08 pikus volumio[938]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29) Jun 12 15:12:08 pikus volumio[938]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1342:45) Jun 12 15:12:08 pikus volumio[938]: at Socket.emit (node:events:514:28) Jun 12 15:12:08 pikus volumio[938]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 12 15:12:08 pikus volumio[938]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Jun 12 15:12:08 pikus volumio[938]: errno: -74, Jun 12 15:12:08 pikus volumio[938]: syscall: 'scandir', Jun 12 15:12:08 pikus volumio[938]: code: 'UNKNOWN', Jun 12 15:12:08 pikus volumio[938]: path: '/data/plugins//music_service' Jun 12 15:12:08 pikus volumio[938]: } Jun 12 15:12:08 pikus volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 15:12:08 pikus sudo[2764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 15:12:08 pikus sudo[2764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:08 pikus sudo[2764]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:08 pikus sudo[2767]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 15:12:08 pikus sudo[2767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 15:12:08 pikus sudo[2767]: pam_unix(sudo:session): session closed for user root Jun 12 15:12:08 pikus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 12 15:12:08 pikus systemd[1]: shairport-sync.service: Deactivated successfully. Jun 12 15:12:08 pikus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 12 15:12:08 pikus systemd[1]: shairport-sync.service: Consumed 1.904s CPU time. Jun 12 15:12:08 pikus systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 12 15:12:08 pikus avahi-daemon[932]: Got SIGTERM, quitting. Jun 12 15:12:08 pikus avahi-daemon[932]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.2.120. Jun 12 15:12:08 pikus avahi-daemon[932]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 12 15:12:08 pikus avahi-daemon[932]: avahi-daemon 0.8 exiting. Jun 12 15:12:08 pikus systemd[1]: avahi-daemon.service: Deactivated successfully. Jun 12 15:12:08 pikus systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 12 15:12:08 pikus ntpd[714]: IO: Listen normally on 4 wlan0 10.0.2.120:123 Jun 12 15:12:08 pikus ntpd[714]: IO: new interface(s) found: waking up resolver Jun 12 15:12:09 pikus systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 12 15:12:09 pikus avahi-daemon[2772]: Process 932 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jun 12 15:12:09 pikus avahi-daemon[2772]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 12 15:12:09 pikus avahi-daemon[2772]: Successfully dropped root privileges. Jun 12 15:12:09 pikus avahi-daemon[2772]: avahi-daemon 0.8 starting up. Jun 12 15:12:09 pikus systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 12 15:12:09 pikus avahi-daemon[2772]: Successfully called chroot(). Jun 12 15:12:09 pikus avahi-daemon[2772]: Successfully dropped remaining capabilities. Jun 12 15:12:09 pikus avahi-daemon[2772]: Loading service file /services/volumio.service. Jun 12 15:12:09 pikus avahi-daemon[2772]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.2.120. Jun 12 15:12:09 pikus avahi-daemon[2772]: New relevant interface wlan0.IPv4 for mDNS. Jun 12 15:12:09 pikus avahi-daemon[2772]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 12 15:12:09 pikus avahi-daemon[2772]: New relevant interface lo.IPv4 for mDNS. Jun 12 15:12:09 pikus avahi-daemon[2772]: Network interface enumeration completed. Jun 12 15:12:09 pikus avahi-daemon[2772]: Registering new address record for 10.0.2.120 on wlan0.IPv4. Jun 12 15:12:09 pikus avahi-daemon[2772]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 12 15:12:09 pikus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 12 15:12:09 pikus sudo[2779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-12 15:11' Jun 12 15:12:09 pikus sudo[2779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 15:12:09 pikus systemd[1]: Started wireless.service - Wireless Services. Jun 12 15:12:09 pikus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 12 15:12:09 pikus sudo[2557]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"