-- Logs begin at Fri 2024-04-05 14:06:16 UTC, end at Fri 2024-04-05 14:14:19 UTC. -- Apr 05 14:13:56 volumio volumio[938]: info: Apr 05 14:13:56 volumio volumio[938]: ---------------------------- MPD announces state update: player Apr 05 14:13:56 volumio volumio[938]: info: ControllerMpd::getState Apr 05 14:13:56 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:13:56 volumio volumio[938]: info: sendMpdCommand status took 3 milliseconds Apr 05 14:13:56 volumio volumio[938]: verbose: ControllerMpd::parseState Apr 05 14:13:56 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:13:56 volumio volumio[938]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 05 14:13:56 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:13:56 volumio volumio[938]: info: ControllerMpd::pushState Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::servicePushState Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::pushState Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::volumioPushState Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:13:56 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Apr 05 14:13:56 volumio volumio[938]: 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":"Trouble Is A Friend (cover: Lenka)","artist":"张琰","album":"张琰（琰子）木吉他独奏","uri":"http://sjy6.stream.qqmusic.qq.com/M500004WOpQU3snahg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=22EAF983C7537A299D9355007D8EA9B290B004E7C9994B5ABDBE3657A667F13BB8355BA49F1B6E60DC5937173724F2A77CCF425232A463D6&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 05 14:13:56 volumio volumio[938]: verbose: CURRENT POSITION 0 Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::syncState stateService stop Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::volumioPushState Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::stPlaybackTimer Apr 05 14:13:56 volumio volumio[938]: info: ------------------------------ 20ms Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::volumioGetQueue Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::getQueue Apr 05 14:13:56 volumio volumio[938]: info: CorePlayQueue::getQueue Apr 05 14:13:56 volumio volumio[938]: info: Clearing queue after UPNP request Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::ClearQueue Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::stop Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:13:56 volumio volumio[938]: verbose: UNSET VOLATILE Apr 05 14:13:56 volumio volumio[938]: info: CorePlayQueue::clearPlayQueue Apr 05 14:13:56 volumio volumio[938]: info: CorePlayQueue::saveQueue Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::volumioPushState Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:13:56 volumio volumio[938]: info: Apr 05 14:13:56 volumio volumio[938]: ---------------------------- MPD announces system playlist update Apr 05 14:13:56 volumio volumio[938]: info: Ignoring MPD Status Update Apr 05 14:13:56 volumio volumio[938]: error: updateQueue error: null Apr 05 14:13:56 volumio volumio[938]: info: ------------------------------ 1ms Apr 05 14:13:56 volumio volumio[938]: info: Starting UPNP Playback Apr 05 14:13:56 volumio volumio[938]: info: Preparing playback through UPNP Apr 05 14:13:56 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Apr 05 14:13:56 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Apr 05 14:13:56 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces system playlist update Apr 05 14:13:57 volumio volumio[938]: info: Ignoring MPD Status Update Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces system playlist update Apr 05 14:13:57 volumio volumio[938]: info: Ignoring MPD Status Update Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 2ms Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces system playlist update Apr 05 14:13:57 volumio volumio[938]: info: Ignoring MPD Status Update Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 2ms Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 1ms Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces system playlist update Apr 05 14:13:57 volumio volumio[938]: info: Ignoring MPD Status Update Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 2ms Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces system playlist update Apr 05 14:13:57 volumio volumio[938]: info: Ignoring MPD Status Update Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 1ms Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces system playlist update Apr 05 14:13:57 volumio volumio[938]: info: Ignoring MPD Status Update Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 1ms Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces state update: player Apr 05 14:13:57 volumio volumio[938]: info: ControllerMpd::getState Apr 05 14:13:57 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:13:57 volumio volumio[938]: info: sendMpdCommand status took 4 milliseconds Apr 05 14:13:57 volumio volumio[938]: verbose: ControllerMpd::parseState Apr 05 14:13:57 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces state update: player Apr 05 14:13:57 volumio volumio[938]: info: ControllerMpd::getState Apr 05 14:13:57 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:13:57 volumio volumio[938]: info: Apr 05 14:13:57 volumio volumio[938]: ---------------------------- MPD announces system playlist update Apr 05 14:13:57 volumio volumio[938]: info: Ignoring MPD Status Update Apr 05 14:13:57 volumio volumio[938]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 05 14:13:57 volumio volumio[938]: info: sendMpdCommand status took 8 milliseconds Apr 05 14:13:57 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:13:57 volumio volumio[938]: verbose: ControllerMpd::parseState Apr 05 14:13:57 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:13:57 volumio volumio[938]: info: ControllerMpd::pushState Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::servicePushState Apr 05 14:13:57 volumio volumio[938]: verbose: In UPNP mode Apr 05 14:13:57 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","uri":"http://sjy6.stream.qqmusic.qq.com/M500002hRqyH4MKpvR.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=42A2B4BF499DA2608D5CAB5AE863F182018723918C84013FD857183C4E214DC898BB54A2810F887A570A78EC4D4327E06FBCB864B9C8E661&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 05 14:13:57 volumio volumio[938]: verbose: CURRENT POSITION 0 Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::syncState stateService play Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::pushState Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::volumioPushState Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 60ms Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 40ms Apr 05 14:13:57 volumio volumio[938]: info: sendMpdCommand playlistinfo took 46 milliseconds Apr 05 14:13:57 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:13:57 volumio volumio[938]: info: ControllerMpd::pushState Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::servicePushState Apr 05 14:13:57 volumio volumio[938]: verbose: In UPNP mode Apr 05 14:13:57 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","uri":"http://sjy6.stream.qqmusic.qq.com/M500002hRqyH4MKpvR.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=42A2B4BF499DA2608D5CAB5AE863F182018723918C84013FD857183C4E214DC898BB54A2810F887A570A78EC4D4327E06FBCB864B9C8E661&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 05 14:13:57 volumio volumio[938]: verbose: CURRENT POSITION 0 Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::syncState stateService play Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play Apr 05 14:13:57 volumio volumio[938]: info: Received an update from plugin. extracting info from payload Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::pushState Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::volumioPushState Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::pushState Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::volumioPushState Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:13:57 volumio volumio[938]: info: ------------------------------ 92ms Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::volumioGetQueue Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::getQueue Apr 05 14:13:57 volumio volumio[938]: info: CorePlayQueue::getQueue Apr 05 14:13:57 volumio volumio[938]: info: CoreCommandRouter::volumioGetQueue Apr 05 14:13:57 volumio volumio[938]: info: CoreStateMachine::getQueue Apr 05 14:13:57 volumio volumio[938]: info: CorePlayQueue::getQueue Apr 05 14:14:18 volumio volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 14:14:18 volumio volumio[938]: { Error: connect ETIMEDOUT 108.160.162.109:443 Apr 05 14:14:18 volumio volumio[938]: at Object._errnoException (util.js:1022:11) Apr 05 14:14:18 volumio volumio[938]: at _exceptionWithHostPort (util.js:1044:20) Apr 05 14:14:19 volumio volumio[938]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 05 14:14:19 volumio volumio[938]: code: 'ETIMEDOUT', Apr 05 14:14:19 volumio volumio[938]: errno: 'ETIMEDOUT', Apr 05 14:14:19 volumio volumio[938]: syscall: 'connect', Apr 05 14:14:19 volumio volumio[938]: address: '108.160.162.109', Apr 05 14:14:19 volumio volumio[938]: port: 443 } Apr 05 14:14:19 volumio volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 14:14:19 volumio sudo[2585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-05 14:13 Apr 05 14:14:19 volumio sudo[2585]: 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"