-- Logs begin at Mon 2024-03-25 15:04:43 UTC, end at Sun 2024-03-31 12:05:45 UTC. -- Mar 31 12:04:26 opera volumio[15795]: info: Mar 31 12:04:26 opera volumio[15795]: ---------------------------- MPD announces state update: player Mar 31 12:04:26 opera volumio[15795]: info: ControllerMpd::getState Mar 31 12:04:26 opera volumio[15795]: verbose: ControllerMpd::sendMpdCommand status Mar 31 12:04:26 opera volumio[15795]: info: sendMpdCommand status took 2 milliseconds Mar 31 12:04:26 opera volumio[15795]: verbose: ControllerMpd::parseState Mar 31 12:04:26 opera volumio[15795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 12:04:26 opera volumio[15795]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 31 12:04:26 opera volumio[15795]: verbose: ControllerMpd::parseTrackInfo Mar 31 12:04:26 opera volumio[15795]: info: ControllerMpd::pushState Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::servicePushState Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::pushState Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::volumioPushState Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 12:04:26 opera volumio[15795]: info: CorePlayQueue::getTrack 0 Mar 31 12:04:26 opera volumio[15795]: 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://113.215.236.144/amobile.music.tc.qq.com/M800002Ntk4f3ghF8z.mp3?guid=000000001465bbe0000001784427ee14&vkey=F92775C7C69FEC9DA965491D3BC9E7FC73C211C3A65BD9BE9BA1CB131BAE06A7F40991275B104794DC3A2EB61D37016C5B008593384BBAB5&uin=1959889&fromtag=111093","trackType":""} Mar 31 12:04:26 opera volumio[15795]: verbose: CURRENT POSITION 0 Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::syncState stateService stop Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::syncState currentStatus play Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::volumioPushState Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::stPlaybackTimer Mar 31 12:04:26 opera volumio[15795]: info: ------------------------------ 20ms Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::volumioGetQueue Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::getQueue Mar 31 12:04:26 opera volumio[15795]: info: CorePlayQueue::getQueue Mar 31 12:04:26 opera volumio[15795]: info: Updating RAAT Signal Path Mar 31 12:04:26 opera volumio[15795]: info: Updating RAAT Signal Path Mar 31 12:04:26 opera volumio[15795]: info: Clearing queue after UPNP request Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::ClearQueue Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::stop Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 12:04:26 opera volumio[15795]: verbose: UNSET VOLATILE Mar 31 12:04:26 opera volumio[15795]: info: CorePlayQueue::clearPlayQueue Mar 31 12:04:26 opera volumio[15795]: info: CorePlayQueue::saveQueue Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::volumioPushState Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::volumioPushQueue Mar 31 12:04:26 opera volumio[15795]: info: Updating RAAT Signal Path Mar 31 12:04:26 opera volumio[15795]: info: Mar 31 12:04:26 opera volumio[15795]: ---------------------------- MPD announces system playlist update Mar 31 12:04:26 opera volumio[15795]: info: Ignoring MPD Status Update Mar 31 12:04:26 opera volumio[15795]: error: updateQueue error: null Mar 31 12:04:26 opera volumio[15795]: info: ------------------------------ 1ms Mar 31 12:04:26 opera volumio[15795]: info: Starting UPNP Playback Mar 31 12:04:26 opera volumio[15795]: info: Preparing playback through UPNP Mar 31 12:04:26 opera volumio[15795]: info: CoreCommandRouter::volumioGetState Mar 31 12:04:26 opera volumio[15795]: info: CorePlayQueue::getTrack 0 Mar 31 12:04:26 opera volumio[15795]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces system playlist update Mar 31 12:04:27 opera volumio[15795]: info: Ignoring MPD Status Update Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 1ms Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces system playlist update Mar 31 12:04:27 opera volumio[15795]: info: Ignoring MPD Status Update Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 1ms Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces system playlist update Mar 31 12:04:27 opera volumio[15795]: info: Ignoring MPD Status Update Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces system playlist update Mar 31 12:04:27 opera volumio[15795]: info: Ignoring MPD Status Update Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 2ms Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 2ms Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces system playlist update Mar 31 12:04:27 opera volumio[15795]: info: Ignoring MPD Status Update Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces system playlist update Mar 31 12:04:27 opera volumio[15795]: info: Ignoring MPD Status Update Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 2ms Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 1ms Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces state update: player Mar 31 12:04:27 opera volumio[15795]: info: ControllerMpd::getState Mar 31 12:04:27 opera volumio[15795]: verbose: ControllerMpd::sendMpdCommand status Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces state update: player Mar 31 12:04:27 opera volumio[15795]: info: sendMpdCommand status took 3 milliseconds Mar 31 12:04:27 opera volumio[15795]: info: ControllerMpd::getState Mar 31 12:04:27 opera volumio[15795]: verbose: ControllerMpd::sendMpdCommand status Mar 31 12:04:27 opera volumio[15795]: verbose: ControllerMpd::parseState Mar 31 12:04:27 opera volumio[15795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 12:04:27 opera volumio[15795]: info: Mar 31 12:04:27 opera volumio[15795]: ---------------------------- MPD announces system playlist update Mar 31 12:04:27 opera volumio[15795]: info: Ignoring MPD Status Update Mar 31 12:04:27 opera volumio[15795]: info: sendMpdCommand status took 2 milliseconds Mar 31 12:04:27 opera volumio[15795]: verbose: ControllerMpd::parseState Mar 31 12:04:27 opera volumio[15795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 12:04:27 opera volumio[15795]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 3ms Mar 31 12:04:27 opera volumio[15795]: verbose: ControllerMpd::parseTrackInfo Mar 31 12:04:27 opera volumio[15795]: info: ControllerMpd::pushState Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::servicePushState Mar 31 12:04:27 opera volumio[15795]: verbose: In UPNP mode Mar 31 12:04:27 opera volumio[15795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无情","artist":"若心","album":"无情","uri":"http://113.215.236.144/amobile.music.tc.qq.com/M800002jyCYF2EaQZp.mp3?guid=000000001465bbe0000001784427ee14&vkey=043F335F89902BDBA3F6F4971C46CE0EE92FD93F80C63A3697465F19278AE42A404C6E289F474318E3F28FEAD87AB08EF8B9033C27364E83&uin=1959889&fromtag=111093","trackType":""} Mar 31 12:04:27 opera volumio[15795]: verbose: CURRENT POSITION 0 Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::syncState stateService play Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::syncState currentStatus stop Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::pushState Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::volumioPushState Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 23ms Mar 31 12:04:27 opera volumio[15795]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 31 12:04:27 opera volumio[15795]: verbose: ControllerMpd::parseTrackInfo Mar 31 12:04:27 opera volumio[15795]: info: ControllerMpd::pushState Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::servicePushState Mar 31 12:04:27 opera volumio[15795]: verbose: In UPNP mode Mar 31 12:04:27 opera volumio[15795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":250,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无情","artist":"若心","album":"无情","uri":"http://113.215.236.144/amobile.music.tc.qq.com/M800002jyCYF2EaQZp.mp3?guid=000000001465bbe0000001784427ee14&vkey=043F335F89902BDBA3F6F4971C46CE0EE92FD93F80C63A3697465F19278AE42A404C6E289F474318E3F28FEAD87AB08EF8B9033C27364E83&uin=1959889&fromtag=111093","trackType":""} Mar 31 12:04:27 opera volumio[15795]: verbose: CURRENT POSITION 0 Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::syncState stateService play Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::syncState currentStatus play Mar 31 12:04:27 opera volumio[15795]: info: Received an update from plugin. extracting info from payload Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::pushState Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::volumioPushState Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::pushState Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::volumioPushState Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 12:04:27 opera volumio[15795]: info: ------------------------------ 43ms Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::volumioGetQueue Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::getQueue Mar 31 12:04:27 opera volumio[15795]: info: CorePlayQueue::getQueue Mar 31 12:04:27 opera volumio[15795]: info: CoreCommandRouter::volumioGetQueue Mar 31 12:04:27 opera volumio[15795]: info: CoreStateMachine::getQueue Mar 31 12:04:27 opera volumio[15795]: info: CorePlayQueue::getQueue Mar 31 12:04:27 opera volumio[15795]: info: Updating RAAT Signal Path Mar 31 12:04:27 opera volumio[15795]: info: Updating RAAT Signal Path Mar 31 12:04:27 opera volumio[15795]: info: Updating RAAT Signal Path Mar 31 12:04:27 opera volumio[15795]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E8%8C%B9%E8%8A%B8/%E8%8C%B9%E6%AD%A4%E7%B2%BE%E5%BD%A9%E5%8D%81%E4%B8%89%E9%A6%96/72feb3be-ae00-4122-b63d-fe179de34062.jpg' Mar 31 12:05:45 opera volumio[15795]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 12:05:45 opera volumio[15795]: { Error: connect ETIMEDOUT 128.242.245.157:443 Mar 31 12:05:45 opera volumio[15795]: at Object._errnoException (util.js:1022:11) Mar 31 12:05:45 opera volumio[15795]: at _exceptionWithHostPort (util.js:1044:20) Mar 31 12:05:45 opera volumio[15795]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Mar 31 12:05:45 opera volumio[15795]: code: 'ETIMEDOUT', Mar 31 12:05:45 opera volumio[15795]: errno: 'ETIMEDOUT', Mar 31 12:05:45 opera volumio[15795]: syscall: 'connect', Mar 31 12:05:45 opera volumio[15795]: address: '128.242.245.157', Mar 31 12:05:45 opera volumio[15795]: port: 443 } Mar 31 12:05:45 opera volumio[15795]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 12:05:45 opera sudo[17261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 12:04 Mar 31 12:05:45 opera sudo[17261]: 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"