-- Logs begin at Fri 2024-05-10 22:25:15 CST, end at Sat 2024-05-11 08:14:37 CST. -- May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::volumioNext May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::next May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::stop May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::stPlaybackTimer May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::updateTrackBlock May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrackBlock May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::pushState May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrack 0 May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::volumioPushState May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::serviceStop May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrack 0 May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::serviceStop May 11 08:14:06 volumio volumio[947]: info: ControllerMpd::stop May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand stop May 11 08:14:06 volumio volumio[947]: info: May 11 08:14:06 volumio volumio[947]: ---------------------------- MPD announces state update: player May 11 08:14:06 volumio volumio[947]: info: sendMpdCommand stop took 130 milliseconds May 11 08:14:06 volumio volumio[947]: info: ControllerMpd::getState May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::play index undefined May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::startPlaybackTimer May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::clearAddPlayTracks USB/E8D1-CC7B/10/乌兰托娅 - 草原情歌.mp3 May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand stop May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::updateTrackBlock May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrackBlock May 11 08:14:06 volumio volumio[947]: info: sendMpdCommand status took 20 milliseconds May 11 08:14:06 volumio volumio[947]: info: sendMpdCommand stop took 13 milliseconds May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::parseState May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand clear May 11 08:14:06 volumio volumio[947]: info: May 11 08:14:06 volumio volumio[947]: ---------------------------- MPD announces system playlist update May 11 08:14:06 volumio volumio[947]: info: Ignoring MPD Status Update May 11 08:14:06 volumio volumio[947]: info: sendMpdCommand playlistinfo took 6 milliseconds May 11 08:14:06 volumio volumio[947]: info: sendMpdCommand clear took 7 milliseconds May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand add "USB/E8D1-CC7B/10/乌兰托娅 - 草原情歌.mp3" May 11 08:14:06 volumio volumio[947]: info: ControllerMpd::pushState May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::servicePushState May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::pushState May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::volumioPushState May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:06 volumio volumio[947]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰托娅 - 2010年最新歌曲.mp3","artist":null,"album":null,"uri":"USB/E8D1-CC7B/10/乌兰托娅 - 2010年最新歌曲.mp3","trackType":"mp3"} May 11 08:14:06 volumio volumio[947]: verbose: CURRENT POSITION 3 May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::syncState stateService stop May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus stop May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::pushState May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::volumioPushState May 11 08:14:06 volumio volumio[947]: info: No code May 11 08:14:06 volumio volumio[947]: info: CoreStateMachine::pushState May 11 08:14:06 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:06 volumio volumio[947]: info: CoreCommandRouter::volumioPushState May 11 08:14:06 volumio volumio[947]: info: ------------------------------ 96ms May 11 08:14:06 volumio volumio[947]: error: updateQueue error: null May 11 08:14:06 volumio volumio[947]: info: May 11 08:14:06 volumio volumio[947]: ---------------------------- MPD announces system playlist update May 11 08:14:06 volumio volumio[947]: info: Ignoring MPD Status Update May 11 08:14:06 volumio volumio[947]: info: ------------------------------ 75ms May 11 08:14:06 volumio volumio[947]: info: sendMpdCommand add "USB/E8D1-CC7B/10/乌兰托娅 - 草原情歌.mp3" took 72 milliseconds May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand play May 11 08:14:06 volumio volumio[947]: info: May 11 08:14:06 volumio volumio[947]: ---------------------------- MPD announces state update: player May 11 08:14:06 volumio volumio[947]: info: ------------------------------ 40ms May 11 08:14:06 volumio volumio[947]: info: sendMpdCommand play took 37 milliseconds May 11 08:14:06 volumio volumio[947]: info: ControllerMpd::getState May 11 08:14:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status May 11 08:14:07 volumio volumio[947]: info: May 11 08:14:07 volumio volumio[947]: ---------------------------- MPD announces state update: player May 11 08:14:07 volumio volumio[947]: info: ControllerMpd::getState May 11 08:14:07 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status May 11 08:14:07 volumio volumio[947]: info: sendMpdCommand status took 5 milliseconds May 11 08:14:07 volumio volumio[947]: verbose: ControllerMpd::parseState May 11 08:14:07 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 08:14:07 volumio volumio[947]: info: sendMpdCommand status took 14 milliseconds May 11 08:14:07 volumio volumio[947]: info: sendMpdCommand playlistinfo took 5 milliseconds May 11 08:14:07 volumio volumio[947]: verbose: ControllerMpd::parseState May 11 08:14:07 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 08:14:07 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo May 11 08:14:07 volumio volumio[947]: info: ControllerMpd::pushState May 11 08:14:07 volumio volumio[947]: info: CoreCommandRouter::servicePushState May 11 08:14:07 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:07 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰托娅 - 草原情歌.mp3","artist":null,"album":null,"uri":"USB/E8D1-CC7B/10/乌兰托娅 - 草原情歌.mp3","trackType":"mp3"} May 11 08:14:07 volumio volumio[947]: verbose: CURRENT POSITION 3 May 11 08:14:07 volumio volumio[947]: info: CoreStateMachine::syncState stateService play May 11 08:14:07 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus stop May 11 08:14:07 volumio volumio[947]: info: ------------------------------ 36ms May 11 08:14:07 volumio volumio[947]: info: sendMpdCommand playlistinfo took 12 milliseconds May 11 08:14:07 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo May 11 08:14:07 volumio volumio[947]: info: ControllerMpd::pushState May 11 08:14:07 volumio volumio[947]: info: CoreCommandRouter::servicePushState May 11 08:14:07 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:07 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰托娅 - 草原情歌.mp3","artist":null,"album":null,"uri":"USB/E8D1-CC7B/10/乌兰托娅 - 草原情歌.mp3","trackType":"mp3"} May 11 08:14:07 volumio volumio[947]: verbose: CURRENT POSITION 3 May 11 08:14:07 volumio volumio[947]: info: CoreStateMachine::syncState stateService play May 11 08:14:07 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play May 11 08:14:07 volumio volumio[947]: info: Received an update from plugin. extracting info from payload May 11 08:14:07 volumio volumio[947]: info: CoreStateMachine::pushState May 11 08:14:07 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:07 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 08:14:07 volumio volumio[947]: info: CoreCommandRouter::volumioPushState May 11 08:14:07 volumio volumio[947]: info: CoreStateMachine::pushState May 11 08:14:07 volumio volumio[947]: info: CorePlayQueue::getTrack 3 May 11 08:14:07 volumio volumio[947]: info: CoreCommandRouter::volumioPushState May 11 08:14:07 volumio volumio[947]: info: ------------------------------ 79ms May 11 08:14:36 volumio volumio[947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 08:14:36 volumio volumio[947]: Error: connect ETIMEDOUT 122.248.226.57:443 May 11 08:14:36 volumio volumio[947]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 11 08:14:36 volumio volumio[947]: errno: -110, May 11 08:14:36 volumio volumio[947]: code: 'ETIMEDOUT', May 11 08:14:36 volumio volumio[947]: syscall: 'connect', May 11 08:14:36 volumio volumio[947]: address: '122.248.226.57', May 11 08:14:36 volumio volumio[947]: port: 443 May 11 08:14:36 volumio volumio[947]: } May 11 08:14:36 volumio volumio[947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 08:14:37 volumio sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 08:13 May 11 08:14:37 volumio sudo[1689]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"