-- Logs begin at Sat 2024-09-14 10:00:34 UTC, end at Tue 2024-10-29 03:57:22 UTC. -- Oct 29 03:57:12 musical-fidelity volumio[1244]: info: Clearing queue after UPNP request Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::ClearQueue Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::stop Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 03:57:13 musical-fidelity volumio[1244]: verbose: UNSET VOLATILE Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::stPlaybackTimer Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::updateTrackBlock Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CorePlayQueue::getTrackBlock Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::serviceStop Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ControllerMpd::stop Oct 29 03:57:13 musical-fidelity volumio[1244]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CorePlayQueue::clearPlayQueue Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CorePlayQueue::saveQueue Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushQueue Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Oct 29 03:57:13 musical-fidelity volumio[1244]: ---------------------------- MPD announces system playlist update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Ignoring MPD Status Update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Oct 29 03:57:13 musical-fidelity volumio[1244]: ---------------------------- MPD announces state update: player Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ControllerMpd::getState Oct 29 03:57:13 musical-fidelity volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Oct 29 03:57:13 musical-fidelity volumio[1244]: info: sendMpdCommand stop took 61 milliseconds Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Starting UPNP Playback Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Preparing playback through UPNP Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 29 03:57:13 musical-fidelity volumio[1244]: error: updateQueue error: null Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ------------------------------ 4ms Oct 29 03:57:13 musical-fidelity volumio[1244]: info: sendMpdCommand status took 5 milliseconds Oct 29 03:57:13 musical-fidelity volumio[1244]: verbose: ControllerMpd::parseState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ControllerMpd::pushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::servicePushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 29 03:57:13 musical-fidelity volumio[1244]: 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} Oct 29 03:57:13 musical-fidelity volumio[1244]: verbose: CURRENT POSITION 0 Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::syncState stateService stop Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::syncState currentStatus stop Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:13 musical-fidelity volumio[1244]: info: No code Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:13 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ------------------------------ 31ms Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Oct 29 03:57:13 musical-fidelity volumio[1244]: ---------------------------- MPD announces system playlist update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Ignoring MPD Status Update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ------------------------------ 9ms Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Oct 29 03:57:13 musical-fidelity volumio[1244]: ---------------------------- MPD announces system playlist update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Ignoring MPD Status Update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ------------------------------ 2ms Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Oct 29 03:57:13 musical-fidelity volumio[1244]: ---------------------------- MPD announces system playlist update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Ignoring MPD Status Update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ------------------------------ 1ms Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Oct 29 03:57:13 musical-fidelity volumio[1244]: ---------------------------- MPD announces system playlist update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Ignoring MPD Status Update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Oct 29 03:57:13 musical-fidelity volumio[1244]: ---------------------------- MPD announces system playlist update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Ignoring MPD Status Update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ------------------------------ 2ms Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ------------------------------ 2ms Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Oct 29 03:57:13 musical-fidelity volumio[1244]: ---------------------------- MPD announces system playlist update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: Ignoring MPD Status Update Oct 29 03:57:13 musical-fidelity volumio[1244]: info: ------------------------------ 1ms Oct 29 03:57:14 musical-fidelity volumio[1244]: info: Oct 29 03:57:14 musical-fidelity volumio[1244]: ---------------------------- MPD announces state update: player Oct 29 03:57:14 musical-fidelity volumio[1244]: info: ControllerMpd::getState Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Oct 29 03:57:14 musical-fidelity volumio[1244]: info: sendMpdCommand status took 24 milliseconds Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: ControllerMpd::parseState Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 03:57:14 musical-fidelity volumio[1244]: info: Oct 29 03:57:14 musical-fidelity volumio[1244]: ---------------------------- MPD announces system playlist update Oct 29 03:57:14 musical-fidelity volumio[1244]: info: Ignoring MPD Status Update Oct 29 03:57:14 musical-fidelity volumio[1244]: info: Oct 29 03:57:14 musical-fidelity volumio[1244]: ---------------------------- MPD announces state update: player Oct 29 03:57:14 musical-fidelity volumio[1244]: info: ControllerMpd::getState Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Oct 29 03:57:14 musical-fidelity volumio[1244]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: ControllerMpd::parseTrackInfo Oct 29 03:57:14 musical-fidelity volumio[1244]: info: ControllerMpd::pushState Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::servicePushState Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: In UPNP mode Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天使(治愈纯音乐版)","artist":"Billy","album":"天使","uri":"http://aqqmusic.tc.qq.com/M5000026GKCu4DybA4.mp3?guid=9358DC40865744DCBDA2DE57A1499F8D&vkey=F97DC951D8B79B5B23D5A4D4BEC445DA9D3E06CFBEAF217C593D8A400ED7BEED0BC4D400DE2AE2BC8786D752B1CA12B713EDE11E51588256&uin=1152921504703920994&redirect=1&fromtag=101042","trackType":""} Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: CURRENT POSITION 0 Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreStateMachine::syncState stateService play Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreStateMachine::syncState currentStatus stop Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:14 musical-fidelity volumio[1244]: info: ------------------------------ 41ms Oct 29 03:57:14 musical-fidelity volumio[1244]: info: ------------------------------ 15ms Oct 29 03:57:14 musical-fidelity volumio[1244]: info: sendMpdCommand status took 15 milliseconds Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: ControllerMpd::parseState Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 03:57:14 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:14 musical-fidelity volumio[1244]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: ControllerMpd::parseTrackInfo Oct 29 03:57:14 musical-fidelity volumio[1244]: info: ControllerMpd::pushState Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::servicePushState Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: In UPNP mode Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天使(治愈纯音乐版)","artist":"Billy","album":"天使","uri":"http://aqqmusic.tc.qq.com/M5000026GKCu4DybA4.mp3?guid=9358DC40865744DCBDA2DE57A1499F8D&vkey=F97DC951D8B79B5B23D5A4D4BEC445DA9D3E06CFBEAF217C593D8A400ED7BEED0BC4D400DE2AE2BC8786D752B1CA12B713EDE11E51588256&uin=1152921504703920994&redirect=1&fromtag=101042","trackType":""} Oct 29 03:57:14 musical-fidelity volumio[1244]: verbose: CURRENT POSITION 0 Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreStateMachine::syncState stateService play Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreStateMachine::syncState currentStatus play Oct 29 03:57:14 musical-fidelity volumio[1244]: info: Received an update from plugin. extracting info from payload Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:14 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:14 musical-fidelity volumio[1244]: info: ------------------------------ 50ms Oct 29 03:57:14 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:14 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:18 musical-fidelity volumio[1651]: 61 Oct 29 03:57:18 musical-fidelity volumio[1651]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 29 03:57:18 musical-fidelity volumio[1651]: Dload Upload Total Spent Left Speed Oct 29 03:57:18 musical-fidelity volumio[1244]: info: Oct 29 03:57:18 musical-fidelity volumio[1244]: ---------------------------- Client requests Volume 61 Oct 29 03:57:18 musical-fidelity volumio[1244]: info: VolumeController::SetAlsaVolume61 Oct 29 03:57:18 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:18 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:18 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:18 musical-fidelity volumio[1651]: [155B blob data] Oct 29 03:57:18 musical-fidelity volumio[1651]: {"time":1730174238203,"response":"volume Success"}61 Oct 29 03:57:18 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:18 musical-fidelity volumio[1244]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 29 03:57:18 musical-fidelity volumio[1244]: xcb_connection_has_error() returned true Oct 29 03:57:18 musical-fidelity volumio[1651]: 68 Oct 29 03:57:18 musical-fidelity volumio[1651]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 29 03:57:18 musical-fidelity volumio[1651]: Dload Upload Total Spent Left Speed Oct 29 03:57:18 musical-fidelity volumio[1244]: info: Oct 29 03:57:18 musical-fidelity volumio[1244]: ---------------------------- Client requests Volume 68 Oct 29 03:57:18 musical-fidelity volumio[1244]: info: VolumeController::SetAlsaVolume68 Oct 29 03:57:18 musical-fidelity volumio[1244]: info: CoreStateMachine::pushState Oct 29 03:57:18 musical-fidelity volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 29 03:57:18 musical-fidelity volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 03:57:18 musical-fidelity volumio[1651]: [155B blob data] Oct 29 03:57:18 musical-fidelity volumio[1244]: info: Updating RAAT Signal Path Oct 29 03:57:18 musical-fidelity volumio[1651]: {"time":1730174238625,"response":"volume Success"}68 Oct 29 03:57:21 musical-fidelity volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 03:57:21 musical-fidelity volumio[1244]: { Error: connect ETIMEDOUT 31.13.67.33:443 Oct 29 03:57:21 musical-fidelity volumio[1244]: at Object._errnoException (util.js:1022:11) Oct 29 03:57:21 musical-fidelity volumio[1244]: at _exceptionWithHostPort (util.js:1044:20) Oct 29 03:57:21 musical-fidelity volumio[1244]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 29 03:57:21 musical-fidelity volumio[1244]: code: 'ETIMEDOUT', Oct 29 03:57:21 musical-fidelity volumio[1244]: errno: 'ETIMEDOUT', Oct 29 03:57:21 musical-fidelity volumio[1244]: syscall: 'connect', Oct 29 03:57:21 musical-fidelity volumio[1244]: address: '31.13.67.33', Oct 29 03:57:21 musical-fidelity volumio[1244]: port: 443 } Oct 29 03:57:21 musical-fidelity volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 03:57:22 musical-fidelity sudo[11087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-29 03:56 Oct 29 03:57:22 musical-fidelity sudo[11087]: 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"