-- Logs begin at Wed 2024-07-24 11:30:57 UTC, end at Wed 2024-07-24 12:29:17 UTC. -- Jul 24 12:29:06 volumio volumio[10798]: info: Jul 24 12:29:06 volumio volumio[10798]: ---------------------------- MPD announces state update: player Jul 24 12:29:06 volumio volumio[10798]: info: ControllerMpd::getState Jul 24 12:29:06 volumio volumio[10798]: verbose: ControllerMpd::sendMpdCommand status Jul 24 12:29:06 volumio volumio[10798]: info: sendMpdCommand status took 1 milliseconds Jul 24 12:29:06 volumio volumio[10798]: verbose: ControllerMpd::parseState Jul 24 12:29:06 volumio volumio[10798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 24 12:29:06 volumio volumio[10798]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 24 12:29:06 volumio volumio[10798]: verbose: ControllerMpd::parseTrackInfo Jul 24 12:29:06 volumio volumio[10798]: info: ControllerMpd::pushState Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::servicePushState Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::pushState Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::volumioPushState Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 12:29:06 volumio volumio[10798]: info: CorePlayQueue::getTrack 0 Jul 24 12:29:06 volumio volumio[10798]: 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":"黎明前的黑暗","artist":"袁乐乐","album":"黎明前的黑暗（新版）","uri":"http://sjy6.stream.qqmusic.qq.com/M500003MMS4X1ExEtN.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=47146D710522092081BDC2E11CB6F52A0484E155B3DD24A8908DC8BF268E9759975C5BBC18451509B308190CF72CE38066B62A3BFF9B3E3D&uin=22213939&src=M500004Y6t5q1GrBfc.mp3&redirect=1&fromtag=111042","trackType":""} Jul 24 12:29:06 volumio volumio[10798]: verbose: CURRENT POSITION 0 Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::syncState stateService stop Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::syncState currentStatus play Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::volumioPushState Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::stPlaybackTimer Jul 24 12:29:06 volumio volumio[10798]: info: ------------------------------ 22ms Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::volumioGetQueue Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::getQueue Jul 24 12:29:06 volumio volumio[10798]: info: CorePlayQueue::getQueue Jul 24 12:29:06 volumio volumio[10798]: info: Clearing queue after UPNP request Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::ClearQueue Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::stop Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 24 12:29:06 volumio volumio[10798]: verbose: UNSET VOLATILE Jul 24 12:29:06 volumio volumio[10798]: info: CorePlayQueue::clearPlayQueue Jul 24 12:29:06 volumio volumio[10798]: info: CorePlayQueue::saveQueue Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::volumioPushState Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::volumioPushQueue Jul 24 12:29:06 volumio volumio[10798]: info: Jul 24 12:29:06 volumio volumio[10798]: ---------------------------- MPD announces system playlist update Jul 24 12:29:06 volumio volumio[10798]: info: Ignoring MPD Status Update Jul 24 12:29:06 volumio volumio[10798]: info: Starting UPNP Playback Jul 24 12:29:06 volumio volumio[10798]: info: Preparing playback through UPNP Jul 24 12:29:06 volumio volumio[10798]: info: CoreCommandRouter::volumioGetState Jul 24 12:29:06 volumio volumio[10798]: info: CorePlayQueue::getTrack 0 Jul 24 12:29:06 volumio volumio[10798]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 24 12:29:06 volumio volumio[10798]: error: updateQueue error: null Jul 24 12:29:06 volumio volumio[10798]: info: ------------------------------ 4ms Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces system playlist update Jul 24 12:29:07 volumio volumio[10798]: info: Ignoring MPD Status Update Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 2ms Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces system playlist update Jul 24 12:29:07 volumio volumio[10798]: info: Ignoring MPD Status Update Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 1ms Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces system playlist update Jul 24 12:29:07 volumio volumio[10798]: info: Ignoring MPD Status Update Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 1ms Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces system playlist update Jul 24 12:29:07 volumio volumio[10798]: info: Ignoring MPD Status Update Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces system playlist update Jul 24 12:29:07 volumio volumio[10798]: info: Ignoring MPD Status Update Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 1ms Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 1ms Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces system playlist update Jul 24 12:29:07 volumio volumio[10798]: info: Ignoring MPD Status Update Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 1ms Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces state update: player Jul 24 12:29:07 volumio volumio[10798]: info: ControllerMpd::getState Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::sendMpdCommand status Jul 24 12:29:07 volumio volumio[10798]: info: sendMpdCommand status took 3 milliseconds Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::parseState Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces state update: player Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces system playlist update Jul 24 12:29:07 volumio volumio[10798]: info: Ignoring MPD Status Update Jul 24 12:29:07 volumio volumio[10798]: info: ControllerMpd::getState Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::sendMpdCommand status Jul 24 12:29:07 volumio volumio[10798]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::parseTrackInfo Jul 24 12:29:07 volumio volumio[10798]: info: ControllerMpd::pushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::servicePushState Jul 24 12:29:07 volumio volumio[10798]: verbose: In UPNP mode Jul 24 12:29:07 volumio volumio[10798]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Windfall","artist":"TheFatRat","album":"Windfall","uri":"http://sjy6.stream.qqmusic.qq.com/M500004ENtGo3kr0ge.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=C93B86C3B8BE08E280E90608D21B23CEBF221BBC23631D6D3268973957D0AB0DD340EEAE765C77FF130567F84C4F43AEC43B0C70A43DB269&uin=22213939&src=M5000017dAJu3TwUri.mp3&redirect=1&fromtag=111042","trackType":""} Jul 24 12:29:07 volumio volumio[10798]: verbose: CURRENT POSITION 0 Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::syncState stateService play Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::syncState currentStatus stop Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::pushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioPushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 39ms Jul 24 12:29:07 volumio volumio[10798]: info: Jul 24 12:29:07 volumio volumio[10798]: ---------------------------- MPD announces state update: player Jul 24 12:29:07 volumio volumio[10798]: info: sendMpdCommand status took 29 milliseconds Jul 24 12:29:07 volumio volumio[10798]: info: ControllerMpd::getState Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::sendMpdCommand status Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 31ms Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::parseState Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 24 12:29:07 volumio volumio[10798]: info: sendMpdCommand status took 24 milliseconds Jul 24 12:29:07 volumio volumio[10798]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::parseState Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::parseTrackInfo Jul 24 12:29:07 volumio volumio[10798]: info: ControllerMpd::pushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::servicePushState Jul 24 12:29:07 volumio volumio[10798]: verbose: In UPNP mode Jul 24 12:29:07 volumio volumio[10798]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Windfall","artist":"TheFatRat","album":"Windfall","uri":"http://sjy6.stream.qqmusic.qq.com/M500004ENtGo3kr0ge.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=C93B86C3B8BE08E280E90608D21B23CEBF221BBC23631D6D3268973957D0AB0DD340EEAE765C77FF130567F84C4F43AEC43B0C70A43DB269&uin=22213939&src=M5000017dAJu3TwUri.mp3&redirect=1&fromtag=111042","trackType":""} Jul 24 12:29:07 volumio volumio[10798]: verbose: CURRENT POSITION 0 Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::syncState stateService play Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::syncState currentStatus play Jul 24 12:29:07 volumio volumio[10798]: info: Received an update from plugin. extracting info from payload Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::pushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioPushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::pushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioPushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 79ms Jul 24 12:29:07 volumio volumio[10798]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 24 12:29:07 volumio volumio[10798]: verbose: ControllerMpd::parseTrackInfo Jul 24 12:29:07 volumio volumio[10798]: info: ControllerMpd::pushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::servicePushState Jul 24 12:29:07 volumio volumio[10798]: verbose: In UPNP mode Jul 24 12:29:07 volumio volumio[10798]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Windfall","artist":"TheFatRat","album":"Windfall","uri":"http://sjy6.stream.qqmusic.qq.com/M500004ENtGo3kr0ge.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=C93B86C3B8BE08E280E90608D21B23CEBF221BBC23631D6D3268973957D0AB0DD340EEAE765C77FF130567F84C4F43AEC43B0C70A43DB269&uin=22213939&src=M5000017dAJu3TwUri.mp3&redirect=1&fromtag=111042","trackType":""} Jul 24 12:29:07 volumio volumio[10798]: verbose: CURRENT POSITION 0 Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::syncState stateService play Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::syncState currentStatus play Jul 24 12:29:07 volumio volumio[10798]: info: Received an update from plugin. extracting info from payload Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::pushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioPushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::pushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioPushState Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 12:29:07 volumio volumio[10798]: info: ------------------------------ 106ms Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioGetQueue Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::getQueue Jul 24 12:29:07 volumio volumio[10798]: info: CorePlayQueue::getQueue Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioGetQueue Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::getQueue Jul 24 12:29:07 volumio volumio[10798]: info: CorePlayQueue::getQueue Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioGetQueue Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::getQueue Jul 24 12:29:07 volumio volumio[10798]: info: CorePlayQueue::getQueue Jul 24 12:29:07 volumio volumio[10798]: info: CoreCommandRouter::volumioGetQueue Jul 24 12:29:07 volumio volumio[10798]: info: CoreStateMachine::getQueue Jul 24 12:29:07 volumio volumio[10798]: info: CorePlayQueue::getQueue Jul 24 12:29:17 volumio volumio[10798]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 12:29:17 volumio volumio[10798]: { Error: connect ETIMEDOUT 173.252.248.244:443 Jul 24 12:29:17 volumio volumio[10798]: at Object._errnoException (util.js:1022:11) Jul 24 12:29:17 volumio volumio[10798]: at _exceptionWithHostPort (util.js:1044:20) Jul 24 12:29:17 volumio volumio[10798]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 24 12:29:17 volumio volumio[10798]: code: 'ETIMEDOUT', Jul 24 12:29:17 volumio volumio[10798]: errno: 'ETIMEDOUT', Jul 24 12:29:17 volumio volumio[10798]: syscall: 'connect', Jul 24 12:29:17 volumio volumio[10798]: address: '173.252.248.244', Jul 24 12:29:17 volumio volumio[10798]: port: 443 } Jul 24 12:29:17 volumio volumio[10798]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 12:29:17 volumio sudo[11575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 12:28 Jul 24 12:29:17 volumio sudo[11575]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"