-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2025-12-16 14:45:51 UTC. -- Dec 16 14:44:06 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%98%9F%E7%89%B9/%E5%A4%A7%E6%9C%9B%E5%85%AC%E5%9B%AD/489ac0e5-b328-4623-818a-a0f706896df2.jpg' Dec 16 14:44:07 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BB%84%E7%BB%AE%E7%8F%8A/%E4%BA%9A%E6%B4%B2%E7%AC%AC%E4%B8%80%E5%A5%B3%E5%A3%B0/3cc30af9-87fc-4589-8cfb-17f36c5fadb8.jpg' Dec 16 14:44:12 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BE%83%E4%BE%83/%E6%88%91%E6%98%AF%E4%BE%83%E4%BE%83/f64cce92-73f2-438c-87fb-4b977842ac18.jpg' Dec 16 14:44:13 volumio volumio[9659]: info: CoreCommandRouter::volumioPlay Dec 16 14:44:13 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::play index undefined Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:13 volumio volumio[9659]: info: CorePlayQueue::getTrack 0 Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:44:13 volumio volumio[9659]: info: CorePlayQueue::getTrack 0 Dec 16 14:44:13 volumio volumio[9659]: info: [1765896253367] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:44:13 volumio volumio[9659]: info: sendMpdCommand stop took 3 milliseconds Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:44:13 volumio volumio[9659]: info: Dec 16 14:44:13 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:13 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:13 volumio volumio[9659]: info: sendMpdCommand clear took 6 milliseconds Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/27.flac" Dec 16 14:44:13 volumio volumio[9659]: error: updateQueue error: null Dec 16 14:44:13 volumio volumio[9659]: info: ------------------------------ 11ms Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/27.flac" Dec 16 14:44:13 volumio volumio[9659]: info: Dec 16 14:44:13 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:13 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:13 volumio volumio[9659]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/27.flac" took 3 milliseconds Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:44:13 volumio volumio[9659]: info: ------------------------------ 10ms Dec 16 14:44:13 volumio volumio[9659]: info: sendMpdCommand play took 5 milliseconds Dec 16 14:44:13 volumio volumio[9659]: info: Dec 16 14:44:13 volumio volumio[9659]: ---------------------------- MPD announces state update: player Dec 16 14:44:13 volumio volumio[9659]: info: ControllerMpd::getState Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:44:13 volumio volumio[9659]: info: Dec 16 14:44:13 volumio volumio[9659]: ---------------------------- MPD announces state update: player Dec 16 14:44:13 volumio volumio[9659]: info: sendMpdCommand status took 201 milliseconds Dec 16 14:44:13 volumio volumio[9659]: info: ControllerMpd::getState Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::parseState Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:44:13 volumio volumio[9659]: info: sendMpdCommand status took 7 milliseconds Dec 16 14:44:13 volumio volumio[9659]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::parseState Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:44:13 volumio volumio[9659]: info: ControllerMpd::pushState Dec 16 14:44:13 volumio volumio[9659]: info: CoreCommandRouter::servicePushState Dec 16 14:44:13 volumio volumio[9659]: info: CorePlayQueue::getTrack 0 Dec 16 14:44:13 volumio volumio[9659]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27.flac","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/27.flac","trackType":"flac"} Dec 16 14:44:13 volumio volumio[9659]: verbose: CURRENT POSITION 0 Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::syncState stateService play Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:13 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:13 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:13 volumio volumio[9659]: info: ------------------------------ 262ms Dec 16 14:44:13 volumio volumio[9659]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 16 14:44:13 volumio volumio[9659]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:44:13 volumio volumio[9659]: info: ControllerMpd::pushState Dec 16 14:44:13 volumio volumio[9659]: info: CoreCommandRouter::servicePushState Dec 16 14:44:13 volumio volumio[9659]: info: CorePlayQueue::getTrack 0 Dec 16 14:44:13 volumio volumio[9659]: verbose: STATE SERVICE {"status":"play","position":0,"seek":190,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"490 Kbps","isStreaming":false,"title":"27.flac","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/27.flac","trackType":"flac"} Dec 16 14:44:13 volumio volumio[9659]: verbose: CURRENT POSITION 0 Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::syncState stateService play Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::syncState currentStatus play Dec 16 14:44:13 volumio volumio[9659]: info: Received an update from plugin. extracting info from payload Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:13 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:13 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:13 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:13 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:13 volumio volumio[9659]: info: ------------------------------ 105ms Dec 16 14:44:15 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%B5%AB%E9%87%8E/%E5%A4%A7%E9%A3%8E%E5%90%B9/9d4713a5-d9f6-444f-8b9e-4d10c1c21a31.jpg' Dec 16 14:44:18 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B8%83%E5%8F%94%EF%BC%88%E5%8F%B6%E6%B3%BD%E6%B5%A9%EF%BC%89/%E5%8D%8A%E7%94%9F%E9%9B%AA/e23be063-44c6-4957-96cd-1f4f3b9c4117.jpg' Dec 16 14:44:18 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9B%B2%E8%82%96%E5%86%B0/%E5%8E%9A%E9%A2%9C%E6%97%A0%E8%80%BB/f3ff318b-521c-49cc-bd58-c68883b542af.jpg' Dec 16 14:44:18 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%BD%90%E5%B1%B1DanieL/%E5%8D%95%E4%BA%BA%E5%88%B8/a9e027d3-2a7e-4a49-8a8d-156e256c9a0e.jpg' Dec 16 14:44:18 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/NMIXX/AD%20MARE/9910108f-c5fc-42ae-9f22-52d6cbc72416.jpg' Dec 16 14:44:18 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8C%83%E5%80%AALiu/%E5%8D%97%E5%8D%8A%E7%90%83%E4%B8%8E%E5%8C%97%E6%B5%B7%E9%81%93/bcea09c4-09de-4272-9451-c2f473fc4d88.jpg' Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 47 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume47 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 48 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume48 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 49 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume49 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 50 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume50 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 51 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume51 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 52 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume52 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 53 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume53 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 54 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume54 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:44:22 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 55 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume55 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:22 volumio volumio[9659]: info: Dec 16 14:44:22 volumio volumio[9659]: ---------------------------- Client requests Volume 56 Dec 16 14:44:22 volumio volumio[9659]: info: VolumeController::SetAlsaVolume56 Dec 16 14:44:22 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:22 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:22 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPlay Dec 16 14:44:27 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::play index 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::stop Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:27 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::stPlaybackTimer Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::updateTrackBlock Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrackBlock Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 0 Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::serviceStop Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 0 Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::serviceStop Dec 16 14:44:27 volumio volumio[9659]: info: [1765896267606] ControllerUPNPBrowser::stop Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:44:27 volumio volumio[9659]: info: Dec 16 14:44:27 volumio volumio[9659]: ---------------------------- MPD announces state update: player Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand stop took 92 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: ControllerMpd::getState Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::play index undefined Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: info: [1765896267704] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand status took 10 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand stop took 7 milliseconds Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::parseState Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:44:27 volumio volumio[9659]: info: Dec 16 14:44:27 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:27 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand clear took 3 milliseconds Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/86.flac" Dec 16 14:44:27 volumio volumio[9659]: info: ControllerMpd::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::servicePushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: 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":"27.flac","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/27.flac","trackType":"flac"} Dec 16 14:44:27 volumio volumio[9659]: verbose: CURRENT POSITION 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::syncState stateService stop Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: No code Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: ------------------------------ 47ms Dec 16 14:44:27 volumio volumio[9659]: error: updateQueue error: null Dec 16 14:44:27 volumio volumio[9659]: info: ------------------------------ 35ms Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/86.flac" Dec 16 14:44:27 volumio volumio[9659]: info: Dec 16 14:44:27 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:27 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/86.flac" took 8 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:44:27 volumio volumio[9659]: info: ------------------------------ 4ms Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand play took 3 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: Dec 16 14:44:27 volumio volumio[9659]: ---------------------------- MPD announces state update: player Dec 16 14:44:27 volumio volumio[9659]: info: ControllerMpd::getState Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand status took 7 milliseconds Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::parseState Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand clearerror took 3 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:44:27 volumio volumio[9659]: info: ControllerMpd::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::servicePushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: 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":"86.flac","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/86.flac","trackType":"flac"} Dec 16 14:44:27 volumio volumio[9659]: verbose: CURRENT POSITION 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::syncState stateService stop Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: No code Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: ------------------------------ 48ms Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPlay Dec 16 14:44:27 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::play index 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::stop Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:27 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::play index undefined Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: info: [1765896267883] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand stop took 1 milliseconds Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:44:27 volumio volumio[9659]: info: Dec 16 14:44:27 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:27 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand clear took 2 milliseconds Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/86.flac" Dec 16 14:44:27 volumio volumio[9659]: error: updateQueue error: null Dec 16 14:44:27 volumio volumio[9659]: info: ------------------------------ 4ms Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/86.flac" Dec 16 14:44:27 volumio volumio[9659]: info: Dec 16 14:44:27 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:27 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/86.flac" took 2 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:44:27 volumio volumio[9659]: info: ------------------------------ 3ms Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand play took 2 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: Dec 16 14:44:27 volumio volumio[9659]: ---------------------------- MPD announces state update: player Dec 16 14:44:27 volumio volumio[9659]: info: ControllerMpd::getState Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand status took 6 milliseconds Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::parseState Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand clearerror took 3 milliseconds Dec 16 14:44:27 volumio volumio[9659]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 14:44:27 volumio volumio[9659]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:44:27 volumio volumio[9659]: info: ControllerMpd::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::servicePushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:27 volumio volumio[9659]: 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":"86.flac","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/86.flac","trackType":"flac"} Dec 16 14:44:27 volumio volumio[9659]: verbose: CURRENT POSITION 56 Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::syncState stateService stop Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: No code Dec 16 14:44:27 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:27 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:27 volumio volumio[9659]: info: ------------------------------ 41ms Dec 16 14:44:28 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/space%20x/0321/160aa1ae-7648-4325-979d-d214b5f23840.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%83%AD%E5%86%A0%E5%BB%B7/%E5%8F%8D%E6%B4%BE%E8%A7%92%E8%89%B2/0f40baea-dd18-4016-a5ee-ca9898b56671.png' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/G.E.M.%20%E9%82%93%E7%B4%AB%E6%A3%8B/%E5%90%AF%E7%A4%BA%E5%BD%95/b2e747f3-8684-4150-a2e5-d7415fdfdc86.png' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joysaaaa/%E5%8E%BB%E8%A7%81%E6%83%B3%E8%A7%81%E7%9A%84%E4%BA%BA/58a6daf5-97b3-486f-90a2-f65f75face9c.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E7%90%AA/%E5%8F%AF%E5%8F%AF%E6%89%98%E6%B5%B7%E7%9A%84%E7%89%A7%E7%BE%8A%E4%BA%BA/5a2c6e44-044a-4c18-8b93-ca22c65453a7.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B8%8C%E6%9E%97%E5%A8%9C%E4%BE%9D%E9%AB%98/%E5%94%AF%E6%88%91%E2%80%A2%E5%BF%B5/26bae18e-0b1c-4d23-8988-dd6508de75dd.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%9F%E9%95%BF/%E5%93%AA%E9%87%8C%E9%83%BD%E6%98%AF%E4%BD%A0/8b647107-d316-4f80-a294-791e243e3a00.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BA%8E%E5%86%AC%E7%84%B6/%E5%90%AC%E8%AF%B4%E4%BD%A0/998bd557-a2a3-43b0-9410-b3ffa81fc189.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E6%98%95%E8%9E%8D/%E5%90%AC%E6%88%91%E8%AF%B4%E8%B0%A2%E8%B0%A2%E4%BD%A0/b9871539-bc10-4a49-ba0f-5b7b3109a408.jpg' Dec 16 14:44:31 volumio volumio[9659]: 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/%E5%8F%B9%E4%BA%91%E5%85%AE/69776e57-05ba-4abc-b387-41e346c6b36a.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%8A%E4%BA%94%E4%BA%BA/%E8%BF%90%E6%B0%94%E6%9D%A5%E5%BE%97%E8%8B%A5%E6%9C%89%E4%BC%BC%E6%97%A0/9862047e-3342-4cf4-b993-550f8c65e282.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B7%AF%E9%A3%9E%E6%96%87/%E5%98%89%E5%AE%BE/0e30d4f5-5878-4f0f-ab3e-057197c56698.jpg' Dec 16 14:44:31 volumio volumio[9659]: info: CoreCommandRouter::volumioSeek Dec 16 14:44:31 volumio volumio[9659]: info: CoreStateMachine::seek Dec 16 14:44:31 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:31 volumio volumio[9659]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.9.3:8200/MediaItems/86.flac","realUri":"upnp/folder/http://192.168.9.3:8200/ctl/ContentDir@64$0","type":"song","albumart":"/albumart?cacheid=835&web=//extralarge&path=&metadata=false","artist":"","album":"","name":"104 月满西楼","title":"104 月满西楼","duration":307} Dec 16 14:44:31 volumio volumio[9659]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:44:31 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:31 volumio volumio[9659]: info: [1765896271849] ControllerUPNPBrowser::seek Dec 16 14:44:31 volumio volumio[9659]: info: ControllerMpd::seek Dec 16 14:44:31 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:31 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:31 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:31 volumio volumio[9659]: error: null Dec 16 14:44:31 volumio volumio[9659]: info: Dec 16 14:44:31 volumio volumio[9659]: ---------------------------- MPD announces state update: player Dec 16 14:44:31 volumio volumio[9659]: info: ControllerMpd::getState Dec 16 14:44:31 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:44:31 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 16 14:44:31 volumio volumio[9659]: info: sendMpdCommand status took 10 milliseconds Dec 16 14:44:31 volumio volumio[9659]: verbose: ControllerMpd::parseState Dec 16 14:44:31 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:44:31 volumio volumio[9659]: info: sendMpdCommand clearerror took 9 milliseconds Dec 16 14:44:31 volumio volumio[9659]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 16 14:44:31 volumio volumio[9659]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:44:31 volumio volumio[9659]: info: ControllerMpd::pushState Dec 16 14:44:31 volumio volumio[9659]: info: CoreCommandRouter::servicePushState Dec 16 14:44:31 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:31 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:31 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:31 volumio volumio[9659]: info: CorePlayQueue::getTrack 56 Dec 16 14:44:31 volumio volumio[9659]: 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":"86.flac","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/86.flac","trackType":"flac"} Dec 16 14:44:31 volumio volumio[9659]: verbose: CURRENT POSITION 56 Dec 16 14:44:31 volumio volumio[9659]: info: CoreStateMachine::syncState stateService stop Dec 16 14:44:31 volumio volumio[9659]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:44:31 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:31 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:31 volumio volumio[9659]: info: No code Dec 16 14:44:31 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:31 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:31 volumio volumio[9659]: info: ------------------------------ 60ms Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%A8%8B%E5%93%8D/%E5%9B%9B%E5%AD%A3%E4%BA%88%E4%BD%A0/04f3e19c-b200-4e7d-b0fe-ba341ef5c94d.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E8%BF%9C/%E5%98%89%E5%AE%BE/a36cbe46-992f-461d-860c-ff78818c72ed.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E8%BF%9C/%E5%98%89%E5%AE%BE/a36cbe46-992f-461d-860c-ff78818c72ed.jpg' Dec 16 14:44:31 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BE%9A%E7%8E%A5/70%E6%81%8B%E6%AD%8C/a3dd36ec-a88a-48a0-8ebd-9bee62152988.jpg' Dec 16 14:44:32 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BB%84%E9%BE%84/%E5%9C%A8%E6%A2%A6%E9%87%8C%E6%92%92%E9%87%8E/86f841ac-b0b5-48ab-8f4c-33ef2b3b462d.png' Dec 16 14:44:32 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BE%83%E4%BE%83/%E6%88%91%E6%98%AF%E4%BE%83%E4%BE%83/f64cce92-73f2-438c-87fb-4b977842ac18.jpg' Dec 16 14:44:32 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%83%A1%E6%9D%A8%E6%9E%97/%E5%A4%9A%E6%83%85%E7%A7%8D/72131a9a-284e-4d67-a1a6-141e70438ca1.jpg' Dec 16 14:44:32 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E7%BF%8A%E5%90%9B/%E6%83%85%E7%88%B1%E6%80%A8%E5%A5%B3/a8113feb-1f65-4ba9-8619-4b8880bb3d05.jpg' Dec 16 14:44:32 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BB%84%E7%BB%AE%E7%8F%8A/%E4%BA%9A%E6%B4%B2%E7%AC%AC%E4%B8%80%E5%A5%B3%E5%A3%B0/3cc30af9-87fc-4589-8cfb-17f36c5fadb8.jpg' Dec 16 14:44:33 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%98%9F%E7%89%B9/%E5%A4%A7%E6%9C%9B%E5%85%AC%E5%9B%AD/489ac0e5-b328-4623-818a-a0f706896df2.jpg' Dec 16 14:44:33 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%B5%AB%E9%87%8E/%E5%A4%A7%E9%A3%8E%E5%90%B9/9d4713a5-d9f6-444f-8b9e-4d10c1c21a31.jpg' Dec 16 14:44:39 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BD%95%E9%9D%99/%E5%A4%A9%E5%9C%B0%E4%B8%8E%E7%88%B1%E4%B8%8E%E4%BA%BA/a08ed67c-d3a5-41a6-9acd-ec1889fd2c84.jpg' Dec 16 14:44:42 volumio volumio[9659]: info: CoreCommandRouter::volumioPlay Dec 16 14:44:42 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::play index 97 Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::stop Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:42 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::play index undefined Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:42 volumio volumio[9659]: info: CorePlayQueue::getTrack 97 Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:44:42 volumio volumio[9659]: info: CorePlayQueue::getTrack 97 Dec 16 14:44:42 volumio volumio[9659]: info: [1765896282869] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:44:42 volumio volumio[9659]: info: sendMpdCommand stop took 2 milliseconds Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:44:42 volumio volumio[9659]: info: Dec 16 14:44:42 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:42 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:42 volumio volumio[9659]: info: sendMpdCommand clear took 2 milliseconds Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/136.flac" Dec 16 14:44:42 volumio volumio[9659]: error: updateQueue error: null Dec 16 14:44:42 volumio volumio[9659]: info: ------------------------------ 4ms Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/136.flac" Dec 16 14:44:42 volumio volumio[9659]: info: Dec 16 14:44:42 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:42 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:42 volumio volumio[9659]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/136.flac" took 2 milliseconds Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:44:42 volumio volumio[9659]: info: ------------------------------ 4ms Dec 16 14:44:42 volumio volumio[9659]: info: sendMpdCommand play took 3 milliseconds Dec 16 14:44:42 volumio volumio[9659]: info: Dec 16 14:44:42 volumio volumio[9659]: ---------------------------- MPD announces state update: player Dec 16 14:44:42 volumio volumio[9659]: info: ControllerMpd::getState Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 16 14:44:42 volumio volumio[9659]: info: sendMpdCommand status took 6 milliseconds Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::parseState Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:44:42 volumio volumio[9659]: info: sendMpdCommand clearerror took 3 milliseconds Dec 16 14:44:42 volumio volumio[9659]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 14:44:42 volumio volumio[9659]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:44:42 volumio volumio[9659]: info: ControllerMpd::pushState Dec 16 14:44:42 volumio volumio[9659]: info: CoreCommandRouter::servicePushState Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:42 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:42 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:42 volumio volumio[9659]: info: CorePlayQueue::getTrack 97 Dec 16 14:44:42 volumio volumio[9659]: 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":"136.flac","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/136.flac","trackType":"flac"} Dec 16 14:44:42 volumio volumio[9659]: verbose: CURRENT POSITION 97 Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::syncState stateService stop Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:42 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:42 volumio volumio[9659]: info: No code Dec 16 14:44:42 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:42 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:42 volumio volumio[9659]: info: ------------------------------ 37ms Dec 16 14:44:43 volumio volumio[9659]: info: CoreCommandRouter::volumioPlay Dec 16 14:44:43 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::play index 97 Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::stop Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:43 volumio volumio[9659]: verbose: UNSET VOLATILE Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::play index undefined Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:44:43 volumio volumio[9659]: info: CorePlayQueue::getTrack 97 Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:44:43 volumio volumio[9659]: info: CorePlayQueue::getTrack 97 Dec 16 14:44:43 volumio volumio[9659]: info: [1765896283082] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:44:43 volumio volumio[9659]: info: sendMpdCommand stop took 2 milliseconds Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:44:43 volumio volumio[9659]: info: Dec 16 14:44:43 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:43 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:43 volumio volumio[9659]: info: sendMpdCommand clear took 2 milliseconds Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/136.flac" Dec 16 14:44:43 volumio volumio[9659]: error: updateQueue error: null Dec 16 14:44:43 volumio volumio[9659]: info: ------------------------------ 3ms Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/136.flac" Dec 16 14:44:43 volumio volumio[9659]: info: Dec 16 14:44:43 volumio volumio[9659]: ---------------------------- MPD announces system playlist update Dec 16 14:44:43 volumio volumio[9659]: info: Ignoring MPD Status Update Dec 16 14:44:43 volumio volumio[9659]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/136.flac" took 2 milliseconds Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:44:43 volumio volumio[9659]: info: ------------------------------ 5ms Dec 16 14:44:43 volumio volumio[9659]: info: sendMpdCommand play took 4 milliseconds Dec 16 14:44:43 volumio volumio[9659]: info: Dec 16 14:44:43 volumio volumio[9659]: ---------------------------- MPD announces state update: player Dec 16 14:44:43 volumio volumio[9659]: info: ControllerMpd::getState Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 16 14:44:43 volumio volumio[9659]: info: sendMpdCommand status took 6 milliseconds Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::parseState Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:44:43 volumio volumio[9659]: info: sendMpdCommand clearerror took 3 milliseconds Dec 16 14:44:43 volumio volumio[9659]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 14:44:43 volumio volumio[9659]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:44:43 volumio volumio[9659]: info: ControllerMpd::pushState Dec 16 14:44:43 volumio volumio[9659]: info: CoreCommandRouter::servicePushState Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:43 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:44:43 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:43 volumio volumio[9659]: info: CorePlayQueue::getTrack 97 Dec 16 14:44:43 volumio volumio[9659]: 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":"136.flac","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/136.flac","trackType":"flac"} Dec 16 14:44:43 volumio volumio[9659]: verbose: CURRENT POSITION 97 Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::syncState stateService stop Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:43 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:43 volumio volumio[9659]: info: No code Dec 16 14:44:43 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:44:43 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:44:43 volumio volumio[9659]: info: ------------------------------ 36ms Dec 16 14:44:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 16 14:44:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 14:44:51 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 16 14:44:52 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 16 14:44:57 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 16 14:44:59 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 16 14:45:01 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 16 14:45:07 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 14:45:07 volumio volumio[9659]: info: CURURI: music-library Dec 16 14:45:07 volumio volumio[9659]: error: Failed LSINFO: null Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 16 14:45:10 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 14:45:14 volumio volumio[9659]: info: Retrieving Cloud Streaming UI Dec 16 14:45:14 volumio volumio[9659]: info: Getting Tidal Cloud Configuration Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 14:45:14 volumio volumio[9659]: info: Getting Qobuz Cloud Configuration Dec 16 14:45:14 volumio volumio[9659]: info: Asking plugin for UI Config Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 14:45:14 volumio volumio[9659]: info: Getting Spotify Cloud Configuration Dec 16 14:45:14 volumio volumio[9659]: info: Asking plugin for UI Config Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 14:45:14 volumio volumio[9659]: info: Saving Spotify Acccount Dec 16 14:45:14 volumio volumio[9659]: info: Got Tidal Cloud Configuration Dec 16 14:45:14 volumio volumio[9659]: info: Got it Dec 16 14:45:14 volumio volumio[9659]: info: Got it Dec 16 14:45:14 volumio volumio[9659]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 14:45:14 volumio volumio[9659]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::volumioGetBrowseSources Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:14 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 16 14:45:18 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:19 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%94%A1%E5%81%A5%E9%9B%85/%E5%A4%B1%E7%89%A9%E6%8B%9B%E9%A2%86/deb3efc8-2d2d-4c2f-ae0e-a62f6e144e60.jpg' Dec 16 14:45:19 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 16 14:45:19 volumio sudo[9880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/smbtree -N -b Dec 16 14:45:19 volumio sudo[9880]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:45:20 volumio sudo[9880]: pam_unix(sudo:session): session closed for user root Dec 16 14:45:20 volumio volumio[9659]: smbXcli_negprot_smb1_done: No compatible protocol selected by server. Dec 16 14:45:22 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 16 14:45:22 volumio sudo[9884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/smbtree -N -b Dec 16 14:45:22 volumio sudo[9884]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:45:23 volumio sudo[9884]: pam_unix(sudo:session): session closed for user root Dec 16 14:45:23 volumio volumio[9659]: smbXcli_negprot_smb1_done: No compatible protocol selected by server. Dec 16 14:45:23 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:23 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AE%8B%E5%AD%9F%E5%90%9B/%E5%A4%B1%E7%9C%A0%E7%9A%84%E5%A4%9C/d9b99ce9-54fe-4179-90dc-5035e8ec3870.jpg' Dec 16 14:45:25 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E5%BE%B7%E4%B8%BD/%E5%AD%A4%E5%8D%95%E7%9A%84%E5%BF%83%E7%97%9B/1d708e54-ba6c-4c8a-aaa2-41093efe093b.jpg' Dec 16 14:45:26 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:29 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BE%9A%E7%8E%A5/%E5%9B%9B%E5%A4%A7%E5%90%8D%E8%91%97/4d814a7c-6210-45bb-8532-6e7370149b42.jpg' Dec 16 14:45:30 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:32 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B9%83%E6%9C%A8%E5%9D%8246/%E5%A5%BD%E3%81%8D%E3%81%A8%E3%81%84%E3%81%86%E3%81%AE%E3%81%AF%E3%83%AD%E3%83%83%E3%82%AF%E3%81%A0%E3%81%9C%EF%BC%81%20(Special%20Edition)/312ccfa4-da47-45f0-8ece-2a77e10c19dc.jpg' Dec 16 14:45:34 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:38 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%B7%B1/%E5%A5%BD%E5%A5%BD%E7%94%9F%E6%B4%BB%E5%B0%B1%E6%98%AF%E7%BE%8E%E5%A5%BD%E7%94%9F%E6%B4%BB/7bcf9583-10a5-423d-9645-df5ad951d1af.jpg' Dec 16 14:45:38 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:41 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 16 14:45:41 volumio sudo[9888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/smbtree -N -b Dec 16 14:45:41 volumio sudo[9888]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:45:41 volumio nmbd[608]: [2025/12/16 14:45:41.723059, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 16 14:45:41 volumio nmbd[608]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.9.5 for name WORKGROUP<1d>. Dec 16 14:45:41 volumio nmbd[608]: This response was from IP 192.168.9.3, reporting an IP address of 192.168.9.3. Dec 16 14:45:42 volumio sudo[9888]: pam_unix(sudo:session): session closed for user root Dec 16 14:45:42 volumio volumio[9659]: smbXcli_negprot_smb1_done: No compatible protocol selected by server. Dec 16 14:45:42 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:43 volumio volumio[9659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%96%84%E5%AE%87/%E5%A6%82/891b68fa-76ff-419c-a96a-dcbf2a74e2f9.jpg' Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:46 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:46 volumio volumio[9659]: info: Dec 16 14:45:46 volumio volumio[9659]: ---------------------------- Client requests Volume 55 Dec 16 14:45:46 volumio volumio[9659]: info: VolumeController::SetAlsaVolume55 Dec 16 14:45:46 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:46 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:46 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:46 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:46 volumio volumio[9659]: info: Dec 16 14:45:46 volumio volumio[9659]: ---------------------------- Client requests Volume 56 Dec 16 14:45:46 volumio volumio[9659]: info: VolumeController::SetAlsaVolume56 Dec 16 14:45:46 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:46 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:46 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:46 volumio volumio[9659]: info: Dec 16 14:45:46 volumio volumio[9659]: ---------------------------- Client requests Volume 58 Dec 16 14:45:46 volumio volumio[9659]: info: VolumeController::SetAlsaVolume58 Dec 16 14:45:46 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:46 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:46 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:46 volumio volumio[9659]: info: Dec 16 14:45:46 volumio volumio[9659]: ---------------------------- Client requests Volume 60 Dec 16 14:45:46 volumio volumio[9659]: info: VolumeController::SetAlsaVolume60 Dec 16 14:45:46 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:46 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:46 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:46 volumio volumio[9659]: info: Dec 16 14:45:46 volumio volumio[9659]: ---------------------------- Client requests Volume 61 Dec 16 14:45:46 volumio volumio[9659]: info: VolumeController::SetAlsaVolume61 Dec 16 14:45:46 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:46 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:46 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:46 volumio volumio[9659]: info: Dec 16 14:45:46 volumio volumio[9659]: ---------------------------- Client requests Volume 64 Dec 16 14:45:46 volumio volumio[9659]: info: VolumeController::SetAlsaVolume64 Dec 16 14:45:46 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:46 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:46 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:46 volumio volumio[9659]: info: Dec 16 14:45:46 volumio volumio[9659]: ---------------------------- Client requests Volume 66 Dec 16 14:45:46 volumio volumio[9659]: info: VolumeController::SetAlsaVolume66 Dec 16 14:45:46 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:46 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:47 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:47 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:47 volumio volumio[9659]: info: Dec 16 14:45:47 volumio volumio[9659]: ---------------------------- Client requests Volume 68 Dec 16 14:45:47 volumio volumio[9659]: info: VolumeController::SetAlsaVolume68 Dec 16 14:45:47 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:47 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:47 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:47 volumio volumio[9659]: info: Dec 16 14:45:47 volumio volumio[9659]: ---------------------------- Client requests Volume 71 Dec 16 14:45:47 volumio volumio[9659]: info: VolumeController::SetAlsaVolume71 Dec 16 14:45:47 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:47 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:47 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:47 volumio volumio[9659]: info: Dec 16 14:45:47 volumio volumio[9659]: ---------------------------- Client requests Volume 72 Dec 16 14:45:47 volumio volumio[9659]: info: VolumeController::SetAlsaVolume72 Dec 16 14:45:47 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:47 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:47 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:47 volumio volumio[9659]: info: Dec 16 14:45:47 volumio volumio[9659]: ---------------------------- Client requests Volume 73 Dec 16 14:45:47 volumio volumio[9659]: info: VolumeController::SetAlsaVolume73 Dec 16 14:45:47 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:47 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 16 14:45:47 volumio volumio[9659]: info: Setting Remote Device Volume: http://192.168.9.5 Dec 16 14:45:47 volumio volumio[9659]: info: Dec 16 14:45:47 volumio volumio[9659]: ---------------------------- Client requests Volume 75 Dec 16 14:45:47 volumio volumio[9659]: info: VolumeController::SetAlsaVolume75 Dec 16 14:45:47 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:47 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:47 volumio volumio[9659]: info: Dec 16 14:45:47 volumio volumio[9659]: ---------------------------- Client requests Volume 76 Dec 16 14:45:47 volumio volumio[9659]: info: VolumeController::SetAlsaVolume76 Dec 16 14:45:47 volumio volumio[9659]: info: CoreStateMachine::pushState Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:45:47 volumio volumio[9659]: info: CoreCommandRouter::volumioPushState Dec 16 14:45:47 volumio volumio[9659]: info: Done setting volume on: Dec 16 14:45:50 volumio volumio[9659]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 16 14:45:51 volumio volumio[9659]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 14:45:51 volumio volumio[9659]: Error: connect ETIMEDOUT 69.171.224.40:443 Dec 16 14:45:51 volumio volumio[9659]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 16 14:45:51 volumio volumio[9659]: errno: -110, Dec 16 14:45:51 volumio volumio[9659]: code: 'ETIMEDOUT', Dec 16 14:45:51 volumio volumio[9659]: syscall: 'connect', Dec 16 14:45:51 volumio volumio[9659]: address: '69.171.224.40', Dec 16 14:45:51 volumio volumio[9659]: port: 443 Dec 16 14:45:51 volumio volumio[9659]: } Dec 16 14:45:51 volumio volumio[9659]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 14:45:51 volumio sudo[9914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 14:44 Dec 16 14:45:51 volumio sudo[9914]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="51a522a0fb55c1bb9391fe373b08507527c94c95" VOLUMIO_FE_VERSION="c24266cbcdb448eb1c9463664e6c8a43e38231af" VOLUMIO_FE3_VERSION="4e93a5cb22ce7b9e53df90f7b2c3f3d1b2d6d7c2" VOLUMIO_BE_VERSION="8b3e6f24b6d055dc597c25250dded8bef566af7b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 02 Dec 2021 06:37:00 PM CET" VOLUMIO_VERSION="3.163" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6797fd9e7818a7259d13472fff06b043"