-- Logs begin at Wed 2022-12-14 12:36:47 UTC, end at Mon 2024-09-02 12:33:11 UTC. -- Sep 02 12:32:52 opera volumio[1096]: info: Sep 02 12:32:52 opera volumio[1096]: ---------------------------- MPD announces state update: player Sep 02 12:32:52 opera volumio[1096]: info: ControllerMpd::getState Sep 02 12:32:52 opera volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Sep 02 12:32:52 opera volumio[1096]: info: sendMpdCommand status took 0 milliseconds Sep 02 12:32:52 opera volumio[1096]: verbose: ControllerMpd::parseState Sep 02 12:32:52 opera volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 12:32:52 opera volumio[1096]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 02 12:32:52 opera volumio[1096]: verbose: ControllerMpd::parseTrackInfo Sep 02 12:32:52 opera volumio[1096]: info: ControllerMpd::pushState Sep 02 12:32:52 opera volumio[1096]: info: CoreCommandRouter::servicePushState Sep 02 12:32:52 opera volumio[1096]: info: CoreStateMachine::pushState Sep 02 12:32:52 opera volumio[1096]: info: CoreCommandRouter::volumioPushState Sep 02 12:32:52 opera volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 12:32:52 opera volumio[1096]: info: CorePlayQueue::getTrack 0 Sep 02 12:32:52 opera volumio[1096]: 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":"Aphrodite","artist":"S.E.N.S.","album":"GOLDEN☆BEST ～ SINGLES COLLECTION","uri":"http://isure6.stream.qqmusic.qq.com/M50000460GrA2wvDkk.mp3?guid=ffffffff940390ae0000018aef37fd87&vkey=0BCE807F65D4A85CD355A2FA32F63E198C9F464DA529217C6DD15790FAF7A20F49AC602D2BC3F29DA1BC69ADEF7400000CC3B2EEEAFDB4B3&uin=1152921504724187376&redirect=1&fromtag=111042","trackType":""} Sep 02 12:32:52 opera volumio[1096]: verbose: CURRENT POSITION 0 Sep 02 12:32:52 opera volumio[1096]: info: CoreStateMachine::syncState stateService stop Sep 02 12:32:52 opera volumio[1096]: info: CoreStateMachine::syncState currentStatus play Sep 02 12:32:52 opera volumio[1096]: info: CoreStateMachine::play index undefined Sep 02 12:32:52 opera volumio[1096]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 12:32:52 opera volumio[1096]: info: CoreStateMachine::pushState Sep 02 12:32:52 opera volumio[1096]: info: CorePlayQueue::getTrack 0 Sep 02 12:32:52 opera volumio[1096]: info: CoreCommandRouter::volumioPushState Sep 02 12:32:52 opera volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 12:32:52 opera volumio[1096]: info: CorePlayQueue::getTrack 0 Sep 02 12:32:52 opera volumio[1096]: info: ------------------------------ 13ms Sep 02 12:32:52 opera volumio[1096]: info: CoreCommandRouter::volumioGetQueue Sep 02 12:32:52 opera volumio[1096]: info: CoreStateMachine::getQueue Sep 02 12:32:52 opera volumio[1096]: info: CorePlayQueue::getQueue Sep 02 12:32:52 opera volumio[1096]: info: Clearing queue after UPNP request Sep 02 12:32:53 opera volumio[1096]: info: CoreStateMachine::ClearQueue Sep 02 12:32:53 opera volumio[1096]: info: CoreStateMachine::stop Sep 02 12:32:53 opera volumio[1096]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 12:32:53 opera volumio[1096]: verbose: UNSET VOLATILE Sep 02 12:32:53 opera volumio[1096]: info: CorePlayQueue::clearPlayQueue Sep 02 12:32:53 opera volumio[1096]: info: CorePlayQueue::saveQueue Sep 02 12:32:53 opera volumio[1096]: info: CoreCommandRouter::volumioPushState Sep 02 12:32:53 opera volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 12:32:53 opera volumio[1096]: info: CoreCommandRouter::volumioPushQueue Sep 02 12:32:53 opera volumio[1096]: info: Sep 02 12:32:53 opera volumio[1096]: ---------------------------- MPD announces system playlist update Sep 02 12:32:53 opera volumio[1096]: info: Ignoring MPD Status Update Sep 02 12:32:53 opera volumio[1096]: error: updateQueue error: null Sep 02 12:32:53 opera volumio[1096]: info: ------------------------------ 4ms Sep 02 12:32:53 opera volumio[1096]: info: Starting UPNP Playback Sep 02 12:32:53 opera volumio[1096]: info: Preparing playback through UPNP Sep 02 12:32:53 opera volumio[1096]: info: CoreCommandRouter::volumioGetState Sep 02 12:32:53 opera volumio[1096]: info: CorePlayQueue::getTrack 0 Sep 02 12:32:53 opera volumio[1096]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 02 12:32:53 opera volumio[1096]: info: Sep 02 12:32:53 opera volumio[1096]: ---------------------------- MPD announces system playlist update Sep 02 12:32:53 opera volumio[1096]: info: Ignoring MPD Status Update Sep 02 12:32:53 opera volumio[1096]: info: ------------------------------ 2ms Sep 02 12:32:53 opera volumio[1096]: info: Sep 02 12:32:53 opera volumio[1096]: ---------------------------- MPD announces system playlist update Sep 02 12:32:53 opera volumio[1096]: info: Ignoring MPD Status Update Sep 02 12:32:53 opera volumio[1096]: info: ------------------------------ 3ms Sep 02 12:32:53 opera volumio[1096]: info: Sep 02 12:32:53 opera volumio[1096]: ---------------------------- MPD announces system playlist update Sep 02 12:32:53 opera volumio[1096]: info: Ignoring MPD Status Update Sep 02 12:32:53 opera volumio[1096]: info: ------------------------------ 2ms Sep 02 12:32:53 opera volumio[1096]: info: Sep 02 12:32:53 opera volumio[1096]: ---------------------------- MPD announces system playlist update Sep 02 12:32:53 opera volumio[1096]: info: Ignoring MPD Status Update Sep 02 12:32:53 opera volumio[1096]: info: ------------------------------ 2ms Sep 02 12:32:53 opera volumio[1096]: info: Sep 02 12:32:53 opera volumio[1096]: ---------------------------- MPD announces system playlist update Sep 02 12:32:53 opera volumio[1096]: info: Ignoring MPD Status Update Sep 02 12:32:53 opera volumio[1096]: info: ------------------------------ 1ms Sep 02 12:32:53 opera volumio[1096]: info: Sep 02 12:32:53 opera volumio[1096]: ---------------------------- MPD announces system playlist update Sep 02 12:32:53 opera volumio[1096]: info: Ignoring MPD Status Update Sep 02 12:32:53 opera volumio[1096]: info: ------------------------------ 1ms Sep 02 12:32:54 opera volumio[1096]: info: Sep 02 12:32:54 opera volumio[1096]: ---------------------------- MPD announces state update: player Sep 02 12:32:54 opera volumio[1096]: info: ControllerMpd::getState Sep 02 12:32:54 opera volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Sep 02 12:32:54 opera volumio[1096]: info: sendMpdCommand status took 2 milliseconds Sep 02 12:32:54 opera volumio[1096]: verbose: ControllerMpd::parseState Sep 02 12:32:54 opera volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 12:32:54 opera volumio[1096]: info: Sep 02 12:32:54 opera volumio[1096]: ---------------------------- MPD announces system playlist update Sep 02 12:32:54 opera volumio[1096]: info: Ignoring MPD Status Update Sep 02 12:32:54 opera volumio[1096]: info: Sep 02 12:32:54 opera volumio[1096]: ---------------------------- MPD announces state update: player Sep 02 12:32:54 opera volumio[1096]: info: ControllerMpd::getState Sep 02 12:32:54 opera volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Sep 02 12:32:54 opera volumio[1096]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 02 12:32:54 opera volumio[1096]: verbose: ControllerMpd::parseTrackInfo Sep 02 12:32:54 opera volumio[1096]: info: ControllerMpd::pushState Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::servicePushState Sep 02 12:32:54 opera volumio[1096]: verbose: In UPNP mode Sep 02 12:32:54 opera volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":315,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"You Raise Me Up","artist":"Strikepoint","album":"Reflections","uri":"http://isure6.stream.qqmusic.qq.com/F0000035LLYW0g6kyq.flac?guid=ffffffff940390ae0000018aef37fd87&vkey=7AC2B80A6AB2757A830B18223F1D5BA5DE52097A0F83FE1C65C01D2F554CDBFE4D257C7F0425922116D468DFD72BC0D93C89D19E6022B5BA&uin=1152921504724187376&redirect=1&fromtag=111114","trackType":""} Sep 02 12:32:54 opera volumio[1096]: verbose: CURRENT POSITION 0 Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::syncState stateService play Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::pushState Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::volumioPushState Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 12:32:54 opera volumio[1096]: info: ------------------------------ 32ms Sep 02 12:32:54 opera volumio[1096]: info: ------------------------------ 31ms Sep 02 12:32:54 opera volumio[1096]: info: sendMpdCommand status took 30 milliseconds Sep 02 12:32:54 opera volumio[1096]: verbose: ControllerMpd::parseState Sep 02 12:32:54 opera volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 12:32:54 opera volumio[1096]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 02 12:32:54 opera volumio[1096]: verbose: ControllerMpd::parseTrackInfo Sep 02 12:32:54 opera volumio[1096]: info: ControllerMpd::pushState Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::servicePushState Sep 02 12:32:54 opera volumio[1096]: verbose: In UPNP mode Sep 02 12:32:54 opera volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":315,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"640 Kbps","isStreaming":false,"title":"You Raise Me Up","artist":"Strikepoint","album":"Reflections","uri":"http://isure6.stream.qqmusic.qq.com/F0000035LLYW0g6kyq.flac?guid=ffffffff940390ae0000018aef37fd87&vkey=7AC2B80A6AB2757A830B18223F1D5BA5DE52097A0F83FE1C65C01D2F554CDBFE4D257C7F0425922116D468DFD72BC0D93C89D19E6022B5BA&uin=1152921504724187376&redirect=1&fromtag=111114","trackType":""} Sep 02 12:32:54 opera volumio[1096]: verbose: CURRENT POSITION 0 Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::syncState stateService play Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::syncState currentStatus play Sep 02 12:32:54 opera volumio[1096]: info: Received an update from plugin. extracting info from payload Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::pushState Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::volumioPushState Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::pushState Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::volumioPushState Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 12:32:54 opera volumio[1096]: info: ------------------------------ 78ms Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::volumioGetQueue Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::getQueue Sep 02 12:32:54 opera volumio[1096]: info: CorePlayQueue::getQueue Sep 02 12:32:54 opera volumio[1096]: info: CoreCommandRouter::volumioGetQueue Sep 02 12:32:54 opera volumio[1096]: info: CoreStateMachine::getQueue Sep 02 12:32:54 opera volumio[1096]: info: CorePlayQueue::getQueue Sep 02 12:32:54 opera volumio[1096]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/S.E.N.S./GOLDEN%E2%98%86BEST%20%EF%BD%9E%20SINGLES%20COLLECTION/762b0c51-4fd7-4577-a3df-624be201f1f9.jpg' Sep 02 12:33:11 opera volumio[1096]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 12:33:11 opera volumio[1096]: { Error: connect ETIMEDOUT 116.89.243.8:443 Sep 02 12:33:11 opera volumio[1096]: at Object._errnoException (util.js:1022:11) Sep 02 12:33:11 opera volumio[1096]: at _exceptionWithHostPort (util.js:1044:20) Sep 02 12:33:11 opera volumio[1096]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 02 12:33:11 opera volumio[1096]: code: 'ETIMEDOUT', Sep 02 12:33:11 opera volumio[1096]: errno: 'ETIMEDOUT', Sep 02 12:33:11 opera volumio[1096]: syscall: 'connect', Sep 02 12:33:11 opera volumio[1096]: address: '116.89.243.8', Sep 02 12:33:11 opera volumio[1096]: port: 443 } Sep 02 12:33:11 opera volumio[1096]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 12:33:11 opera sudo[5062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 12:32 Sep 02 12:33:11 opera sudo[5062]: 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"