-- Logs begin at Sat 2023-10-28 21:13:39 +07, end at Sat 2023-10-28 21:39:22 +07. -- Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:01 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:02 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:05 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:38:07 nguyenductam volumio[910]: info: CorePlayQueue::getTrack 16 Oct 28 21:38:07 nguyenductam volumio[910]: info: CorePlayQueue::getTrack 22 Oct 28 21:38:07 nguyenductam volumio[910]: info: Prefetching next song Oct 28 21:38:07 nguyenductam volumio[910]: info: DOING PREFETCH IN MPD Oct 28 21:38:07 nguyenductam volumio[910]: verbose: ControllerMpd::sendMpdCommand add "USB/002B-809D/Music/Yoshio Kimura - 80th Anniversary - Audiophile Selections (2017) [WAV]/(13) [Yoshio Kimura (木村好夫)] Night's Blues (Composed by Tokujiro Okubo).wav" Oct 28 21:38:07 nguyenductam volumio[910]: info: sendMpdCommand add "USB/002B-809D/Music/Yoshio Kimura - 80th Anniversary - Audiophile Selections (2017) [WAV]/(13) [Yoshio Kimura (木村好夫)] Night's Blues (Composed by Tokujiro Okubo).wav" took 0 milliseconds Oct 28 21:38:07 nguyenductam volumio[910]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 28 21:38:07 nguyenductam volumio[910]: info: Oct 28 21:38:07 nguyenductam volumio[910]: ---------------------------- MPD announces system playlist update Oct 28 21:38:07 nguyenductam volumio[910]: info: Ignoring MPD Status Update Oct 28 21:38:07 nguyenductam volumio[910]: info: Oct 28 21:38:07 nguyenductam volumio[910]: ---------------------------- MPD announces state update: options Oct 28 21:38:07 nguyenductam volumio[910]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 28 21:38:07 nguyenductam volumio[910]: info: ControllerMpd::getState Oct 28 21:38:07 nguyenductam volumio[910]: verbose: ControllerMpd::sendMpdCommand status Oct 28 21:38:07 nguyenductam volumio[910]: info: ------------------------------ 2ms Oct 28 21:38:07 nguyenductam volumio[910]: info: sendMpdCommand status took 2 milliseconds Oct 28 21:38:07 nguyenductam volumio[910]: verbose: ControllerMpd::parseState Oct 28 21:38:07 nguyenductam volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 21:38:07 nguyenductam volumio[910]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 28 21:38:07 nguyenductam volumio[910]: verbose: ControllerMpd::parseTrackInfo Oct 28 21:38:07 nguyenductam volumio[910]: info: ControllerMpd::pushState Oct 28 21:38:07 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:07 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:07 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:07 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:07 nguyenductam volumio[910]: info: ------------------------------ 14ms Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:09 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:10 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:11 nguyenductam volumio[910]: info: Oct 28 21:38:11 nguyenductam volumio[910]: ---------------------------- MPD announces system playlist update Oct 28 21:38:11 nguyenductam volumio[910]: info: Ignoring MPD Status Update Oct 28 21:38:11 nguyenductam volumio[910]: info: Oct 28 21:38:11 nguyenductam volumio[910]: ---------------------------- MPD announces state update: player Oct 28 21:38:11 nguyenductam volumio[910]: info: ControllerMpd::getState Oct 28 21:38:11 nguyenductam volumio[910]: verbose: ControllerMpd::sendMpdCommand status Oct 28 21:38:11 nguyenductam volumio[910]: info: ------------------------------ 2ms Oct 28 21:38:11 nguyenductam volumio[910]: info: sendMpdCommand status took 2 milliseconds Oct 28 21:38:11 nguyenductam volumio[910]: verbose: ControllerMpd::parseState Oct 28 21:38:11 nguyenductam volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 21:38:11 nguyenductam volumio[910]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 28 21:38:11 nguyenductam volumio[910]: verbose: ControllerMpd::parseTrackInfo Oct 28 21:38:11 nguyenductam volumio[910]: info: ControllerMpd::pushState Oct 28 21:38:11 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:38:11 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:38:11 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:38:11 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:38:11 nguyenductam volumio[910]: info: ------------------------------ 15ms Oct 28 21:38:12 nguyenductam volumio[910]: info: CoreStateMachine::startPlaybackTimer Oct 28 21:38:12 nguyenductam volumio[910]: info: CorePlayQueue::getTrack 22 Oct 28 21:38:13 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:38:21 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:38:29 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:38:36 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:38:40 nguyenductam volumio[910]: error: error Oct 28 21:38:44 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:38:50 nguyenductam ntpd[645]: Soliciting pool server 103.199.19.135 Oct 28 21:38:52 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:38:59 nguyenductam ntpd[645]: Soliciting pool server 2401:5f80:5001:1f::31f Oct 28 21:39:00 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:39:02 nguyenductam ntpd[645]: Soliciting pool server 103.184.124.23 Oct 28 21:39:06 nguyenductam ntpd[645]: Soliciting pool server 115.165.161.155 Oct 28 21:39:08 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:39:16 nguyenductam wpa_supplicant[747]: wlan0: Failed to initiate sched scan Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::servicePushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreStateMachine::pushState Oct 28 21:39:21 nguyenductam volumio[910]: info: CoreCommandRouter::volumioPushState Oct 28 21:39:22 nguyenductam volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 21:39:22 nguyenductam volumio[910]: Error: certificate is not yet valid Oct 28 21:39:22 nguyenductam volumio[910]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Oct 28 21:39:22 nguyenductam volumio[910]: at TLSSocket.emit (events.js:315:20) Oct 28 21:39:22 nguyenductam volumio[910]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Oct 28 21:39:22 nguyenductam volumio[910]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Oct 28 21:39:22 nguyenductam volumio[910]: code: 'CERT_NOT_YET_VALID' Oct 28 21:39:22 nguyenductam volumio[910]: } Oct 28 21:39:22 nguyenductam volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 21:39:22 nguyenductam sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-10-28 21:38 Oct 28 21:39:22 nguyenductam sudo[2799]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"