-- Logs begin at Wed 2022-12-14 12:36:47 UTC, end at Sat 2024-03-30 10:34:06 UTC. -- Mar 30 10:33:58 opera volumio[16063]: info: Mar 30 10:33:58 opera volumio[16063]: ---------------------------- MPD announces state update: player Mar 30 10:33:58 opera volumio[16063]: info: ControllerMpd::getState Mar 30 10:33:58 opera volumio[16063]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:33:58 opera volumio[16063]: info: sendMpdCommand status took 2 milliseconds Mar 30 10:33:58 opera volumio[16063]: verbose: ControllerMpd::parseState Mar 30 10:33:58 opera volumio[16063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:33:58 opera volumio[16063]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 30 10:33:58 opera volumio[16063]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:33:58 opera volumio[16063]: info: ControllerMpd::pushState Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::servicePushState Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::pushState Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::volumioPushState Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:33:58 opera volumio[16063]: info: CorePlayQueue::getTrack 0 Mar 30 10:33:58 opera volumio[16063]: 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://124.225.188.53/amobile.music.tc.qq.com/F000004VzfW32Walsk.flac?guid=ffffffff940390ae0000018aef37fd87&vkey=6393F791684C241BE20809AABB5B22F0120A9A0AA7897E2719795255E431A791A0F09D51F087A587EF9168A95463135754D615388D85412D&uin=1152921504724187376&src=F000003I9EzD3gKhDi.flac&redirect=1&fromtag=111114","trackType":""} Mar 30 10:33:58 opera volumio[16063]: verbose: CURRENT POSITION 0 Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::syncState stateService stop Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::play index undefined Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::pushState Mar 30 10:33:58 opera volumio[16063]: info: CorePlayQueue::getTrack 0 Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::volumioPushState Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:33:58 opera volumio[16063]: info: CorePlayQueue::getTrack 0 Mar 30 10:33:58 opera volumio[16063]: info: ------------------------------ 19ms Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::getQueue Mar 30 10:33:58 opera volumio[16063]: info: CorePlayQueue::getQueue Mar 30 10:33:58 opera volumio[16063]: info: Clearing queue after UPNP request Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::ClearQueue Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::stop Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 30 10:33:58 opera volumio[16063]: verbose: UNSET VOLATILE Mar 30 10:33:58 opera volumio[16063]: info: CorePlayQueue::clearPlayQueue Mar 30 10:33:58 opera volumio[16063]: info: CorePlayQueue::saveQueue Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::volumioPushState Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::volumioPushQueue Mar 30 10:33:58 opera volumio[16063]: info: Mar 30 10:33:58 opera volumio[16063]: ---------------------------- MPD announces system playlist update Mar 30 10:33:58 opera volumio[16063]: info: Ignoring MPD Status Update Mar 30 10:33:58 opera volumio[16063]: error: updateQueue error: null Mar 30 10:33:58 opera volumio[16063]: info: ------------------------------ 2ms Mar 30 10:33:58 opera volumio[16063]: info: Starting UPNP Playback Mar 30 10:33:58 opera volumio[16063]: info: Preparing playback through UPNP Mar 30 10:33:58 opera volumio[16063]: info: CoreCommandRouter::volumioGetState Mar 30 10:33:58 opera volumio[16063]: info: CorePlayQueue::getTrack 0 Mar 30 10:33:58 opera volumio[16063]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 30 10:33:59 opera volumio[16063]: info: Mar 30 10:33:59 opera volumio[16063]: ---------------------------- MPD announces system playlist update Mar 30 10:33:59 opera volumio[16063]: info: Ignoring MPD Status Update Mar 30 10:33:59 opera volumio[16063]: info: Mar 30 10:33:59 opera volumio[16063]: ---------------------------- MPD announces system playlist update Mar 30 10:33:59 opera volumio[16063]: info: Ignoring MPD Status Update Mar 30 10:33:59 opera volumio[16063]: info: ------------------------------ 2ms Mar 30 10:33:59 opera volumio[16063]: info: Mar 30 10:33:59 opera volumio[16063]: ---------------------------- MPD announces system playlist update Mar 30 10:33:59 opera volumio[16063]: info: Ignoring MPD Status Update Mar 30 10:33:59 opera volumio[16063]: info: ------------------------------ 2ms Mar 30 10:33:59 opera volumio[16063]: info: Mar 30 10:33:59 opera volumio[16063]: ---------------------------- MPD announces system playlist update Mar 30 10:33:59 opera volumio[16063]: info: Ignoring MPD Status Update Mar 30 10:33:59 opera volumio[16063]: info: ------------------------------ 1ms Mar 30 10:33:59 opera volumio[16063]: info: ------------------------------ 1ms Mar 30 10:33:59 opera volumio[16063]: info: Mar 30 10:33:59 opera volumio[16063]: ---------------------------- MPD announces system playlist update Mar 30 10:33:59 opera volumio[16063]: info: Ignoring MPD Status Update Mar 30 10:33:59 opera volumio[16063]: info: ------------------------------ 1ms Mar 30 10:33:59 opera volumio[16063]: info: Mar 30 10:33:59 opera volumio[16063]: ---------------------------- MPD announces system playlist update Mar 30 10:33:59 opera volumio[16063]: info: Ignoring MPD Status Update Mar 30 10:33:59 opera volumio[16063]: info: ------------------------------ 1ms Mar 30 10:33:59 opera volumio[16063]: info: Mar 30 10:33:59 opera volumio[16063]: ---------------------------- MPD announces state update: player Mar 30 10:33:59 opera volumio[16063]: info: ControllerMpd::getState Mar 30 10:33:59 opera volumio[16063]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:34:00 opera volumio[16063]: info: Mar 30 10:34:00 opera volumio[16063]: ---------------------------- MPD announces state update: player Mar 30 10:34:00 opera volumio[16063]: info: sendMpdCommand status took 123 milliseconds Mar 30 10:34:00 opera volumio[16063]: info: ControllerMpd::getState Mar 30 10:34:00 opera volumio[16063]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:34:00 opera volumio[16063]: verbose: ControllerMpd::parseState Mar 30 10:34:00 opera volumio[16063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:34:00 opera volumio[16063]: info: Mar 30 10:34:00 opera volumio[16063]: ---------------------------- MPD announces system playlist update Mar 30 10:34:00 opera volumio[16063]: info: Ignoring MPD Status Update Mar 30 10:34:00 opera volumio[16063]: info: sendMpdCommand status took 4 milliseconds Mar 30 10:34:00 opera volumio[16063]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 30 10:34:00 opera volumio[16063]: verbose: ControllerMpd::parseState Mar 30 10:34:00 opera volumio[16063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:34:00 opera volumio[16063]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:34:00 opera volumio[16063]: info: ControllerMpd::pushState Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::servicePushState Mar 30 10:34:00 opera volumio[16063]: verbose: In UPNP mode Mar 30 10:34:00 opera volumio[16063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"尘鼓","artist":"何训田","album":"波罗密多","uri":"http://124.225.188.53/amobile.music.tc.qq.com/F0000027t1oX0Z62vT.flac?guid=ffffffff940390ae0000018aef37fd87&vkey=CB967CAFA5D3519ED7CC40996B5636EADFED47B0056FB07A55EA1A829363832A85B654A33CE13C8E7042942867390A37D4923E299E45E173&uin=1152921504724187376&redirect=1&fromtag=111114","trackType":""} Mar 30 10:34:00 opera volumio[16063]: verbose: CURRENT POSITION 0 Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::syncState stateService play Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::syncState currentStatus stop Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::pushState Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::volumioPushState Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:34:00 opera volumio[16063]: info: ------------------------------ 138ms Mar 30 10:34:00 opera volumio[16063]: info: ------------------------------ 12ms Mar 30 10:34:00 opera volumio[16063]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 30 10:34:00 opera volumio[16063]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:34:00 opera volumio[16063]: info: ControllerMpd::pushState Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::servicePushState Mar 30 10:34:00 opera volumio[16063]: verbose: In UPNP mode Mar 30 10:34:00 opera volumio[16063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"574 Kbps","isStreaming":false,"title":"尘鼓","artist":"何训田","album":"波罗密多","uri":"http://124.225.188.53/amobile.music.tc.qq.com/F0000027t1oX0Z62vT.flac?guid=ffffffff940390ae0000018aef37fd87&vkey=CB967CAFA5D3519ED7CC40996B5636EADFED47B0056FB07A55EA1A829363832A85B654A33CE13C8E7042942867390A37D4923E299E45E173&uin=1152921504724187376&redirect=1&fromtag=111114","trackType":""} Mar 30 10:34:00 opera volumio[16063]: verbose: CURRENT POSITION 0 Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::syncState stateService play Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:34:00 opera volumio[16063]: info: Received an update from plugin. extracting info from payload Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::pushState Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::volumioPushState Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::pushState Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::volumioPushState Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:34:00 opera volumio[16063]: info: ------------------------------ 34ms Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::getQueue Mar 30 10:34:00 opera volumio[16063]: info: CorePlayQueue::getQueue Mar 30 10:34:00 opera volumio[16063]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:34:00 opera volumio[16063]: info: CoreStateMachine::getQueue Mar 30 10:34:00 opera volumio[16063]: info: CorePlayQueue::getQueue Mar 30 10:34:00 opera volumio[16063]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9F%B3%E9%9D%92%E7%91%B6/%E5%85%B0%E9%99%B5%E7%8E%8B%E5%85%A5%E9%98%B5%E6%9B%B2/f0de1213-d8de-43cb-9c04-6f03abbb4ebc.jpg' Mar 30 10:34:06 opera volumio[16063]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 10:34:06 opera volumio[16063]: { Error: connect ETIMEDOUT 69.171.242.11:443 Mar 30 10:34:06 opera volumio[16063]: at Object._errnoException (util.js:1022:11) Mar 30 10:34:06 opera volumio[16063]: at _exceptionWithHostPort (util.js:1044:20) Mar 30 10:34:06 opera volumio[16063]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Mar 30 10:34:06 opera volumio[16063]: code: 'ETIMEDOUT', Mar 30 10:34:06 opera volumio[16063]: errno: 'ETIMEDOUT', Mar 30 10:34:06 opera volumio[16063]: syscall: 'connect', Mar 30 10:34:06 opera volumio[16063]: address: '69.171.242.11', Mar 30 10:34:06 opera volumio[16063]: port: 443 } Mar 30 10:34:06 opera volumio[16063]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 10:34:06 opera sudo[17588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 10:33 Mar 30 10:34:06 opera sudo[17588]: 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"