-- Logs begin at Fri 2024-07-12 07:45:05 UTC, end at Thu 2025-09-11 16:41:34 UTC. -- Sep 11 16:41:16 opera volumio[13462]: info: Sep 11 16:41:16 opera volumio[13462]: ---------------------------- MPD announces state update: player Sep 11 16:41:16 opera volumio[13462]: info: ControllerMpd::getState Sep 11 16:41:16 opera volumio[13462]: verbose: ControllerMpd::sendMpdCommand status Sep 11 16:41:16 opera volumio[13462]: info: sendMpdCommand status took 8 milliseconds Sep 11 16:41:16 opera volumio[13462]: verbose: ControllerMpd::parseState Sep 11 16:41:16 opera volumio[13462]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 16:41:16 opera volumio[13462]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 16:41:16 opera volumio[13462]: verbose: ControllerMpd::parseTrackInfo Sep 11 16:41:16 opera volumio[13462]: info: ControllerMpd::pushState Sep 11 16:41:16 opera volumio[13462]: info: CoreCommandRouter::servicePushState Sep 11 16:41:16 opera volumio[13462]: info: CoreStateMachine::pushState Sep 11 16:41:16 opera volumio[13462]: info: CoreCommandRouter::volumioPushState Sep 11 16:41:16 opera volumio[13462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 16:41:16 opera volumio[13462]: info: CorePlayQueue::getTrack 0 Sep 11 16:41:16 opera volumio[13462]: 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":"Implora","artist":"Ecosound","album":"Moonlight Elay Pue (Musica andina e peruviana)","uri":"http://aqqmusic.tc.qq.com/F000000OSkHk3mRm2K.flac?guid=000000005b56b722000001937137c5ff&vkey=B12E9AF967B6669A18628CA2F916D19ABD142FD6C8057CC0F525A792CFBD49BFA13837EE4AF566016D0C66056BAF86E72AEE642A3590E545__v2b9aba46&uin=1152921504950983260&src=F000003PK7Nr3rF9Jf.flac&redirect=1&fromtag=111114","trackType":""} Sep 11 16:41:16 opera volumio[13462]: verbose: CURRENT POSITION 0 Sep 11 16:41:16 opera volumio[13462]: info: CoreStateMachine::syncState stateService stop Sep 11 16:41:16 opera volumio[13462]: info: CoreStateMachine::syncState currentStatus play Sep 11 16:41:16 opera volumio[13462]: info: CoreStateMachine::play index undefined Sep 11 16:41:16 opera volumio[13462]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 16:41:16 opera volumio[13462]: info: CoreStateMachine::pushState Sep 11 16:41:16 opera volumio[13462]: info: CorePlayQueue::getTrack 0 Sep 11 16:41:16 opera volumio[13462]: info: CoreCommandRouter::volumioPushState Sep 11 16:41:16 opera volumio[13462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 16:41:16 opera volumio[13462]: info: CorePlayQueue::getTrack 0 Sep 11 16:41:16 opera volumio[13462]: info: ------------------------------ 34ms Sep 11 16:41:16 opera volumio[13462]: info: Updating RAAT Signal Path Sep 11 16:41:16 opera volumio[13462]: info: Updating RAAT Signal Path Sep 11 16:41:16 opera volumio[13462]: info: CoreCommandRouter::volumioGetQueue Sep 11 16:41:16 opera volumio[13462]: info: CoreStateMachine::getQueue Sep 11 16:41:16 opera volumio[13462]: info: CorePlayQueue::getQueue Sep 11 16:41:16 opera volumio[13462]: info: Clearing queue after UPNP request Sep 11 16:41:17 opera volumio[13462]: info: CoreStateMachine::ClearQueue Sep 11 16:41:17 opera volumio[13462]: info: CoreStateMachine::stop Sep 11 16:41:17 opera volumio[13462]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 16:41:17 opera volumio[13462]: verbose: UNSET VOLATILE Sep 11 16:41:17 opera volumio[13462]: info: CorePlayQueue::clearPlayQueue Sep 11 16:41:17 opera volumio[13462]: info: CorePlayQueue::saveQueue Sep 11 16:41:17 opera volumio[13462]: info: CoreCommandRouter::volumioPushState Sep 11 16:41:17 opera volumio[13462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 16:41:17 opera volumio[13462]: info: CoreCommandRouter::volumioPushQueue Sep 11 16:41:17 opera volumio[13462]: info: Updating RAAT Signal Path Sep 11 16:41:17 opera volumio[13462]: info: Sep 11 16:41:17 opera volumio[13462]: ---------------------------- MPD announces system playlist update Sep 11 16:41:17 opera volumio[13462]: info: Ignoring MPD Status Update Sep 11 16:41:17 opera volumio[13462]: error: updateQueue error: null Sep 11 16:41:17 opera volumio[13462]: info: ------------------------------ 2ms Sep 11 16:41:17 opera volumio[13462]: info: Starting UPNP Playback Sep 11 16:41:17 opera volumio[13462]: info: Preparing playback through UPNP Sep 11 16:41:17 opera volumio[13462]: info: CoreCommandRouter::volumioGetState Sep 11 16:41:17 opera volumio[13462]: info: CorePlayQueue::getTrack 0 Sep 11 16:41:17 opera volumio[13462]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 16:41:17 opera volumio[13462]: info: Sep 11 16:41:17 opera volumio[13462]: ---------------------------- MPD announces system playlist update Sep 11 16:41:17 opera volumio[13462]: info: Ignoring MPD Status Update Sep 11 16:41:17 opera volumio[13462]: info: ------------------------------ 0ms Sep 11 16:41:17 opera volumio[13462]: info: Sep 11 16:41:17 opera volumio[13462]: ---------------------------- MPD announces system playlist update Sep 11 16:41:17 opera volumio[13462]: info: Ignoring MPD Status Update Sep 11 16:41:17 opera volumio[13462]: info: ------------------------------ 1ms Sep 11 16:41:17 opera volumio[13462]: info: Sep 11 16:41:17 opera volumio[13462]: ---------------------------- MPD announces system playlist update Sep 11 16:41:17 opera volumio[13462]: info: Ignoring MPD Status Update Sep 11 16:41:17 opera volumio[13462]: info: Sep 11 16:41:17 opera volumio[13462]: ---------------------------- MPD announces system playlist update Sep 11 16:41:17 opera volumio[13462]: info: Ignoring MPD Status Update Sep 11 16:41:17 opera volumio[13462]: info: ------------------------------ 1ms Sep 11 16:41:17 opera volumio[13462]: info: ------------------------------ 1ms Sep 11 16:41:17 opera volumio[13462]: info: Sep 11 16:41:17 opera volumio[13462]: ---------------------------- MPD announces system playlist update Sep 11 16:41:17 opera volumio[13462]: info: Ignoring MPD Status Update Sep 11 16:41:17 opera volumio[13462]: info: Sep 11 16:41:17 opera volumio[13462]: ---------------------------- MPD announces system playlist update Sep 11 16:41:17 opera volumio[13462]: info: Ignoring MPD Status Update Sep 11 16:41:17 opera volumio[13462]: info: ------------------------------ 1ms Sep 11 16:41:17 opera volumio[13462]: info: ------------------------------ 1ms Sep 11 16:41:19 opera volumio[13462]: info: Sep 11 16:41:19 opera volumio[13462]: ---------------------------- MPD announces state update: player Sep 11 16:41:19 opera volumio[13462]: info: ControllerMpd::getState Sep 11 16:41:19 opera volumio[13462]: verbose: ControllerMpd::sendMpdCommand status Sep 11 16:41:19 opera volumio[13462]: info: Sep 11 16:41:19 opera volumio[13462]: ---------------------------- MPD announces state update: player Sep 11 16:41:19 opera volumio[13462]: info: sendMpdCommand status took 6 milliseconds Sep 11 16:41:19 opera volumio[13462]: info: ControllerMpd::getState Sep 11 16:41:19 opera volumio[13462]: verbose: ControllerMpd::sendMpdCommand status Sep 11 16:41:19 opera volumio[13462]: verbose: ControllerMpd::parseState Sep 11 16:41:19 opera volumio[13462]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 16:41:19 opera volumio[13462]: info: sendMpdCommand status took 1 milliseconds Sep 11 16:41:19 opera volumio[13462]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 16:41:19 opera volumio[13462]: verbose: ControllerMpd::parseState Sep 11 16:41:19 opera volumio[13462]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 16:41:19 opera volumio[13462]: verbose: ControllerMpd::parseTrackInfo Sep 11 16:41:19 opera volumio[13462]: info: ControllerMpd::pushState Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::servicePushState Sep 11 16:41:19 opera volumio[13462]: verbose: In UPNP mode Sep 11 16:41:19 opera volumio[13462]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Implora","artist":"Duo Mayenos","album":"Implora – Les plus belles mélodies sud-américaines","uri":"http://aqqmusic.tc.qq.com/F000001ws8vc2ZSIMU.flac?guid=000000005b56b722000001937137c5ff&vkey=751119923753F7AF87EEAC141A3817F90F2D207A1CCB36C35DF0B48A2E1ACBA93A275435AE69E93A4BC9C590901DBDE3EC3ACD0987E4BC64__v21e2a1e5c&uin=1152921504950983260&src=F0000034bR1h4emVKN.flac&redirect=1&fromtag=111114","trackType":""} Sep 11 16:41:19 opera volumio[13462]: verbose: CURRENT POSITION 0 Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::syncState stateService play Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::syncState currentStatus stop Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::pushState Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::volumioPushState Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 16:41:19 opera volumio[13462]: info: ------------------------------ 21ms Sep 11 16:41:19 opera volumio[13462]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 11 16:41:19 opera volumio[13462]: verbose: ControllerMpd::parseTrackInfo Sep 11 16:41:19 opera volumio[13462]: info: ControllerMpd::pushState Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::servicePushState Sep 11 16:41:19 opera volumio[13462]: verbose: In UPNP mode Sep 11 16:41:19 opera volumio[13462]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Implora","artist":"Duo Mayenos","album":"Implora – Les plus belles mélodies sud-américaines","uri":"http://aqqmusic.tc.qq.com/F000001ws8vc2ZSIMU.flac?guid=000000005b56b722000001937137c5ff&vkey=751119923753F7AF87EEAC141A3817F90F2D207A1CCB36C35DF0B48A2E1ACBA93A275435AE69E93A4BC9C590901DBDE3EC3ACD0987E4BC64__v21e2a1e5c&uin=1152921504950983260&src=F0000034bR1h4emVKN.flac&redirect=1&fromtag=111114","trackType":""} Sep 11 16:41:19 opera volumio[13462]: verbose: CURRENT POSITION 0 Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::syncState stateService play Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::syncState currentStatus play Sep 11 16:41:19 opera volumio[13462]: info: Received an update from plugin. extracting info from payload Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::pushState Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::volumioPushState Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::pushState Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::volumioPushState Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 16:41:19 opera volumio[13462]: info: ------------------------------ 25ms Sep 11 16:41:19 opera volumio[13462]: info: Updating RAAT Signal Path Sep 11 16:41:19 opera volumio[13462]: info: Updating RAAT Signal Path Sep 11 16:41:19 opera volumio[13462]: info: Updating RAAT Signal Path Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::volumioGetQueue Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::getQueue Sep 11 16:41:19 opera volumio[13462]: info: CorePlayQueue::getQueue Sep 11 16:41:19 opera volumio[13462]: info: CoreCommandRouter::volumioGetQueue Sep 11 16:41:19 opera volumio[13462]: info: CoreStateMachine::getQueue Sep 11 16:41:19 opera volumio[13462]: info: CorePlayQueue::getQueue Sep 11 16:41:19 opera volumio[13462]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ecosound/Moonlight%20Elay%20Pue%20(Musica%20andina%20e%20peruviana)/f1047ef3-cd0e-49dd-9557-f532a1824b77.jpg' Sep 11 16:41:33 opera volumio[13462]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 16:41:33 opera volumio[13462]: { Error: connect ETIMEDOUT 69.63.176.143:443 Sep 11 16:41:33 opera volumio[13462]: at Object._errnoException (util.js:1022:11) Sep 11 16:41:33 opera volumio[13462]: at _exceptionWithHostPort (util.js:1044:20) Sep 11 16:41:33 opera volumio[13462]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 11 16:41:33 opera volumio[13462]: code: 'ETIMEDOUT', Sep 11 16:41:33 opera volumio[13462]: errno: 'ETIMEDOUT', Sep 11 16:41:33 opera volumio[13462]: syscall: 'connect', Sep 11 16:41:33 opera volumio[13462]: address: '69.63.176.143', Sep 11 16:41:33 opera volumio[13462]: port: 443 } Sep 11 16:41:33 opera volumio[13462]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 16:41:34 opera sudo[20989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 16:40 Sep 11 16:41:34 opera sudo[20989]: 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="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"