-- Logs begin at Sat 2024-08-31 02:29:09 UTC, end at Sat 2024-08-31 03:15:34 UTC. -- Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Clearing queue after UPNP request Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::ClearQueue Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::stop Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 03:14:19 musical-fidelity volumio[18274]: verbose: UNSET VOLATILE Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::stPlaybackTimer Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::updateTrackBlock Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrackBlock Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrack 0 Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::serviceStop Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrack 0 Aug 31 03:14:19 musical-fidelity volumio[18274]: info: ControllerMpd::stop Aug 31 03:14:19 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CorePlayQueue::clearPlayQueue Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CorePlayQueue::saveQueue Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushQueue Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Aug 31 03:14:19 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Aug 31 03:14:19 musical-fidelity volumio[18274]: ---------------------------- MPD announces state update: player Aug 31 03:14:19 musical-fidelity volumio[18274]: info: ControllerMpd::getState Aug 31 03:14:19 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:14:19 musical-fidelity volumio[18274]: info: sendMpdCommand stop took 62 milliseconds Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Starting UPNP Playback Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Preparing playback through UPNP Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioGetState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrack 0 Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 03:14:19 musical-fidelity volumio[18274]: error: updateQueue error: null Aug 31 03:14:19 musical-fidelity volumio[18274]: info: ------------------------------ 15ms Aug 31 03:14:19 musical-fidelity volumio[18274]: info: sendMpdCommand status took 7 milliseconds Aug 31 03:14:19 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: ControllerMpd::pushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::servicePushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrack 0 Aug 31 03:14:19 musical-fidelity volumio[18274]: 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} Aug 31 03:14:19 musical-fidelity volumio[18274]: verbose: CURRENT POSITION 0 Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState stateService stop Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:19 musical-fidelity volumio[18274]: info: No code Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:19 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:19 musical-fidelity volumio[18274]: info: ------------------------------ 51ms Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:19 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:19 musical-fidelity volumio[18274]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%9E%A0%E5%A9%A7%E7%A5%8E/%E9%9D%92%E5%9F%8E%E5%B1%B1%E4%B8%8B%E7%99%BD%E7%B4%A0%E8%B4%9E/1eb22cc4-60a2-4ad9-b857-91bd57f12d87.jpg' Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Aug 31 03:14:20 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Aug 31 03:14:20 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: ------------------------------ 5ms Aug 31 03:14:20 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Aug 31 03:14:20 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: ------------------------------ 3ms Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Aug 31 03:14:20 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Aug 31 03:14:20 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Aug 31 03:14:20 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:14:20 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:14:21 musical-fidelity volumio[18274]: info: Aug 31 03:14:21 musical-fidelity volumio[18274]: ---------------------------- MPD announces state update: player Aug 31 03:14:21 musical-fidelity volumio[18274]: info: ControllerMpd::getState Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:14:21 musical-fidelity volumio[18274]: info: Aug 31 03:14:21 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:14:21 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:14:21 musical-fidelity volumio[18274]: info: Aug 31 03:14:21 musical-fidelity volumio[18274]: ---------------------------- MPD announces state update: player Aug 31 03:14:21 musical-fidelity volumio[18274]: info: sendMpdCommand status took 8 milliseconds Aug 31 03:14:21 musical-fidelity volumio[18274]: info: ControllerMpd::getState Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseState Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 03:14:21 musical-fidelity volumio[18274]: info: ------------------------------ 10ms Aug 31 03:14:21 musical-fidelity volumio[18274]: info: sendMpdCommand status took 8 milliseconds Aug 31 03:14:21 musical-fidelity volumio[18274]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseState Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseTrackInfo Aug 31 03:14:21 musical-fidelity volumio[18274]: info: ControllerMpd::pushState Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::servicePushState Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: In UPNP mode Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"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://aqqmusic.tc.qq.com/M5000022khCi0ie8Mv.mp3?guid=FC8F180814F24AEEBDABA6A0F21431D1&vkey=568DC955EB4A70F74CDC5F25FEF3D457DDD1DB976FDFD296B10DC07572BA1DDEB02B742B50468DB14D52E6C19EFBA2B02DBA237EFAC0EFB3&uin=1152921504863828538&redirect=1&fromtag=101042","trackType":""} Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: CURRENT POSITION 0 Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState stateService play Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:21 musical-fidelity volumio[18274]: info: ------------------------------ 34ms Aug 31 03:14:21 musical-fidelity volumio[18274]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseTrackInfo Aug 31 03:14:21 musical-fidelity volumio[18274]: info: ControllerMpd::pushState Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::servicePushState Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: In UPNP mode Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":179,"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://aqqmusic.tc.qq.com/M5000022khCi0ie8Mv.mp3?guid=FC8F180814F24AEEBDABA6A0F21431D1&vkey=568DC955EB4A70F74CDC5F25FEF3D457DDD1DB976FDFD296B10DC07572BA1DDEB02B742B50468DB14D52E6C19EFBA2B02DBA237EFAC0EFB3&uin=1152921504863828538&redirect=1&fromtag=101042","trackType":""} Aug 31 03:14:21 musical-fidelity volumio[18274]: verbose: CURRENT POSITION 0 Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState stateService play Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState currentStatus play Aug 31 03:14:21 musical-fidelity volumio[18274]: info: Received an update from plugin. extracting info from payload Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:21 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:21 musical-fidelity volumio[18274]: info: ------------------------------ 55ms Aug 31 03:14:22 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:22 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:22 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:54 musical-fidelity volumio[1561]: 50 Aug 31 03:14:54 musical-fidelity volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 03:14:54 musical-fidelity volumio[1561]: Dload Upload Total Spent Left Speed Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Aug 31 03:14:54 musical-fidelity volumio[18274]: ---------------------------- Client requests Volume 50 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: VolumeController::SetAlsaVolume50 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:54 musical-fidelity volumio[1561]: [155B blob data] Aug 31 03:14:54 musical-fidelity volumio[1561]: {"time":1725074094134,"response":"volume Success"}50 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:54 musical-fidelity volumio[1561]: 43 Aug 31 03:14:54 musical-fidelity volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 03:14:54 musical-fidelity volumio[1561]: Dload Upload Total Spent Left Speed Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Aug 31 03:14:54 musical-fidelity volumio[18274]: ---------------------------- Client requests Volume 43 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: VolumeController::SetAlsaVolume43 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:54 musical-fidelity volumio[1561]: [155B blob data] Aug 31 03:14:54 musical-fidelity volumio[1561]: {"time":1725074094475,"response":"volume Success"}43 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:54 musical-fidelity volumio[1561]: 37 Aug 31 03:14:54 musical-fidelity volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 03:14:54 musical-fidelity volumio[1561]: Dload Upload Total Spent Left Speed Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Aug 31 03:14:54 musical-fidelity volumio[18274]: ---------------------------- Client requests Volume 37 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: VolumeController::SetAlsaVolume37 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:54 musical-fidelity volumio[1561]: [155B blob data] Aug 31 03:14:54 musical-fidelity volumio[1561]: {"time":1725074094677,"response":"volume Success"}37 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:54 musical-fidelity volumio[1561]: 31 Aug 31 03:14:54 musical-fidelity volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 03:14:54 musical-fidelity volumio[1561]: Dload Upload Total Spent Left Speed Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Aug 31 03:14:54 musical-fidelity volumio[18274]: ---------------------------- Client requests Volume 31 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: VolumeController::SetAlsaVolume31 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:54 musical-fidelity volumio[1561]: [155B blob data] Aug 31 03:14:54 musical-fidelity volumio[1561]: {"time":1725074094784,"response":"volume Success"}31 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:54 musical-fidelity volumio[1561]: 25 Aug 31 03:14:54 musical-fidelity volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 03:14:54 musical-fidelity volumio[1561]: Dload Upload Total Spent Left Speed Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Aug 31 03:14:54 musical-fidelity volumio[18274]: ---------------------------- Client requests Volume 25 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: VolumeController::SetAlsaVolume25 Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:54 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:54 musical-fidelity volumio[1561]: [155B blob data] Aug 31 03:14:54 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:54 musical-fidelity volumio[1561]: {"time":1725074094972,"response":"volume Success"}25 Aug 31 03:14:55 musical-fidelity volumio[1561]: 12 Aug 31 03:14:55 musical-fidelity volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 03:14:55 musical-fidelity volumio[1561]: Dload Upload Total Spent Left Speed Aug 31 03:14:55 musical-fidelity volumio[18274]: info: Aug 31 03:14:55 musical-fidelity volumio[18274]: ---------------------------- Client requests Volume 12 Aug 31 03:14:55 musical-fidelity volumio[18274]: info: VolumeController::SetAlsaVolume12 Aug 31 03:14:55 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:55 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:55 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:55 musical-fidelity volumio[1561]: [155B blob data] Aug 31 03:14:55 musical-fidelity volumio[1561]: {"time":1725074095273,"response":"volume Success"}12 Aug 31 03:14:55 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:14:55 musical-fidelity volumio[1561]: 6 Aug 31 03:14:55 musical-fidelity volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 03:14:55 musical-fidelity volumio[1561]: Dload Upload Total Spent Left Speed Aug 31 03:14:55 musical-fidelity volumio[18274]: info: Aug 31 03:14:55 musical-fidelity volumio[18274]: ---------------------------- Client requests Volume 6 Aug 31 03:14:55 musical-fidelity volumio[18274]: info: VolumeController::SetAlsaVolume6 Aug 31 03:14:55 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:14:55 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:14:55 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:14:55 musical-fidelity volumio[1561]: [155B blob data] Aug 31 03:14:55 musical-fidelity volumio[1561]: {"time":1725074095673,"response":"volume Success"}6 Aug 31 03:14:55 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:31 musical-fidelity volumio[18274]: info: Clearing queue after UPNP request Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::ClearQueue Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::stop Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: UNSET VOLATILE Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::stPlaybackTimer Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::updateTrackBlock Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrackBlock Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrack 0 Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::serviceStop Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrack 0 Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ControllerMpd::stop Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CorePlayQueue::clearPlayQueue Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CorePlayQueue::saveQueue Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushQueue Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces state update: player Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ControllerMpd::getState Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:15:32 musical-fidelity volumio[18274]: info: sendMpdCommand stop took 35 milliseconds Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Starting UPNP Playback Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Preparing playback through UPNP Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioGetState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrack 0 Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 03:15:32 musical-fidelity volumio[18274]: error: updateQueue error: null Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 6ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: sendMpdCommand status took 5 milliseconds Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ControllerMpd::pushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::servicePushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CorePlayQueue::getTrack 0 Aug 31 03:15:32 musical-fidelity volumio[18274]: 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} Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: CURRENT POSITION 0 Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState stateService stop Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:15:32 musical-fidelity volumio[18274]: info: No code Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:15:32 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 44ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 4ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 2ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces state update: player Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ControllerMpd::getState Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces system playlist update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Ignoring MPD Status Update Aug 31 03:15:32 musical-fidelity volumio[18274]: info: Aug 31 03:15:32 musical-fidelity volumio[18274]: ---------------------------- MPD announces state update: player Aug 31 03:15:32 musical-fidelity volumio[18274]: info: sendMpdCommand status took 7 milliseconds Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ControllerMpd::getState Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseState Aug 31 03:15:32 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 03:15:32 musical-fidelity volumio[18274]: info: ------------------------------ 5ms Aug 31 03:15:32 musical-fidelity volumio[18274]: info: sendMpdCommand status took 3 milliseconds Aug 31 03:15:32 musical-fidelity volumio[18274]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseState Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseTrackInfo Aug 31 03:15:33 musical-fidelity volumio[18274]: info: ControllerMpd::pushState Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::servicePushState Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: In UPNP mode Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":37,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"早安（民谣）","artist":"李袈","album":"原创大合集2","uri":"http://aqqmusic.tc.qq.com/M5000021oVyG2FnSPA.mp3?guid=FC8F180814F24AEEBDABA6A0F21431D1&vkey=2A7AE3031F798DCDC70AF0C61CC2FFA671CC79F890FA7F782249DDAEDD5227CB1B00A41FD7A80FC4535B14DE25CD5B129365324F96581533&uin=1152921504863828538&redirect=1&fromtag=101042","trackType":""} Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: CURRENT POSITION 0 Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState stateService play Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:15:33 musical-fidelity volumio[18274]: info: ------------------------------ 32ms Aug 31 03:15:33 musical-fidelity volumio[18274]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: ControllerMpd::parseTrackInfo Aug 31 03:15:33 musical-fidelity volumio[18274]: info: ControllerMpd::pushState Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::servicePushState Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: In UPNP mode Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":37,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"早安（民谣）","artist":"李袈","album":"原创大合集2","uri":"http://aqqmusic.tc.qq.com/M5000021oVyG2FnSPA.mp3?guid=FC8F180814F24AEEBDABA6A0F21431D1&vkey=2A7AE3031F798DCDC70AF0C61CC2FFA671CC79F890FA7F782249DDAEDD5227CB1B00A41FD7A80FC4535B14DE25CD5B129365324F96581533&uin=1152921504863828538&redirect=1&fromtag=101042","trackType":""} Aug 31 03:15:33 musical-fidelity volumio[18274]: verbose: CURRENT POSITION 0 Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState stateService play Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreStateMachine::syncState currentStatus play Aug 31 03:15:33 musical-fidelity volumio[18274]: info: Received an update from plugin. extracting info from payload Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreStateMachine::pushState Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::volumioPushState Aug 31 03:15:33 musical-fidelity volumio[18274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:15:33 musical-fidelity volumio[18274]: info: ------------------------------ 45ms Aug 31 03:15:33 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:33 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:33 musical-fidelity volumio[18274]: info: Updating RAAT Signal Path Aug 31 03:15:33 musical-fidelity volumio[18274]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 03:15:33 musical-fidelity volumio[18274]: { Error: connect ETIMEDOUT 43.226.16.8:443 Aug 31 03:15:33 musical-fidelity volumio[18274]: at Object._errnoException (util.js:1022:11) Aug 31 03:15:33 musical-fidelity volumio[18274]: at _exceptionWithHostPort (util.js:1044:20) Aug 31 03:15:33 musical-fidelity volumio[18274]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 31 03:15:33 musical-fidelity volumio[18274]: code: 'ETIMEDOUT', Aug 31 03:15:33 musical-fidelity volumio[18274]: errno: 'ETIMEDOUT', Aug 31 03:15:33 musical-fidelity volumio[18274]: syscall: 'connect', Aug 31 03:15:33 musical-fidelity volumio[18274]: address: '43.226.16.8', Aug 31 03:15:33 musical-fidelity volumio[18274]: port: 443 } Aug 31 03:15:33 musical-fidelity volumio[18274]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 03:15:34 musical-fidelity sudo[22120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 03:14 Aug 31 03:15:34 musical-fidelity sudo[22120]: 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"