-- Logs begin at Fri 2024-07-12 07:45:05 UTC, end at Tue 2024-09-03 02:26:56 UTC. -- Sep 03 02:25:04 opera volumio[1200]: info: Sep 03 02:25:04 opera volumio[1200]: ---------------------------- MPD announces state update: player Sep 03 02:25:04 opera volumio[1200]: info: ControllerMpd::getState Sep 03 02:25:04 opera volumio[1200]: verbose: ControllerMpd::sendMpdCommand status Sep 03 02:25:04 opera volumio[1200]: info: sendMpdCommand status took 2 milliseconds Sep 03 02:25:04 opera volumio[1200]: verbose: ControllerMpd::parseState Sep 03 02:25:04 opera volumio[1200]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 02:25:04 opera volumio[1200]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 02:25:04 opera volumio[1200]: verbose: ControllerMpd::parseTrackInfo Sep 03 02:25:04 opera volumio[1200]: info: ControllerMpd::pushState Sep 03 02:25:04 opera volumio[1200]: info: CoreCommandRouter::servicePushState Sep 03 02:25:04 opera volumio[1200]: info: CoreStateMachine::pushState Sep 03 02:25:04 opera volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 03 02:25:04 opera volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 02:25:04 opera volumio[1200]: info: CorePlayQueue::getTrack 0 Sep 03 02:25:04 opera volumio[1200]: 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":"Habanera From \"Carmen\" / La Cumparsita (哈巴涅拉舞曲)","artist":"Martynas Levickis","album":"Martynas","uri":"http://aqqmusic.tc.qq.com/F000002vyrPZ0XHfcq.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=D8BD0ADA7EF3EFB198261B9649011A3E1C461B08C18CEDA7884306AE0E87C23C7C74A1FE2BE9AA321F059C7D950EF4E11B9FA4A30FDA4DDD&uin=1152921504950983260&src=F000002Ftrj02sQpH2.flac&redirect=1&fromtag=111114","trackType":""} Sep 03 02:25:04 opera volumio[1200]: verbose: CURRENT POSITION 0 Sep 03 02:25:04 opera volumio[1200]: info: CoreStateMachine::syncState stateService stop Sep 03 02:25:04 opera volumio[1200]: info: CoreStateMachine::syncState currentStatus play Sep 03 02:25:04 opera volumio[1200]: info: CoreStateMachine::play index undefined Sep 03 02:25:04 opera volumio[1200]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 02:25:04 opera volumio[1200]: info: CoreStateMachine::pushState Sep 03 02:25:04 opera volumio[1200]: info: CorePlayQueue::getTrack 0 Sep 03 02:25:04 opera volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 03 02:25:04 opera volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 02:25:04 opera volumio[1200]: info: CorePlayQueue::getTrack 0 Sep 03 02:25:04 opera volumio[1200]: info: ------------------------------ 29ms Sep 03 02:25:04 opera volumio[1200]: info: CoreCommandRouter::volumioGetQueue Sep 03 02:25:04 opera volumio[1200]: info: CoreStateMachine::getQueue Sep 03 02:25:04 opera volumio[1200]: info: CorePlayQueue::getQueue Sep 03 02:25:04 opera volumio[1200]: info: Updating RAAT Signal Path Sep 03 02:25:04 opera volumio[1200]: info: Updating RAAT Signal Path Sep 03 02:25:04 opera volumio[1200]: info: Clearing queue after UPNP request Sep 03 02:25:05 opera volumio[1200]: info: CoreStateMachine::ClearQueue Sep 03 02:25:05 opera volumio[1200]: info: CoreStateMachine::stop Sep 03 02:25:05 opera volumio[1200]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 02:25:05 opera volumio[1200]: verbose: UNSET VOLATILE Sep 03 02:25:05 opera volumio[1200]: info: CorePlayQueue::clearPlayQueue Sep 03 02:25:05 opera volumio[1200]: info: CorePlayQueue::saveQueue Sep 03 02:25:05 opera volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 03 02:25:05 opera volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 02:25:05 opera volumio[1200]: info: CoreCommandRouter::volumioPushQueue Sep 03 02:25:05 opera volumio[1200]: info: Updating RAAT Signal Path Sep 03 02:25:05 opera volumio[1200]: info: Sep 03 02:25:05 opera volumio[1200]: ---------------------------- MPD announces system playlist update Sep 03 02:25:05 opera volumio[1200]: info: Ignoring MPD Status Update Sep 03 02:25:05 opera volumio[1200]: error: updateQueue error: null Sep 03 02:25:05 opera volumio[1200]: info: ------------------------------ 2ms Sep 03 02:25:05 opera volumio[1200]: info: Starting UPNP Playback Sep 03 02:25:05 opera volumio[1200]: info: Preparing playback through UPNP Sep 03 02:25:05 opera volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 03 02:25:05 opera volumio[1200]: info: CorePlayQueue::getTrack 0 Sep 03 02:25:05 opera volumio[1200]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 02:25:05 opera volumio[1200]: info: Sep 03 02:25:05 opera volumio[1200]: ---------------------------- MPD announces system playlist update Sep 03 02:25:05 opera volumio[1200]: info: Ignoring MPD Status Update Sep 03 02:25:05 opera volumio[1200]: info: ------------------------------ 1ms Sep 03 02:25:05 opera volumio[1200]: info: Sep 03 02:25:05 opera volumio[1200]: ---------------------------- MPD announces system playlist update Sep 03 02:25:05 opera volumio[1200]: info: Ignoring MPD Status Update Sep 03 02:25:05 opera volumio[1200]: info: ------------------------------ 1ms Sep 03 02:25:05 opera volumio[1200]: info: Sep 03 02:25:05 opera volumio[1200]: ---------------------------- MPD announces system playlist update Sep 03 02:25:05 opera volumio[1200]: info: Ignoring MPD Status Update Sep 03 02:25:05 opera volumio[1200]: info: ------------------------------ 1ms Sep 03 02:25:05 opera volumio[1200]: info: Sep 03 02:25:05 opera volumio[1200]: ---------------------------- MPD announces system playlist update Sep 03 02:25:05 opera volumio[1200]: info: Ignoring MPD Status Update Sep 03 02:25:05 opera volumio[1200]: info: ------------------------------ 1ms Sep 03 02:25:05 opera volumio[1200]: info: Sep 03 02:25:05 opera volumio[1200]: ---------------------------- MPD announces system playlist update Sep 03 02:25:05 opera volumio[1200]: info: Ignoring MPD Status Update Sep 03 02:25:05 opera volumio[1200]: info: ------------------------------ 6ms Sep 03 02:25:05 opera volumio[1200]: info: Sep 03 02:25:05 opera volumio[1200]: ---------------------------- MPD announces system playlist update Sep 03 02:25:05 opera volumio[1200]: info: Ignoring MPD Status Update Sep 03 02:25:05 opera volumio[1200]: info: ------------------------------ 1ms Sep 03 02:25:05 opera volumio[1200]: info: Sep 03 02:25:05 opera volumio[1200]: ---------------------------- MPD announces state update: player Sep 03 02:25:05 opera volumio[1200]: info: ControllerMpd::getState Sep 03 02:25:05 opera volumio[1200]: verbose: ControllerMpd::sendMpdCommand status Sep 03 02:25:06 opera volumio[1200]: info: Sep 03 02:25:06 opera volumio[1200]: ---------------------------- MPD announces state update: player Sep 03 02:25:06 opera volumio[1200]: info: sendMpdCommand status took 4 milliseconds Sep 03 02:25:06 opera volumio[1200]: info: ControllerMpd::getState Sep 03 02:25:06 opera volumio[1200]: verbose: ControllerMpd::sendMpdCommand status Sep 03 02:25:06 opera volumio[1200]: verbose: ControllerMpd::parseState Sep 03 02:25:06 opera volumio[1200]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 02:25:06 opera volumio[1200]: info: sendMpdCommand status took 1 milliseconds Sep 03 02:25:06 opera volumio[1200]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 02:25:06 opera volumio[1200]: verbose: ControllerMpd::parseState Sep 03 02:25:06 opera volumio[1200]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 02:25:06 opera volumio[1200]: verbose: ControllerMpd::parseTrackInfo Sep 03 02:25:06 opera volumio[1200]: info: ControllerMpd::pushState Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::servicePushState Sep 03 02:25:06 opera volumio[1200]: verbose: In UPNP mode Sep 03 02:25:06 opera volumio[1200]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tara's Theme from Gone with the Wind (1939)","artist":"John Williams/Itzhak Perlman/Boston Pops Orchestra","album":"Cinema Serenade II - \"The Golden Age\"","uri":"http://aqqmusic.tc.qq.com/F000000F5lHY3zseY1.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=8C1D6BAC01CDD95199C45AF5E0D88945241EE1BEE6DC8B6C49C2EAF092C1339437337715355861D205184EED7A431C3F8D3A790D74237D40&uin=1152921504950983260&src=F000000tqE8q0sWiPs.flac&redirect=1&fromtag=111114","trackType":""} Sep 03 02:25:06 opera volumio[1200]: verbose: CURRENT POSITION 0 Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::syncState stateService play Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::syncState currentStatus stop Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::pushState Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 02:25:06 opera volumio[1200]: info: ------------------------------ 25ms Sep 03 02:25:06 opera volumio[1200]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 03 02:25:06 opera volumio[1200]: verbose: ControllerMpd::parseTrackInfo Sep 03 02:25:06 opera volumio[1200]: info: ControllerMpd::pushState Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::servicePushState Sep 03 02:25:06 opera volumio[1200]: verbose: In UPNP mode Sep 03 02:25:06 opera volumio[1200]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tara's Theme from Gone with the Wind (1939)","artist":"John Williams/Itzhak Perlman/Boston Pops Orchestra","album":"Cinema Serenade II - \"The Golden Age\"","uri":"http://aqqmusic.tc.qq.com/F000000F5lHY3zseY1.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=8C1D6BAC01CDD95199C45AF5E0D88945241EE1BEE6DC8B6C49C2EAF092C1339437337715355861D205184EED7A431C3F8D3A790D74237D40&uin=1152921504950983260&src=F000000tqE8q0sWiPs.flac&redirect=1&fromtag=111114","trackType":""} Sep 03 02:25:06 opera volumio[1200]: verbose: CURRENT POSITION 0 Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::syncState stateService play Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::syncState currentStatus play Sep 03 02:25:06 opera volumio[1200]: info: Received an update from plugin. extracting info from payload Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::pushState Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::pushState Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 02:25:06 opera volumio[1200]: info: ------------------------------ 64ms Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::volumioGetQueue Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::getQueue Sep 03 02:25:06 opera volumio[1200]: info: CorePlayQueue::getQueue Sep 03 02:25:06 opera volumio[1200]: info: CoreCommandRouter::volumioGetQueue Sep 03 02:25:06 opera volumio[1200]: info: CoreStateMachine::getQueue Sep 03 02:25:06 opera volumio[1200]: info: CorePlayQueue::getQueue Sep 03 02:25:06 opera volumio[1200]: info: Updating RAAT Signal Path Sep 03 02:25:06 opera volumio[1200]: info: Updating RAAT Signal Path Sep 03 02:25:06 opera volumio[1200]: info: Updating RAAT Signal Path Sep 03 02:25:06 opera volumio[1200]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Martynas%20Levickis/Martynas/929c5469-d7de-4ac1-8f7a-f7b83d3749d7.jpg' Sep 03 02:26:55 opera volumio[1200]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 02:26:55 opera volumio[1200]: { Error: connect ETIMEDOUT 69.197.153.180:443 Sep 03 02:26:55 opera volumio[1200]: at Object._errnoException (util.js:1022:11) Sep 03 02:26:55 opera volumio[1200]: at _exceptionWithHostPort (util.js:1044:20) Sep 03 02:26:55 opera volumio[1200]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 03 02:26:55 opera volumio[1200]: code: 'ETIMEDOUT', Sep 03 02:26:55 opera volumio[1200]: errno: 'ETIMEDOUT', Sep 03 02:26:55 opera volumio[1200]: syscall: 'connect', Sep 03 02:26:55 opera volumio[1200]: address: '69.197.153.180', Sep 03 02:26:55 opera volumio[1200]: port: 443 } Sep 03 02:26:55 opera volumio[1200]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 02:26:56 opera sudo[8559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-03 02:25 Sep 03 02:26:56 opera sudo[8559]: 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"