-- Logs begin at Fri 2024-11-22 16:52:58 +07, end at Fri 2024-11-22 16:55:49 +07. -- Nov 22 16:54:00 volumio volumio[1164]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 22 16:54:00 volumio volumio[1164]: info: Updating MyVolumio device info Nov 22 16:54:00 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 16:54:00 volumio kernel: snd_hda_intel 0000:00:1b.0: couldn't bind with audio component Nov 22 16:54:00 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: autoconfig for CS4206: line_outs=1 (0xb/0x0/0x0/0x0/0x0) type:speaker Nov 22 16:54:00 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Nov 22 16:54:00 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: hp_outs=1 (0xa/0x0/0x0/0x0/0x0) Nov 22 16:54:00 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: mono: mono_out=0x0 Nov 22 16:54:00 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: dig-out=0x10/0x0 Nov 22 16:54:00 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: inputs: Nov 22 16:54:00 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: Line=0xc Nov 22 16:54:00 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: dig-in=0xf Nov 22 16:54:00 volumio kernel: input: HDA Intel PCH Line as /devices/pci0000:00/0000:00:1b.0/sound/card0/input7 Nov 22 16:54:00 volumio kernel: input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input8 Nov 22 16:54:00 volumio kernel: input: HDA Intel PCH SPDIF In as /devices/pci0000:00/0000:00:1b.0/sound/card0/input9 Nov 22 16:54:00 volumio systemd-udevd[1761]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 16:54:00 volumio systemd-udevd[1760]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 16:54:00 volumio systemd-udevd[1763]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 16:54:04 volumio volumio[1164]: info: BOOT COMPLETED Nov 22 16:54:06 volumio volumio[1164]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 22 16:54:06 volumio volumio[1164]: Invalid card number. Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 22 16:54:06 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 22 16:54:13 volumio volumio[1164]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 22 16:54:13 volumio volumio[1164]: info: Preparing to save Alsa Options, stopping services first Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::volumioGetState Nov 22 16:54:13 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::volumioPause Nov 22 16:54:13 volumio volumio[1164]: info: CoreStateMachine::pause Nov 22 16:54:13 volumio volumio[1164]: info: Saving Audio Output to: {"output_device":{"value":"0,1","label":"SPDIF"}} Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 22 16:54:13 volumio volumio[1164]: info: Found match in Cards Database: setting mixer for card SPDIF Nov 22 16:54:13 volumio volumio[1164]: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,1,0) appears twice or more Nov 22 16:54:13 volumio volumio[1164]: amixer: Mixer hw:0 load error: Invalid argument Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 22 16:54:13 volumio volumio[1164]: info: Updating Volume Controller Parameters: Device: 0,1 Name: softvolume Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 22 16:54:13 volumio volumio[1164]: info: Disabling external Volume Control Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 16:54:13 volumio volumio[1164]: info: Enable softmixer device for audio device undefined Nov 22 16:54:13 volumio volumio[1164]: info: Software Volume ALSA configuration written Nov 22 16:54:13 volumio volumio[1164]: info: Preparing to generate the ALSA configuration file Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 22 16:54:13 volumio volumio[1164]: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,1,0) appears twice or more Nov 22 16:54:13 volumio volumio[1164]: amixer: Mixer hw:0 load error: Invalid argument Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 22 16:54:13 volumio volumio[1164]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 22 16:54:13 volumio volumio[1164]: info: Reading ALSA contributions from plugins. Nov 22 16:54:13 volumio volumio[1164]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,1,0) appears twice or more Nov 22 16:54:13 volumio volumio[1164]: amixer: Mixer hw:0 load error: Invalid argument Nov 22 16:54:13 volumio volumio[1164]: info: Asound.conf file written Nov 22 16:54:13 volumio sudo[1851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 22 16:54:13 volumio sudo[1851]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 16:54:13 volumio sudo[1851]: pam_unix(sudo:session): session closed for user root Nov 22 16:54:13 volumio volumio[1164]: info: Output device has changed, restarting MPD Nov 22 16:54:13 volumio sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 22 16:54:13 volumio sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 16:54:13 volumio volumio[1164]: info: Output device has changed, restarting Shairport Sync Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 16:54:13 volumio sudo[1856]: pam_unix(sudo:session): session closed for user root Nov 22 16:54:13 volumio sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 22 16:54:13 volumio sudo[1859]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 16:54:13 volumio systemd[1]: Stopping Music Player Daemon... Nov 22 16:54:13 volumio volumio[1164]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 16:54:13 volumio volumio[1164]: info: Output device has changed, restarting MPD Nov 22 16:54:13 volumio systemd[1]: mpd.service: Succeeded. Nov 22 16:54:13 volumio systemd[1]: Stopped Music Player Daemon. Nov 22 16:54:13 volumio sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 22 16:54:13 volumio sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 16:54:13 volumio volumio[1164]: info: Output device has changed, restarting Shairport Sync Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 16:54:13 volumio sudo[1866]: pam_unix(sudo:session): session closed for user root Nov 22 16:54:13 volumio systemd[1]: Starting Music Player Daemon... Nov 22 16:54:13 volumio sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 22 16:54:13 volumio sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 16:54:13 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 22 16:54:13 volumio systemd[1]: mpd.service: Succeeded. Nov 22 16:54:13 volumio systemd[1]: Stopped Music Player Daemon. Nov 22 16:54:13 volumio volumio[1164]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 16:54:13 volumio systemd[1]: Starting Music Player Daemon... Nov 22 16:54:13 volumio volumio[1164]: info: MPD Permissions set Nov 22 16:54:13 volumio volumio[1164]: info: MPD Permissions set Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::volumioGetState Nov 22 16:54:13 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:13 volumio volumio[1164]: info: VolumeController:: Volume=undefined Mute =false Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::volumioGetState Nov 22 16:54:13 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:13 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:13 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 22 16:54:13 volumio sudo[1874]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 22 16:54:13 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 16:54:13 volumio sudo[1874]: pam_unix(sudo:session): session closed for user root Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 22 16:54:13 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 16:54:13 volumio volumio[1164]: info: Starting Shairport Sync Nov 22 16:54:13 volumio volumio[1164]: info: Starting Shairport Sync Nov 22 16:54:13 volumio sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 16:54:13 volumio sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 16:54:13 volumio sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 16:54:13 volumio sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 16:54:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 22 16:54:13 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 22 16:54:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 22 16:54:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 22 16:54:13 volumio sudo[1890]: pam_unix(sudo:session): session closed for user root Nov 22 16:54:13 volumio volumio[1164]: info: Shairport-Sync Started Nov 22 16:54:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 22 16:54:13 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 22 16:54:13 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 22 16:54:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 22 16:54:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 22 16:54:13 volumio sudo[1892]: pam_unix(sudo:session): session closed for user root Nov 22 16:54:13 volumio volumio[1164]: info: Shairport-Sync Started Nov 22 16:54:13 volumio mpd[1881]: Nov 22 16:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 22 16:54:13 volumio systemd[1]: Started Music Player Daemon. Nov 22 16:54:13 volumio sudo[1869]: pam_unix(sudo:session): session closed for user root Nov 22 16:54:13 volumio sudo[1859]: pam_unix(sudo:session): session closed for user root Nov 22 16:54:13 volumio volumio[1164]: error: MPD error: The expression evaluated to a falsy value: Nov 22 16:54:13 volumio volumio[1164]: assert.ok(self.idling) Nov 22 16:54:13 volumio volumio[1164]: error: The expression evaluated to a falsy value: Nov 22 16:54:13 volumio volumio[1164]: assert.ok(self.idling) Nov 22 16:54:13 volumio volumio[1164]: error: updateQueue error: null Nov 22 16:54:17 volumio volumio[1164]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 22 16:54:17 volumio volumio[1164]: info: CoreStateMachine::getcurrentVolume Nov 22 16:54:17 volumio volumio[1164]: info: CoreCommandRouter::volumioRetrievevolume Nov 22 16:54:17 volumio volumio[1164]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,1,0) appears twice or more Nov 22 16:54:17 volumio volumio[1164]: amixer: Mixer hw:0 load error: Invalid argument Nov 22 16:54:17 volumio volumio[1164]: info: VolumeController:: Volume=undefined Mute =false Nov 22 16:54:17 volumio volumio[1164]: info: CoreCommandRouter::volumioGetState Nov 22 16:54:17 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:17 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:17 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:17 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:17 volumio volumio[1164]: info: CoreStateMachine::updateTrackBlock Nov 22 16:54:17 volumio volumio[1164]: info: CorePlayQueue::getTrackBlock Nov 22 16:54:17 volumio volumio[1164]: info: CoreCommandRouter::volumioRetrievevolume Nov 22 16:54:17 volumio volumio[1164]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,1,0) appears twice or more Nov 22 16:54:17 volumio volumio[1164]: amixer: Mixer hw:0 load error: Invalid argument Nov 22 16:54:17 volumio volumio[1164]: info: VolumeController:: Volume=undefined Mute =false Nov 22 16:54:17 volumio volumio[1164]: info: CoreCommandRouter::volumioGetState Nov 22 16:54:17 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:17 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:17 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:17 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::volumioPlay Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::play index undefined Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::startPlaybackTimer Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Bích Ngọc/01 - Tinh khuc mua dong_Master - Unknown Artist.wav Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand stop took 0 milliseconds Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 16:54:19 volumio volumio[1164]: info: Nov 22 16:54:19 volumio volumio[1164]: ---------------------------- MPD announces system playlist update Nov 22 16:54:19 volumio volumio[1164]: info: Ignoring MPD Status Update Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand clear took 1 milliseconds Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Bích Ngọc/01 - Tinh khuc mua dong_Master - Unknown Artist.wav" Nov 22 16:54:19 volumio volumio[1164]: info: Nov 22 16:54:19 volumio volumio[1164]: ---------------------------- MPD announces system playlist update Nov 22 16:54:19 volumio volumio[1164]: info: Ignoring MPD Status Update Nov 22 16:54:19 volumio volumio[1164]: error: updateQueue error: null Nov 22 16:54:19 volumio volumio[1164]: info: Nov 22 16:54:19 volumio volumio[1164]: ---------------------------- MPD announces system playlist update Nov 22 16:54:19 volumio volumio[1164]: info: Ignoring MPD Status Update Nov 22 16:54:19 volumio volumio[1164]: info: ------------------------------ 2ms Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand add "INTERNAL/Bích Ngọc/01 - Tinh khuc mua dong_Master - Unknown Artist.wav" took 1 milliseconds Nov 22 16:54:19 volumio volumio[1164]: info: ------------------------------ 2ms Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand play Nov 22 16:54:19 volumio volumio[1164]: info: Nov 22 16:54:19 volumio volumio[1164]: ---------------------------- MPD announces system playlist update Nov 22 16:54:19 volumio volumio[1164]: info: Ignoring MPD Status Update Nov 22 16:54:19 volumio volumio[1164]: info: ------------------------------ 2ms Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand play took 1 milliseconds Nov 22 16:54:19 volumio volumio[1164]: info: ------------------------------ 1ms Nov 22 16:54:19 volumio volumio[1164]: info: Nov 22 16:54:19 volumio volumio[1164]: ---------------------------- MPD announces state update: player Nov 22 16:54:19 volumio volumio[1164]: info: ControllerMpd::getState Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:54:19 volumio volumio[1164]: info: Nov 22 16:54:19 volumio volumio[1164]: ---------------------------- MPD announces state update: player Nov 22 16:54:19 volumio volumio[1164]: info: ControllerMpd::getState Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand status took 99 milliseconds Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::parseState Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:54:19 volumio volumio[1164]: info: Nov 22 16:54:19 volumio volumio[1164]: ---------------------------- MPD announces state update: player Nov 22 16:54:19 volumio volumio[1164]: info: ControllerMpd::getState Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand status took 102 milliseconds Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::parseState Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:54:19 volumio volumio[1164]: info: Nov 22 16:54:19 volumio volumio[1164]: ---------------------------- MPD announces state update: player Nov 22 16:54:19 volumio volumio[1164]: info: ControllerMpd::getState Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:54:19 volumio volumio[1164]: info: ControllerMpd::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::servicePushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tinh khuc mua dong_Master","artist":"Unknown Artist","album":"BICH NGOC-MUA THU TRONG MUA","uri":"INTERNAL/Bích Ngọc/01 - Tinh khuc mua dong_Master - Unknown Artist.wav","trackType":"wav"} Nov 22 16:54:19 volumio volumio[1164]: verbose: CURRENT POSITION 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::syncState stateService play Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:54:19 volumio volumio[1164]: info: ------------------------------ 108ms Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand status took 6 milliseconds Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand status took 4 milliseconds Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::parseState Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::parseState Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:54:19 volumio volumio[1164]: info: ControllerMpd::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::servicePushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tinh khuc mua dong_Master","artist":"Unknown Artist","album":"BICH NGOC-MUA THU TRONG MUA","uri":"INTERNAL/Bích Ngọc/01 - Tinh khuc mua dong_Master - Unknown Artist.wav","trackType":"wav"} Nov 22 16:54:19 volumio volumio[1164]: verbose: CURRENT POSITION 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::syncState stateService play Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::syncState currentStatus play Nov 22 16:54:19 volumio volumio[1164]: info: Received an update from plugin. extracting info from payload Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:19 volumio volumio[1164]: info: ------------------------------ 125ms Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 22 16:54:19 volumio volumio[1164]: info: sendMpdCommand playlistinfo took 15 milliseconds Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:54:19 volumio volumio[1164]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:54:19 volumio volumio[1164]: info: ControllerMpd::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::servicePushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tinh khuc mua dong_Master","artist":"Unknown Artist","album":"BICH NGOC-MUA THU TRONG MUA","uri":"INTERNAL/Bích Ngọc/01 - Tinh khuc mua dong_Master - Unknown Artist.wav","trackType":"wav"} Nov 22 16:54:19 volumio volumio[1164]: verbose: CURRENT POSITION 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::syncState stateService play Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::syncState currentStatus play Nov 22 16:54:19 volumio volumio[1164]: info: Received an update from plugin. extracting info from payload Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:19 volumio volumio[1164]: info: ControllerMpd::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::servicePushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tinh khuc mua dong_Master","artist":"Unknown Artist","album":"BICH NGOC-MUA THU TRONG MUA","uri":"INTERNAL/Bích Ngọc/01 - Tinh khuc mua dong_Master - Unknown Artist.wav","trackType":"wav"} Nov 22 16:54:19 volumio volumio[1164]: verbose: CURRENT POSITION 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::syncState stateService play Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::syncState currentStatus play Nov 22 16:54:19 volumio volumio[1164]: info: Received an update from plugin. extracting info from payload Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:19 volumio volumio[1164]: info: CoreStateMachine::pushState Nov 22 16:54:19 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:54:19 volumio volumio[1164]: info: CoreCommandRouter::volumioPushState Nov 22 16:54:19 volumio volumio[1164]: info: ------------------------------ 39ms Nov 22 16:54:19 volumio volumio[1164]: info: ------------------------------ 37ms Nov 22 16:54:24 volumio ntpd[1153]: Soliciting pool server 2404:fbc0:0:2046::c Nov 22 16:54:43 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-BEACON-LOSS Nov 22 16:55:07 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-BEACON-LOSS Nov 22 16:55:15 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-BEACON-LOSS Nov 22 16:55:19 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-BEACON-LOSS Nov 22 16:55:21 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-BEACON-LOSS Nov 22 16:55:24 volumio dhcpcd[882]: wlan0: carrier lost Nov 22 16:55:24 volumio avahi-daemon[797]: Withdrawing address record for 192.168.1.167 on wlan0. Nov 22 16:55:24 volumio avahi-daemon[797]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.167. Nov 22 16:55:24 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-DISCONNECTED bssid=7c:a1:07:85:dd:b0 reason=4 locally_generated=1 Nov 22 16:55:24 volumio avahi-daemon[797]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 22 16:55:24 volumio dhcpcd[882]: wlan0: deleting route to 192.168.1.0/24 Nov 22 16:55:24 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Nov 22 16:55:24 volumio dhcpcd[882]: wlan0: deleting default route via 192.168.1.1 Nov 22 16:55:24 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Nov 22 16:55:24 volumio volumio[1164]: info: Discovery: A device disappeared from network Nov 22 16:55:24 volumio volumio[1164]: info: Discovery: Device volumio disappeared from network Nov 22 16:55:25 volumio wpa_supplicant[1066]: wlan0: SME: Trying to authenticate with 7c:a1:07:85:dd:b0 (SSID='FPT TELECOM' freq=2412 MHz) Nov 22 16:55:25 volumio kernel: wlan0: authenticate with 7c:a1:07:85:dd:b0 Nov 22 16:55:25 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Nov 22 16:55:25 volumio kernel: wlan0: send auth to 7c:a1:07:85:dd:b0 (try 1/3) Nov 22 16:55:25 volumio kernel: wlan0: send auth to 7c:a1:07:85:dd:b0 (try 2/3) Nov 22 16:55:25 volumio kernel: wlan0: send auth to 7c:a1:07:85:dd:b0 (try 3/3) Nov 22 16:55:26 volumio kernel: wlan0: authentication with 7c:a1:07:85:dd:b0 timed out Nov 22 16:55:26 volumio ntpd[1153]: Deleting interface #3 wlan0, 192.168.1.167#123, interface stats: received=44, sent=53, dropped=0, active_time=132 secs Nov 22 16:55:26 volumio ntpd[1153]: 115.165.161.155 local addr 192.168.1.167 -> Nov 22 16:55:26 volumio ntpd[1153]: 103.199.19.135 local addr 192.168.1.167 -> Nov 22 16:55:26 volumio ntpd[1153]: 103.184.124.23 local addr 192.168.1.167 -> Nov 22 16:55:26 volumio ntpd[1153]: 27.71.27.209 local addr 192.168.1.167 -> Nov 22 16:55:26 volumio ntpd[1153]: 103.130.217.41 local addr 192.168.1.167 -> Nov 22 16:55:27 volumio ntpd[1153]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 22 16:55:29 volumio volumio[1164]: info: Volumio Network Manager: Network status updated: 0 Nov 22 16:55:29 volumio ntpd[1153]: Soliciting pool server 2a0c:b847:ffff:79::e53c Nov 22 16:55:29 volumio ntpd[1153]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 22 16:55:32 volumio nmbd[963]: [2024/11/22 16:55:32.884006, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Nov 22 16:55:32 volumio nmbd[963]: reload_interfaces: No subnets to listen to. Waiting.. Nov 22 16:55:33 volumio wpa_supplicant[1066]: wlan0: SME: Trying to authenticate with 7c:a1:07:85:dd:b0 (SSID='FPT TELECOM' freq=2412 MHz) Nov 22 16:55:33 volumio kernel: wlan0: authenticate with 7c:a1:07:85:dd:b0 Nov 22 16:55:33 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Nov 22 16:55:33 volumio kernel: wlan0: send auth to 7c:a1:07:85:dd:b0 (try 1/3) Nov 22 16:55:34 volumio kernel: wlan0: send auth to 7c:a1:07:85:dd:b0 (try 2/3) Nov 22 16:55:34 volumio wpa_supplicant[1066]: wlan0: Trying to associate with 7c:a1:07:85:dd:b0 (SSID='FPT TELECOM' freq=2412 MHz) Nov 22 16:55:34 volumio kernel: wlan0: authenticated Nov 22 16:55:34 volumio kernel: wlan0: associate with 7c:a1:07:85:dd:b0 (try 1/3) Nov 22 16:55:34 volumio kernel: wlan0: associate with 7c:a1:07:85:dd:b0 (try 2/3) Nov 22 16:55:34 volumio kernel: wlan0: associate with 7c:a1:07:85:dd:b0 (try 3/3) Nov 22 16:55:34 volumio ntpd[1153]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 22 16:55:34 volumio kernel: wlan0: association with 7c:a1:07:85:dd:b0 timed out Nov 22 16:55:36 volumio wpa_supplicant[1066]: wlan0: SME: Trying to authenticate with 7c:a1:07:85:dd:b0 (SSID='FPT TELECOM' freq=2412 MHz) Nov 22 16:55:36 volumio kernel: wlan0: authenticate with 7c:a1:07:85:dd:b0 Nov 22 16:55:36 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Nov 22 16:55:36 volumio kernel: wlan0: send auth to 7c:a1:07:85:dd:b0 (try 1/3) Nov 22 16:55:36 volumio kernel: wlan0: send auth to 7c:a1:07:85:dd:b0 (try 2/3) Nov 22 16:55:37 volumio kernel: wlan0: send auth to 7c:a1:07:85:dd:b0 (try 3/3) Nov 22 16:55:37 volumio wpa_supplicant[1066]: wlan0: Trying to associate with 7c:a1:07:85:dd:b0 (SSID='FPT TELECOM' freq=2412 MHz) Nov 22 16:55:37 volumio kernel: wlan0: authenticated Nov 22 16:55:37 volumio kernel: wlan0: associate with 7c:a1:07:85:dd:b0 (try 1/3) Nov 22 16:55:37 volumio kernel: wlan0: associate with 7c:a1:07:85:dd:b0 (try 2/3) Nov 22 16:55:37 volumio kernel: wlan0: associate with 7c:a1:07:85:dd:b0 (try 3/3) Nov 22 16:55:37 volumio wpa_supplicant[1066]: wlan0: Associated with 7c:a1:07:85:dd:b0 Nov 22 16:55:37 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 22 16:55:37 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Nov 22 16:55:37 volumio kernel: wlan0: RX AssocResp from 7c:a1:07:85:dd:b0 (capab=0x1411 status=0 aid=4) Nov 22 16:55:37 volumio kernel: wlan0: associated Nov 22 16:55:37 volumio wpa_supplicant[1066]: wlan0: WPA: Key negotiation completed with 7c:a1:07:85:dd:b0 [PTK=CCMP GTK=CCMP] Nov 22 16:55:37 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-CONNECTED - Connection to 7c:a1:07:85:dd:b0 completed [id=0 id_str=] Nov 22 16:55:37 volumio dhcpcd[882]: wlan0: carrier acquired Nov 22 16:55:37 volumio dhcpcd[882]: wlan0: IAID 37:12:60:39 Nov 22 16:55:37 volumio dhcpcd[882]: wlan0: rebinding lease of 192.168.1.167 Nov 22 16:55:37 volumio dhcpcd[882]: wlan0: probing address 192.168.1.167/24 Nov 22 16:55:37 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 7c:a1:07:85:dd:b0 Nov 22 16:55:37 volumio dhcpcd[882]: wlan0: soliciting an IPv6 router Nov 22 16:55:41 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-BEACON-LOSS Nov 22 16:55:43 volumio dhcpcd[882]: wlan0: leased 192.168.1.167 for 86400 seconds Nov 22 16:55:43 volumio avahi-daemon[797]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.167. Nov 22 16:55:43 volumio avahi-daemon[797]: New relevant interface wlan0.IPv4 for mDNS. Nov 22 16:55:43 volumio dhcpcd[882]: wlan0: adding route to 192.168.1.0/24 Nov 22 16:55:43 volumio dhcpcd[882]: wlan0: adding default route via 192.168.1.1 Nov 22 16:55:43 volumio avahi-daemon[797]: Registering new address record for 192.168.1.167 on wlan0.IPv4. Nov 22 16:55:43 volumio ntpd[1153]: ntpd exiting on signal 15 (Terminated) Nov 22 16:55:43 volumio systemd[1]: Stopping Network Time Service... Nov 22 16:55:43 volumio systemd[1]: ntp.service: Succeeded. Nov 22 16:55:43 volumio systemd[1]: Stopped Network Time Service. Nov 22 16:55:43 volumio systemd[1]: Starting Network Time Service... Nov 22 16:55:43 volumio ntpd[2177]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 22 16:55:43 volumio ntpd[2177]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 22 16:55:43 volumio ntpd[2183]: proto: precision = 0.103 usec (-23) Nov 22 16:55:43 volumio ntpd[2183]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 22 16:55:43 volumio ntpd[2183]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 22 16:55:43 volumio ntpd[2183]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 696 days ago Nov 22 16:55:43 volumio ntpd[2183]: Listen and drop on 0 v6wildcard [::]:123 Nov 22 16:55:43 volumio ntpd[2183]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 22 16:55:43 volumio ntpd[2183]: Listen normally on 2 lo 127.0.0.1:123 Nov 22 16:55:43 volumio ntpd[2183]: Listen normally on 3 wlan0 192.168.1.167:123 Nov 22 16:55:43 volumio ntpd[2183]: Listening on routing socket on fd #20 for interface updates Nov 22 16:55:43 volumio systemd[1]: Started Network Time Service. Nov 22 16:55:43 volumio ntpd[2183]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 22 16:55:43 volumio ntpd[2183]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 22 16:55:44 volumio ntpd[2183]: Soliciting pool server 103.199.19.135 Nov 22 16:55:45 volumio ntpd[2183]: Soliciting pool server 103.130.217.41 Nov 22 16:55:45 volumio ntpd[2183]: Soliciting pool server 103.184.124.23 Nov 22 16:55:46 volumio ntpd[2183]: Soliciting pool server 115.165.161.155 Nov 22 16:55:46 volumio ntpd[2183]: Soliciting pool server 115.165.161.155 Nov 22 16:55:46 volumio ntpd[2183]: Soliciting pool server 27.71.27.209 Nov 22 16:55:46 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-BEACON-LOSS Nov 22 16:55:47 volumio ntpd[2183]: Soliciting pool server 2404:fbc0:0:2046::c Nov 22 16:55:49 volumio volumio[1164]: info: Volumio Network Manager: Network status updated: 2 Nov 22 16:55:49 volumio volumio[1164]: info: Discovery: adding a3e49d6d-d8c6-4c16-a2e9-49bfd221d17e Nov 22 16:55:49 volumio volumio[1164]: info: Discovery: Found device Volumio Nov 22 16:55:49 volumio volumio[1164]: info: CoreCommandRouter::volumioGetState Nov 22 16:55:49 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 22 16:55:49 volumio volumio[1164]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 16:55:49 volumio volumio[1164]: Error: The value NaN is not a number Nov 22 16:55:49 volumio volumio[1164]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Nov 22 16:55:49 volumio volumio[1164]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Nov 22 16:55:49 volumio volumio[1164]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Nov 22 16:55:49 volumio volumio[1164]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Nov 22 16:55:49 volumio volumio[1164]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Nov 22 16:55:49 volumio volumio[1164]: at Browser.emit (events.js:400:28) Nov 22 16:55:49 volumio volumio[1164]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Nov 22 16:55:49 volumio volumio[1164]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Nov 22 16:55:49 volumio volumio[1164]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Nov 22 16:55:49 volumio volumio[1164]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Nov 22 16:55:49 volumio volumio[1164]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Nov 22 16:55:49 volumio volumio[1164]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Nov 22 16:55:49 volumio volumio[1164]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Nov 22 16:55:49 volumio volumio[1164]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 16:55:49 volumio wpa_supplicant[1066]: wlan0: CTRL-EVENT-BEACON-LOSS Nov 22 16:55:49 volumio sudo[2215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 16:54 Nov 22 16:55:49 volumio sudo[2215]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"