-- Logs begin at Fri 2024-11-29 04:42:42 UTC, end at Fri 2024-11-29 04:59:46 UTC. -- Nov 29 04:59:13 mx volumio[1279]: info: Clearing queue after UPNP request Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::ClearQueue Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::stop Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 04:59:13 mx volumio[1279]: verbose: UNSET VOLATILE Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::stPlaybackTimer Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::updateTrackBlock Nov 29 04:59:13 mx volumio[1279]: info: CorePlayQueue::getTrackBlock Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::pushState Nov 29 04:59:13 mx volumio[1279]: info: CorePlayQueue::getTrack 0 Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::volumioPushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::serviceStop Nov 29 04:59:13 mx volumio[1279]: info: CorePlayQueue::getTrack 0 Nov 29 04:59:13 mx volumio[1279]: info: ControllerMpd::stop Nov 29 04:59:13 mx volumio[1279]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 04:59:13 mx volumio[1279]: info: CorePlayQueue::clearPlayQueue Nov 29 04:59:13 mx volumio[1279]: info: CorePlayQueue::saveQueue Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::volumioPushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::volumioPushQueue Nov 29 04:59:13 mx volumio[1279]: info: Updating RAAT Signal Path Nov 29 04:59:13 mx volumio[1279]: info: Updating RAAT Signal Path Nov 29 04:59:13 mx volumio[1279]: info: Nov 29 04:59:13 mx volumio[1279]: ---------------------------- MPD announces system playlist update Nov 29 04:59:13 mx volumio[1279]: info: Ignoring MPD Status Update Nov 29 04:59:13 mx volumio[1279]: info: Nov 29 04:59:13 mx volumio[1279]: ---------------------------- MPD announces state update: player Nov 29 04:59:13 mx volumio[1279]: info: ControllerMpd::getState Nov 29 04:59:13 mx volumio[1279]: verbose: ControllerMpd::sendMpdCommand status Nov 29 04:59:13 mx volumio[1279]: info: sendMpdCommand stop took 52 milliseconds Nov 29 04:59:13 mx volumio[1279]: error: updateQueue error: null Nov 29 04:59:13 mx volumio[1279]: info: ------------------------------ 6ms Nov 29 04:59:13 mx volumio[1279]: info: Starting UPNP Playback Nov 29 04:59:13 mx volumio[1279]: info: Preparing playback through UPNP Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::volumioGetState Nov 29 04:59:13 mx volumio[1279]: info: CorePlayQueue::getTrack 0 Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 04:59:13 mx volumio[1279]: info: sendMpdCommand status took 13 milliseconds Nov 29 04:59:13 mx volumio[1279]: verbose: ControllerMpd::parseState Nov 29 04:59:13 mx volumio[1279]: info: ControllerMpd::pushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::servicePushState Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::pushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::volumioPushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 04:59:13 mx volumio[1279]: info: CorePlayQueue::getTrack 0 Nov 29 04:59:13 mx volumio[1279]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 29 04:59:13 mx volumio[1279]: verbose: CURRENT POSITION 0 Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::syncState stateService stop Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::syncState currentStatus stop Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::pushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::volumioPushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 04:59:13 mx volumio[1279]: info: No code Nov 29 04:59:13 mx volumio[1279]: info: CoreStateMachine::pushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::volumioPushState Nov 29 04:59:13 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 04:59:13 mx volumio[1279]: info: ------------------------------ 52ms Nov 29 04:59:13 mx volumio[1279]: info: Updating RAAT Signal Path Nov 29 04:59:13 mx volumio[1279]: info: Updating RAAT Signal Path Nov 29 04:59:13 mx volumio[1279]: info: Updating RAAT Signal Path Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces system playlist update Nov 29 04:59:14 mx volumio[1279]: info: Ignoring MPD Status Update Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces system playlist update Nov 29 04:59:14 mx volumio[1279]: info: Ignoring MPD Status Update Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 4ms Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 4ms Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces system playlist update Nov 29 04:59:14 mx volumio[1279]: info: Ignoring MPD Status Update Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 2ms Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces system playlist update Nov 29 04:59:14 mx volumio[1279]: info: Ignoring MPD Status Update Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 3ms Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces system playlist update Nov 29 04:59:14 mx volumio[1279]: info: Ignoring MPD Status Update Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 3ms Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces system playlist update Nov 29 04:59:14 mx volumio[1279]: info: Ignoring MPD Status Update Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 2ms Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces state update: player Nov 29 04:59:14 mx volumio[1279]: info: ControllerMpd::getState Nov 29 04:59:14 mx volumio[1279]: verbose: ControllerMpd::sendMpdCommand status Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces system playlist update Nov 29 04:59:14 mx volumio[1279]: info: Ignoring MPD Status Update Nov 29 04:59:14 mx volumio[1279]: info: Nov 29 04:59:14 mx volumio[1279]: ---------------------------- MPD announces state update: player Nov 29 04:59:14 mx volumio[1279]: info: ControllerMpd::getState Nov 29 04:59:14 mx volumio[1279]: verbose: ControllerMpd::sendMpdCommand status Nov 29 04:59:14 mx volumio[1279]: info: sendMpdCommand status took 8 milliseconds Nov 29 04:59:14 mx volumio[1279]: verbose: ControllerMpd::parseState Nov 29 04:59:14 mx volumio[1279]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 7ms Nov 29 04:59:14 mx volumio[1279]: info: sendMpdCommand status took 6 milliseconds Nov 29 04:59:14 mx volumio[1279]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 29 04:59:14 mx volumio[1279]: verbose: ControllerMpd::parseState Nov 29 04:59:14 mx volumio[1279]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 04:59:14 mx volumio[1279]: verbose: ControllerMpd::parseTrackInfo Nov 29 04:59:14 mx volumio[1279]: info: ControllerMpd::pushState Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::servicePushState Nov 29 04:59:14 mx volumio[1279]: verbose: In UPNP mode Nov 29 04:59:14 mx volumio[1279]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"陈百强","album":"我的心底话 (珍藏集)","uri":"http://61.170.82.159/amobile.music.tc.qq.com/M500001HaUkD2hBbCy.mp3?guid=C98E737ACE764DCF8723B110C7CFD65C&vkey=43469221759046FB8B3CF57C01DF8C3A1600A4DBF7BBDB32E2FE4402E8623618839A0FCA3C8B9E0FCB22EE469336472D097C070226840BF0&uin=1196461256&redirect=1&fromtag=101042","trackType":""} Nov 29 04:59:14 mx volumio[1279]: verbose: CURRENT POSITION 0 Nov 29 04:59:14 mx volumio[1279]: info: CoreStateMachine::syncState stateService play Nov 29 04:59:14 mx volumio[1279]: info: CoreStateMachine::syncState currentStatus stop Nov 29 04:59:14 mx volumio[1279]: info: CoreStateMachine::pushState Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::volumioPushState Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 34ms Nov 29 04:59:14 mx volumio[1279]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 29 04:59:14 mx volumio[1279]: verbose: ControllerMpd::parseTrackInfo Nov 29 04:59:14 mx volumio[1279]: info: ControllerMpd::pushState Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::servicePushState Nov 29 04:59:14 mx volumio[1279]: verbose: In UPNP mode Nov 29 04:59:14 mx volumio[1279]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"陈百强","album":"我的心底话 (珍藏集)","uri":"http://61.170.82.159/amobile.music.tc.qq.com/M500001HaUkD2hBbCy.mp3?guid=C98E737ACE764DCF8723B110C7CFD65C&vkey=43469221759046FB8B3CF57C01DF8C3A1600A4DBF7BBDB32E2FE4402E8623618839A0FCA3C8B9E0FCB22EE469336472D097C070226840BF0&uin=1196461256&redirect=1&fromtag=101042","trackType":""} Nov 29 04:59:14 mx volumio[1279]: verbose: CURRENT POSITION 0 Nov 29 04:59:14 mx volumio[1279]: info: CoreStateMachine::syncState stateService play Nov 29 04:59:14 mx volumio[1279]: info: CoreStateMachine::syncState currentStatus play Nov 29 04:59:14 mx volumio[1279]: info: Received an update from plugin. extracting info from payload Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 04:59:14 mx volumio[1279]: info: CoreStateMachine::pushState Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::volumioPushState Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 04:59:14 mx volumio[1279]: info: CoreStateMachine::pushState Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::volumioPushState Nov 29 04:59:14 mx volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 04:59:14 mx volumio[1279]: info: ------------------------------ 67ms Nov 29 04:59:14 mx volumio[1279]: info: Updating RAAT Signal Path Nov 29 04:59:14 mx volumio[1279]: info: Updating RAAT Signal Path Nov 29 04:59:14 mx volumio[1279]: info: Updating RAAT Signal Path Nov 29 04:59:19 mx volumio[1279]: info: CoreCommandRouter::volumioPause Nov 29 04:59:19 mx volumio[1279]: info: CoreStateMachine::pause Nov 29 04:59:19 mx volumio[1279]: info: CoreStateMachine::stPlaybackTimer Nov 29 04:59:19 mx volumio[1279]: info: CoreStateMachine::servicePause Nov 29 04:59:19 mx volumio[1279]: info: CorePlayQueue::getTrack 0 Nov 29 04:59:19 mx volumio[1279]: info: Error: no service or no trackblock to pause Nov 29 04:59:35 mx volumio[1279]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Demi%20Lovato/Die%20Eisk%C3%B6nigin%20-%20V%C3%B6llig%20unverfroren/4c1a203c-5f4c-4ce5-b0d4-1b0ebd087190.jpg' Nov 29 04:59:45 mx volumio[1279]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 04:59:45 mx volumio[1279]: { Error: connect ETIMEDOUT 66.220.149.18:443 Nov 29 04:59:45 mx volumio[1279]: at Object._errnoException (util.js:1022:11) Nov 29 04:59:45 mx volumio[1279]: at _exceptionWithHostPort (util.js:1044:20) Nov 29 04:59:45 mx volumio[1279]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 29 04:59:45 mx volumio[1279]: code: 'ETIMEDOUT', Nov 29 04:59:45 mx volumio[1279]: errno: 'ETIMEDOUT', Nov 29 04:59:45 mx volumio[1279]: syscall: 'connect', Nov 29 04:59:45 mx volumio[1279]: address: '66.220.149.18', Nov 29 04:59:45 mx volumio[1279]: port: 443 } Nov 29 04:59:45 mx volumio[1279]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 04:59:46 mx sudo[6076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 04:58 Nov 29 04:59:46 mx sudo[6076]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"