-- Logs begin at Fri 2025-01-24 14:32:35 UTC, end at Mon 2025-09-01 10:42:20 UTC. -- Sep 01 10:41:20 volumio volumio[1126]: info: Sep 01 10:41:20 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:20 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:20 volumio volumio[1126]: info: Sep 01 10:41:20 volumio volumio[1126]: ---------------------------- MPD announces state update: player Sep 01 10:41:20 volumio volumio[1126]: info: ControllerMpd::getState Sep 01 10:41:20 volumio volumio[1126]: verbose: ControllerMpd::sendMpdCommand status Sep 01 10:41:20 volumio volumio[1126]: info: ------------------------------ 5ms Sep 01 10:41:20 volumio volumio[1126]: verbose: ControllerMpd::parseState Sep 01 10:41:20 volumio volumio[1126]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 10:41:20 volumio volumio[1126]: verbose: ControllerMpd::parseTrackInfo Sep 01 10:41:20 volumio volumio[1126]: info: ControllerMpd::pushState Sep 01 10:41:20 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Sep 01 10:41:20 volumio volumio[1126]: In UPNP mode Sep 01 10:41:20 volumio volumio[1126]: verbose: STATE SERVICE {"status":"play","position":0,"seek":239479,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17Ëê","artist":"ÁõµÂ»ª","album":"Èç¹ûÓÐÒ»Ìì","uri":"http://aqqmusic.tc.qq.com/M8000048MjWb0lHwBN.mp3?guid=000000000d5c4a550000018bd5b7edaf&vkey=7E26552D75A58091BC6BB49C31B36231AD455B0E4AF7F02B5F84716B780A24325E27A9DD5BB9F7CD41EF10A70C0871C22763D6EABDA78A6A__v2b9ab16a&uin=313556878&redirect=1&fromtag=111093","trackType":""} Sep 01 10:41:20 volumio volumio[1126]: verbose: CURRENT POSITION 0 Sep 01 10:41:20 volumio volumio[1126]: info: CoreStateMachine::syncState stateService play Sep 01 10:41:20 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus play Sep 01 10:41:20 volumio volumio[1126]: info: Received an update from plugin. extracting info from payload Sep 01 10:41:20 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 10:41:20 volumio volumio[1126]: info: CoreStateMachine::pushState Sep 01 10:41:20 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Sep 01 10:41:20 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 10:41:20 volumio volumio[1126]: info: CoreStateMachine::pushState Sep 01 10:41:20 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Sep 01 10:41:20 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 10:41:20 volumio volumio[1126]: info: ------------------------------ 25ms Sep 01 10:41:20 volumio volumio[1126]: info: CoreCommandRouter::volumioGetQueue Sep 01 10:41:20 volumio volumio[1126]: info: CoreStateMachine::getQueue Sep 01 10:41:20 volumio volumio[1126]: info: CorePlayQueue::getQueue Sep 01 10:41:20 volumio volumio[1126]: info: CoreCommandRouter::volumioGetQueue Sep 01 10:41:20 volumio volumio[1126]: info: CoreStateMachine::getQueue Sep 01 10:41:20 volumio volumio[1126]: info: CorePlayQueue::getQueue Sep 01 10:41:21 volumio volumio[1126]: info: Sep 01 10:41:21 volumio volumio[1126]: ---------------------------- MPD announces state update: player Sep 01 10:41:21 volumio volumio[1126]: info: ControllerMpd::getState Sep 01 10:41:21 volumio volumio[1126]: verbose: ControllerMpd::sendMpdCommand status Sep 01 10:41:21 volumio volumio[1126]: verbose: ControllerMpd::parseState Sep 01 10:41:21 volumio volumio[1126]: info: ControllerMpd::pushState Sep 01 10:41:21 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Sep 01 10:41:21 volumio volumio[1126]: info: CoreStateMachine::pushState Sep 01 10:41:21 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Sep 01 10:41:21 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 10:41:21 volumio volumio[1126]: info: CorePlayQueue::getTrack 0 Sep 01 10:41:21 volumio volumio[1126]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 10:41:21 volumio volumio[1126]: verbose: CURRENT POSITION 0 Sep 01 10:41:21 volumio volumio[1126]: info: CoreStateMachine::syncState stateService stop Sep 01 10:41:21 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus play Sep 01 10:41:21 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Sep 01 10:41:21 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 10:41:21 volumio volumio[1126]: info: CoreStateMachine::stPlaybackTimer Sep 01 10:41:21 volumio volumio[1126]: info: ------------------------------ 27ms Sep 01 10:41:21 volumio volumio[1126]: info: CoreCommandRouter::volumioGetQueue Sep 01 10:41:21 volumio volumio[1126]: info: CoreStateMachine::getQueue Sep 01 10:41:21 volumio volumio[1126]: info: CorePlayQueue::getQueue Sep 01 10:41:45 volumio volumio[1126]: info: Clearing queue after UPNP request Sep 01 10:41:45 volumio volumio[1126]: info: CoreStateMachine::ClearQueue Sep 01 10:41:45 volumio volumio[1126]: info: CoreStateMachine::stop Sep 01 10:41:45 volumio volumio[1126]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 10:41:45 volumio volumio[1126]: UNSET VOLATILE Sep 01 10:41:45 volumio volumio[1126]: info: CorePlayQueue::clearPlayQueue Sep 01 10:41:45 volumio volumio[1126]: info: CorePlayQueue::saveQueue Sep 01 10:41:45 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Sep 01 10:41:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 10:41:45 volumio volumio[1126]: info: CoreCommandRouter::volumioPushQueue Sep 01 10:41:45 volumio volumio[1126]: info: Sep 01 10:41:45 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:45 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:45 volumio volumio[1126]: error: updateQueue error: null Sep 01 10:41:45 volumio volumio[1126]: info: ------------------------------ 3ms Sep 01 10:41:45 volumio volumio[1126]: info: Starting UPNP Playback Sep 01 10:41:45 volumio volumio[1126]: info: Preparing playback through UPNP Sep 01 10:41:45 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Sep 01 10:41:45 volumio volumio[1126]: info: CorePlayQueue::getTrack 0 Sep 01 10:41:45 volumio volumio[1126]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:46 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:46 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 3ms Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 3ms Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:46 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 2ms Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:46 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:46 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 3ms Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 3ms Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:46 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 2ms Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces state update: player Sep 01 10:41:46 volumio volumio[1126]: info: ControllerMpd::getState Sep 01 10:41:46 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Sep 01 10:41:46 volumio volumio[1126]: verbose: ControllerMpd::sendMpdCommand status Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces state update: player Sep 01 10:41:46 volumio volumio[1126]: info: ControllerMpd::getState Sep 01 10:41:46 volumio volumio[1126]: verbose: ControllerMpd::sendMpdCommand status Sep 01 10:41:46 volumio volumio[1126]: info: Sep 01 10:41:46 volumio volumio[1126]: ---------------------------- MPD announces system playlist update Sep 01 10:41:46 volumio volumio[1126]: info: Ignoring MPD Status Update Sep 01 10:41:46 volumio volumio[1126]: verbose: ControllerMpd::parseState Sep 01 10:41:46 volumio volumio[1126]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 10:41:46 volumio volumio[1126]: verbose: ControllerMpd::parseState Sep 01 10:41:46 volumio volumio[1126]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 14ms Sep 01 10:41:46 volumio volumio[1126]: verbose: ControllerMpd::parseTrackInfo Sep 01 10:41:46 volumio volumio[1126]: verbose: ControllerMpd::parseTrackInfo Sep 01 10:41:46 volumio volumio[1126]: info: ControllerMpd::pushState Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Sep 01 10:41:46 volumio volumio[1126]: In UPNP mode Sep 01 10:41:46 volumio volumio[1126]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"反方向的钟","artist":"周杰伦","album":"Jay","uri":"http://aqqmusic.tc.qq.com/M80000400jk23JDWwJ.mp3?guid=000000000d5c4a550000018bd5b7edaf&vkey=643A48967123453A9262460F51C2BF0D5ACEE86B17ED1F4042004A2FD7E9BE90BE06D09D99C5DE4983BE1D8C89B126E68F3F7A41FAEA6CD4__v2b94c08b&uin=313556878&redirect=1&fromtag=111093","trackType":""} Sep 01 10:41:46 volumio volumio[1126]: verbose: CURRENT POSITION 0 Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::syncState stateService play Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus stop Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::pushState Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 10:41:46 volumio volumio[1126]: info: ControllerMpd::pushState Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Sep 01 10:41:46 volumio volumio[1126]: In UPNP mode Sep 01 10:41:46 volumio volumio[1126]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"反方向的钟","artist":"周杰伦","album":"Jay","uri":"http://aqqmusic.tc.qq.com/M80000400jk23JDWwJ.mp3?guid=000000000d5c4a550000018bd5b7edaf&vkey=643A48967123453A9262460F51C2BF0D5ACEE86B17ED1F4042004A2FD7E9BE90BE06D09D99C5DE4983BE1D8C89B126E68F3F7A41FAEA6CD4__v2b94c08b&uin=313556878&redirect=1&fromtag=111093","trackType":""} Sep 01 10:41:46 volumio volumio[1126]: verbose: CURRENT POSITION 0 Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::syncState stateService play Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus play Sep 01 10:41:46 volumio volumio[1126]: info: Received an update from plugin. extracting info from payload Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::pushState Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::pushState Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 80ms Sep 01 10:41:46 volumio volumio[1126]: info: ------------------------------ 62ms Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::volumioGetQueue Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::getQueue Sep 01 10:41:46 volumio volumio[1126]: info: CorePlayQueue::getQueue Sep 01 10:41:46 volumio volumio[1126]: info: CoreCommandRouter::volumioGetQueue Sep 01 10:41:46 volumio volumio[1126]: info: CoreStateMachine::getQueue Sep 01 10:41:46 volumio volumio[1126]: info: CorePlayQueue::getQueue Sep 01 10:42:19 volumio volumio[1126]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 10:42:19 volumio volumio[1126]: { Error: connect ETIMEDOUT 184.72.1.148:443 Sep 01 10:42:19 volumio volumio[1126]: at Object._errnoException (util.js:1022:11) Sep 01 10:42:19 volumio volumio[1126]: at _exceptionWithHostPort (util.js:1044:20) Sep 01 10:42:19 volumio volumio[1126]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 01 10:42:19 volumio volumio[1126]: code: 'ETIMEDOUT', Sep 01 10:42:19 volumio volumio[1126]: errno: 'ETIMEDOUT', Sep 01 10:42:19 volumio volumio[1126]: syscall: 'connect', Sep 01 10:42:19 volumio volumio[1126]: address: '184.72.1.148', Sep 01 10:42:19 volumio volumio[1126]: port: 443 } Sep 01 10:42:19 volumio volumio[1126]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 10:42:20 volumio sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 10:41 Sep 01 10:42:20 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"