-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2025-12-16 14:09:44 UTC. -- Dec 16 14:08:00 volumio volumio[5689]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 16 14:08:00 volumio volumio[5689]: info: Completed starting MyVolumio Plugin Dec 16 14:08:00 volumio volumio[5689]: info: BOOT COMPLETED Dec 16 14:08:00 volumio volumio[5689]: [Metrics] CommandRouter: 38s 320.27ms Dec 16 14:08:00 volumio volumio[5689]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 14:08:00 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:08:00 volumio volumio[5689]: info: VolumeController:: Setting startup Volume 15 Dec 16 14:08:00 volumio volumio[5689]: info: VolumeController::SetAlsaVolume15 Dec 16 14:08:00 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 14:08:00 volumio volumio[5689]: info: CoreCommandRouter::Close All Modals sent Dec 16 14:08:00 volumio volumio[5689]: info: CoreCommandRouter::Close All Modals sent Dec 16 14:08:00 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:00 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:00 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:00 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:01 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 14:08:01 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 14:08:02 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 14:08:09 volumio volumio[5689]: info: CoreCommandRouter::volumioPlay Dec 16 14:08:09 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::play index undefined Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:09 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:08:09 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:09 volumio volumio[5689]: info: [1765894089222] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:09 volumio volumio[5689]: info: sendMpdCommand stop took 3 milliseconds Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:08:09 volumio volumio[5689]: info: Dec 16 14:08:09 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:09 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:09 volumio volumio[5689]: info: sendMpdCommand clear took 4 milliseconds Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:09 volumio volumio[5689]: error: updateQueue error: null Dec 16 14:08:09 volumio volumio[5689]: info: ------------------------------ 12ms Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:09 volumio volumio[5689]: info: Dec 16 14:08:09 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:09 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:09 volumio volumio[5689]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" took 3 milliseconds Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:08:09 volumio volumio[5689]: info: ------------------------------ 7ms Dec 16 14:08:09 volumio volumio[5689]: info: sendMpdCommand play took 5 milliseconds Dec 16 14:08:09 volumio volumio[5689]: info: Dec 16 14:08:09 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:09 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:09 volumio volumio[5689]: info: Dec 16 14:08:09 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:09 volumio volumio[5689]: info: sendMpdCommand status took 23 milliseconds Dec 16 14:08:09 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:09 volumio volumio[5689]: info: sendMpdCommand status took 11 milliseconds Dec 16 14:08:09 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:09 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:09 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:09 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:09 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:09 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:09 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:09 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:09 volumio volumio[5689]: info: ------------------------------ 71ms Dec 16 14:08:09 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 16 14:08:09 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:09 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:09 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:09 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:09 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:09 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus play Dec 16 14:08:09 volumio volumio[5689]: info: Received an update from plugin. extracting info from payload Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:09 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:09 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:09 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:09 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:09 volumio volumio[5689]: info: ------------------------------ 84ms Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::volumioPlay Dec 16 14:08:39 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::play index 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::stop Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:39 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::stPlaybackTimer Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::updateTrackBlock Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrackBlock Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::serviceStop Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::serviceStop Dec 16 14:08:39 volumio volumio[5689]: info: [1765894119708] ControllerUPNPBrowser::stop Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:39 volumio volumio[5689]: info: Dec 16 14:08:39 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand stop took 97 milliseconds Dec 16 14:08:39 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::play index undefined Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: info: [1765894119810] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand status took 6 milliseconds Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand stop took 3 milliseconds Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:08:39 volumio volumio[5689]: info: Dec 16 14:08:39 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:39 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand clear took 4 milliseconds Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:39 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: 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":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:39 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::syncState stateService stop Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:39 volumio volumio[5689]: info: No code Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:39 volumio volumio[5689]: info: ------------------------------ 64ms Dec 16 14:08:39 volumio volumio[5689]: error: updateQueue error: null Dec 16 14:08:39 volumio volumio[5689]: info: ------------------------------ 53ms Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:39 volumio volumio[5689]: info: Dec 16 14:08:39 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:39 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" took 10 milliseconds Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:08:39 volumio volumio[5689]: info: ------------------------------ 7ms Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand play took 4 milliseconds Dec 16 14:08:39 volumio volumio[5689]: info: Dec 16 14:08:39 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:39 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:39 volumio volumio[5689]: info: Dec 16 14:08:39 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand status took 13 milliseconds Dec 16 14:08:39 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand status took 5 milliseconds Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:39 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":617,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:39 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:39 volumio volumio[5689]: info: ------------------------------ 61ms Dec 16 14:08:39 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 16 14:08:39 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:39 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:39 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:39 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:39 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus play Dec 16 14:08:39 volumio volumio[5689]: info: Received an update from plugin. extracting info from payload Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:39 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:39 volumio volumio[5689]: info: ------------------------------ 73ms Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPlay Dec 16 14:08:41 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::play index 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::stPlaybackTimer Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::updateTrackBlock Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrackBlock Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::serviceStop Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::serviceStop Dec 16 14:08:41 volumio volumio[5689]: info: [1765894121312] ControllerUPNPBrowser::stop Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand stop took 103 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::play index undefined Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: [1765894121417] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand stop took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:41 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand clear took 2 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:41 volumio volumio[5689]: error: updateQueue error: null Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 5ms Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 13ms Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:41 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" took 2 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 4ms Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand play took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:41 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 45ms Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:41 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus play Dec 16 14:08:41 volumio volumio[5689]: info: Received an update from plugin. extracting info from payload Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 80ms Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPlay Dec 16 14:08:41 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::play index 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::stPlaybackTimer Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::updateTrackBlock Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrackBlock Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::serviceStop Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::serviceStop Dec 16 14:08:41 volumio volumio[5689]: info: [1765894121572] ControllerUPNPBrowser::stop Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand stop took 24 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::play index undefined Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: [1765894121599] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 4 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand stop took 2 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:41 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand clear took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: 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":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:41 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState stateService stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: No code Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 45ms Dec 16 14:08:41 volumio volumio[5689]: error: updateQueue error: null Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 36ms Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:41 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" took 7 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 5ms Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand play took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 5 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 4 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:41 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:41 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus play Dec 16 14:08:41 volumio volumio[5689]: info: Received an update from plugin. extracting info from payload Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 67ms Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 62ms Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPlay Dec 16 14:08:41 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::play index 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: verbose: UNSET VOLATILE Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::stPlaybackTimer Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::updateTrackBlock Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrackBlock Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::serviceStop Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::serviceStop Dec 16 14:08:41 volumio volumio[5689]: info: [1765894121777] ControllerUPNPBrowser::stop Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand stop took 32 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::play index undefined Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: [1765894121814] ControllerUPNPBrowser::clearAddPlayTrack Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 4 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand stop took 1 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:41 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand clear took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: 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":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:41 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState stateService stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: No code Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 45ms Dec 16 14:08:41 volumio volumio[5689]: error: updateQueue error: null Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 34ms Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces system playlist update Dec 16 14:08:41 volumio volumio[5689]: info: Ignoring MPD Status Update Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand add "http://192.168.9.3:8200/MediaItems/8048.wav" took 7 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand play Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 4ms Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand play took 2 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: info: Dec 16 14:08:41 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 5 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand status took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:41 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus stop Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 35ms Dec 16 14:08:41 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 16 14:08:41 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:08:41 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:08:41 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:08:41 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:08:41 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus play Dec 16 14:08:41 volumio volumio[5689]: info: Received an update from plugin. extracting info from payload Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:41 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:41 volumio volumio[5689]: info: ------------------------------ 66ms Dec 16 14:08:41 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%AF%94%E8%8E%89/%E5%82%BB%E7%93%9C%E5%B0%B1%E6%98%AF%E6%88%91/c838c39b-0592-4283-b07b-d3a801e95c78.jpg' Dec 16 14:08:46 volumio volumio[5689]: info: VolumeController::SetAlsaVolume38 Dec 16 14:08:46 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:46 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:46 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:08:47 volumio volumio[5689]: info: VolumeController::SetAlsaVolume100 Dec 16 14:08:47 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:08:47 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:08:47 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:09:02 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A2%81%E7%B4%AB%E4%B8%B9/%E7%B4%AB%E8%89%B2%E7%9A%84%E5%A3%B0%E9%9F%B3/6d8804e2-f4cd-4946-8a63-1376a9349641.jpg' Dec 16 14:09:02 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BB%8E%E6%98%8E/%E4%B8%80%E7%94%9F%E5%B9%B8%E7%A6%8F/28cabc8d-f82b-45b6-9d5d-396c16194902.jpg' Dec 16 14:09:03 volumio volumio[5689]: info: CoreCommandRouter::volumioSeek Dec 16 14:09:03 volumio volumio[5689]: info: CoreStateMachine::seek Dec 16 14:09:03 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:03 volumio volumio[5689]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.9.3:8200/MediaItems/8048.wav","realUri":"upnp/folder/http://192.168.9.3:8200/ctl/ContentDir@64$1","type":"song","albumart":"/albumart?cacheid=835&web=//extralarge&path=&metadata=false","artist":"","album":"","name":"01 囚鸟（彭羚）","title":"01 囚鸟（彭羚）","duration":294} Dec 16 14:09:03 volumio volumio[5689]: info: CoreStateMachine::startPlaybackTimer Dec 16 14:09:03 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:03 volumio volumio[5689]: info: [1765894143717] ControllerUPNPBrowser::seek Dec 16 14:09:03 volumio volumio[5689]: info: ControllerMpd::seek Dec 16 14:09:03 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:09:03 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:09:03 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:09:03 volumio volumio[5689]: error: null Dec 16 14:09:03 volumio volumio[5689]: info: Dec 16 14:09:03 volumio volumio[5689]: ---------------------------- MPD announces state update: player Dec 16 14:09:03 volumio volumio[5689]: info: ControllerMpd::getState Dec 16 14:09:03 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand status Dec 16 14:09:03 volumio volumio[5689]: info: sendMpdCommand status took 3 milliseconds Dec 16 14:09:03 volumio volumio[5689]: verbose: ControllerMpd::parseState Dec 16 14:09:03 volumio volumio[5689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 14:09:03 volumio volumio[5689]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 14:09:03 volumio volumio[5689]: verbose: ControllerMpd::parseTrackInfo Dec 16 14:09:03 volumio volumio[5689]: info: ControllerMpd::pushState Dec 16 14:09:03 volumio volumio[5689]: info: CoreCommandRouter::servicePushState Dec 16 14:09:03 volumio volumio[5689]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:03 volumio volumio[5689]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65342,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8048.wav","artist":null,"album":null,"uri":"http://192.168.9.3:8200/MediaItems/8048.wav","trackType":"wav"} Dec 16 14:09:03 volumio volumio[5689]: verbose: CURRENT POSITION 0 Dec 16 14:09:03 volumio volumio[5689]: info: CoreStateMachine::syncState stateService play Dec 16 14:09:03 volumio volumio[5689]: info: CoreStateMachine::syncState currentStatus play Dec 16 14:09:03 volumio volumio[5689]: info: Received an update from plugin. extracting info from payload Dec 16 14:09:03 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:09:03 volumio volumio[5689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:09:03 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:09:03 volumio volumio[5689]: info: CoreStateMachine::pushState Dec 16 14:09:03 volumio volumio[5689]: info: CoreCommandRouter::volumioPushState Dec 16 14:09:03 volumio volumio[5689]: info: ------------------------------ 47ms Dec 16 14:09:04 volumio volumio[5689]: 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/%E4%B8%80%E7%AC%91%E5%80%BE%E5%9F%8E/e9baf905-0f9b-448c-b7fe-6fb8de08d4d3.jpg' Dec 16 14:09:04 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B8%A9%E5%A5%95%E5%BF%83/%E4%B8%80%E8%B7%AF%E7%94%9F%E8%8A%B1/5d1d223a-b810-47b2-b3a7-522089f84511.jpg' Dec 16 14:09:05 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E7%8E%89%E5%88%9A/%E4%B8%87%E7%96%86/69c0a22a-af56-447e-960d-2eaab8636944.jpg' Dec 16 14:09:08 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%A5%E4%B8%BD/%E6%9E%89%E5%87%9D%E7%9C%89/a91cc3af-86e4-42d6-828a-b8fc27a3f667.jpg' Dec 16 14:09:08 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8/%E4%B8%89%E7%94%9F%E4%B8%89%E5%B9%B8/f42307b3-55b1-427d-9d7f-0929cb21955e.jpg' Dec 16 14:09:08 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%A8%E5%8D%83%E5%AC%85/%E4%B8%8A%E5%96%84%E5%A6%82%E9%85%92/255bb0e0-5242-4486-8ba9-3d22ef95b4ab.jpg' Dec 16 14:09:08 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A2%81%E7%B4%AB%E4%B8%B9/%E7%B4%AB%E8%89%B2%E7%9A%84%E5%A3%B0%E9%9F%B3/6d8804e2-f4cd-4946-8a63-1376a9349641.jpg' Dec 16 14:09:11 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%A7%A6%E6%B5%B7%E6%B8%85/%E4%B8%8D%E5%A6%82/ba10f062-30a7-40f7-ad46-6b2e055158ae.jpg' Dec 16 14:09:11 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9D%93%E9%A2%96/%E6%A2%A6%E5%8D%8E%E5%BD%95%20%E7%94%B5%E8%A7%86%E5%89%A7%E5%8E%9F%E5%A3%B0%E5%B8%A6/421e1cfa-5e8c-4bfe-8c97-cda657233262.jpg' Dec 16 14:09:11 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/G.E.M.%E9%82%93%E7%B4%AB%E6%A3%8B/%E5%90%AF%E7%A4%BA%E5%BD%95/2465c875-01c4-49c7-8092-d8bab6729c34.jpg' Dec 16 14:09:11 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%BF%E8%82%86/%E4%B8%8D%E7%94%9C%E6%83%85%E6%AD%8C/8d1465a5-749f-4c4d-9cbf-e2205dd94e14.jpg' Dec 16 14:09:13 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E5%AD%90%E8%B7%AF/%E5%9D%90%E4%B8%8A%E7%81%AB%E8%BD%A6%E5%8E%BB%E6%8B%89%E8%90%A8/0be963eb-d1eb-47f5-8dd8-de8d188d7ae4.jpg' Dec 16 14:09:14 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8E%AB%E5%8F%AB%E5%A7%90%E5%A7%90/%E4%B8%8D%E8%AF%A5%E7%94%A8%E6%83%85/fefe871f-80d7-4760-abbd-3a0966bc3424.jpg' Dec 16 14:09:15 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%BFYueYue/%E4%B8%8D%E8%B4%9F%E4%BA%BA%E9%97%B4/b6bfb81d-e1f5-44a0-abbc-38b58fcfe8dd.png' Dec 16 14:09:15 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8/%E4%B8%8D%E8%BF%87%E4%BA%BA%E9%97%B4/30cb24a4-89f7-46eb-84f4-8b2ede4b09da.jpg' Dec 16 14:09:19 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%84%E5%BF%83%E5%A6%8D/%E5%A5%BD%E5%8F%AF%E6%83%9C/1834325b-1bf9-4a68-9975-dffe553ee9a6.jpg' Dec 16 14:09:19 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/NCT%20DREAM/Glitch%20Mode%20-%20The%202nd%20Album/ad25248e-801f-4c4d-af35-c2914ea8e02e.png' Dec 16 14:09:20 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%84%E5%BF%83%E5%A6%8D/%E4%B8%80%E4%B8%87%E4%B8%AA%E8%88%8D%E4%B8%8D%E5%BE%97/88f38494-88cd-4046-8509-c7a54e321437.jpg' Dec 16 14:09:20 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lil%20Ghost%E5%B0%8F%E9%AC%BC/DEADLINE/b4d9f9fa-39f0-477a-8fae-aba7df99999c.jpg' Dec 16 14:09:23 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%8F%B6%E7%82%AB%E6%B8%85/%E4%B9%9D%E5%BC%A0%E6%9C%BA/736d4624-5f7e-4e85-b196-937d452883f0.png' Dec 16 14:09:24 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E8%8D%A3%E6%B5%A9/%E4%B9%A0%E6%83%AF%E6%99%9A%E7%9D%A1/adfa620f-f137-42a2-9cd5-718450572c32.jpg' Dec 16 14:09:25 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%BFYueYue/%E4%BA%91%E4%B8%8E%E6%B5%B7/796a0690-49ff-41e6-99eb-03af1fb9136c.jpg' Dec 16 14:09:25 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B8%83%E9%9F%B3%E7%9B%92/%E4%BA%91%E6%B7%B1%E4%B8%8D%E7%9F%A5%E5%A4%84/df78fd8a-6157-4806-ac02-249857b8ec47.jpg' Dec 16 14:09:25 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%97%BA%E4%BB%94%E5%B0%8F%E4%B9%94/%E4%BA%91%E8%BE%B9%E4%B9%A6%E5%9D%8A/abfb3650-827f-476f-aac2-67f2f14c9f1c.jpg' Dec 16 14:09:25 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8/%E4%BA%94%E5%8D%81%E5%B9%B4%E4%BB%A5%E5%90%8E/74d390d3-5cab-4a72-91b4-bfd9e56a3c28.jpg' Dec 16 14:09:25 volumio volumio[5689]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%A8%8B%E4%BD%B3%E4%BD%B3/%E4%BA%B2%E7%88%B1%E7%9A%84%E4%B8%8B%E9%9B%A8%E4%BA%86/1ff04e4b-5c85-4e23-ba63-966d3fa032c8.jpg' Dec 16 14:09:26 volumio volumio[5689]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 14:09:26 volumio volumio[5689]: Error: read ECONNRESET Dec 16 14:09:26 volumio volumio[5689]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Dec 16 14:09:26 volumio volumio[5689]: errno: -104, Dec 16 14:09:26 volumio volumio[5689]: code: 'ECONNRESET', Dec 16 14:09:26 volumio volumio[5689]: syscall: 'read' Dec 16 14:09:26 volumio volumio[5689]: } Dec 16 14:09:26 volumio volumio[5689]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 14:09:27 volumio sudo[5909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 14:08 Dec 16 14:09:27 volumio sudo[5909]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:27 volumio sudo[5909]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:29 volumio volumio-remote-updater[438]: [2025-12-16 14:09:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 14:09:29 volumio volumio-remote-updater[438]: [2025-12-16 14:09:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 14:09:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 14:09:29 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 14:09:29 volumio systemd[1]: Started dynamicswap service. Dec 16 14:09:29 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 16 14:09:30 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 14:09:30 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18. Dec 16 14:09:30 volumio systemd[1]: Started dynamicswap service. Dec 16 14:09:30 volumio systemd[1]: Stopped Volumio Backend Module. Dec 16 14:09:30 volumio systemd[1]: Started Volumio Backend Module. Dec 16 14:09:30 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 16 14:09:32 volumio volumio[5924]: info: ------------------------------------------- Dec 16 14:09:32 volumio volumio[5924]: info: ----- Volumio3 ---- Dec 16 14:09:32 volumio volumio[5924]: info: ------------------------------------------- Dec 16 14:09:32 volumio volumio[5924]: info: ----- System startup ---- Dec 16 14:09:32 volumio volumio[5924]: info: ------------------------------------------- Dec 16 14:09:32 volumio volumio[5924]: info: MYVOLUMIO Environment detected Dec 16 14:09:32 volumio volumio[5924]: info: Plugin folders cleanup Dec 16 14:09:32 volumio volumio[5924]: info: Scanning into folder /volumio/app/plugins/ Dec 16 14:09:32 volumio volumio[5924]: info: Scanning category audio_interface Dec 16 14:09:32 volumio volumio[5924]: info: Scanning category miscellanea Dec 16 14:09:32 volumio volumio[5924]: info: Scanning category music_service Dec 16 14:09:32 volumio volumio[5924]: info: Scanning category plugins.json Dec 16 14:09:32 volumio volumio[5924]: info: Scanning category system_controller Dec 16 14:09:32 volumio volumio[5924]: info: Scanning category user_interface Dec 16 14:09:32 volumio volumio[5924]: info: Scanning into folder /data/plugins/ Dec 16 14:09:32 volumio volumio[5924]: info: Plugin folders cleanup completed Dec 16 14:09:32 volumio volumio[5924]: info: ------------------------------------------- Dec 16 14:09:32 volumio volumio[5924]: info: ----- Core plugins startup ---- Dec 16 14:09:32 volumio volumio[5924]: info: ------------------------------------------- Dec 16 14:09:32 volumio volumio[5924]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 14:09:32 volumio volumio[5924]: info: Adding plugin upnp to MyMusic Plugins Dec 16 14:09:33 volumio volumio[5924]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 14:09:33 volumio volumio[5924]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 14:09:33 volumio volumio[5924]: info: Loading plugins from folder /data/plugins/ Dec 16 14:09:33 volumio volumio[5924]: info: Loading plugin "system"... Dec 16 14:09:33 volumio volumio[5924]: info: Loading plugin "appearance"... Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "network"... Dec 16 14:09:34 volumio volumio[5924]: info: Refreshing Cached IP Addresses Dec 16 14:09:34 volumio sudo[5947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 14:09:34 volumio sudo[5947]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "services"... Dec 16 14:09:34 volumio sudo[5947]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "alsa_controller"... Dec 16 14:09:34 volumio sudo[5950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 14:09:34 volumio sudo[5950]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:34 volumio sudo[5950]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "wizard"... Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "volumio_command_line_client"... Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "upnp"... Dec 16 14:09:34 volumio volumio[5924]: info: [1765894174394] Starting Upmpd Daemon Dec 16 14:09:34 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "my_music"... Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "mpd"... Dec 16 14:09:34 volumio volumio-remote-updater[438]: [2025-12-16 14:09:34] [connect] Successful connection Dec 16 14:09:34 volumio volumio[5924]: info: Creating MPD Configuration file Dec 16 14:09:34 volumio sudo[5957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 14:09:34 volumio volumio[5924]: info: Loading plugin "upnp_browser"... Dec 16 14:09:34 volumio sudo[5957]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:34 volumio sudo[5957]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:34 volumio sudo[5959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 14:09:34 volumio sudo[5959]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:34 volumio systemd[1]: Stopping Music Player Daemon... Dec 16 14:09:34 volumio systemd[1]: mpd.service: Succeeded. Dec 16 14:09:34 volumio systemd[1]: Stopped Music Player Daemon. Dec 16 14:09:34 volumio systemd[1]: Starting Music Player Daemon... Dec 16 14:09:35 volumio volumio[5924]: info: Loading plugin "networkfs"... Dec 16 14:09:35 volumio volumio[5924]: info: Starting Udev Watcher for removable devices Dec 16 14:09:35 volumio volumio[5924]: info: Ignoring mount for partition: boot Dec 16 14:09:35 volumio volumio[5924]: info: Ignoring mount for partition: volumio Dec 16 14:09:35 volumio volumio[5924]: info: Ignoring mount for partition: volumio_data Dec 16 14:09:35 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 14:09:35 volumio volumio[5924]: info: Loading plugin "alarm-clock"... Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "airplay_emulation"... Dec 16 14:09:36 volumio volumio[5924]: info: Starting Shairport Sync Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "last_100"... Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "webradio"... Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "i2s_dacs"... Dec 16 14:09:36 volumio volumio[5924]: info: I2S DAC not set, start Auto-detection Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "volumiodiscovery"... Dec 16 14:09:36 volumio volumio[5924]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 14:09:36 volumio volumio[5924]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 14:09:36 volumio volumio[5924]: *** WARNING *** For more information see Dec 16 14:09:36 volumio volumio[5924]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 14:09:36 volumio volumio[5924]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 14:09:36 volumio volumio[5924]: *** WARNING *** For more information see Dec 16 14:09:36 volumio node[5924]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 14:09:36 volumio node[5924]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 14:09:36 volumio node[5924]: *** WARNING *** For more information see Dec 16 14:09:36 volumio node[5924]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 14:09:36 volumio node[5924]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 14:09:36 volumio node[5924]: *** WARNING *** For more information see Dec 16 14:09:36 volumio volumio[5924]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 14:09:36 volumio volumio[5924]: info: Discovery: Started advertising with name: Volumio Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "outputs"... Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "albumart"... Dec 16 14:09:36 volumio volumio[5924]: info: Plugin example_plugin is not enabled Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "inputs"... Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "updater_comm"... Dec 16 14:09:36 volumio mpd[5964]: Dec 16 14:09 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 14:09:36 volumio mpd[5964]: Dec 16 14:09 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 14:09:36 volumio mpd[5964]: Dec 16 14:09 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 14:09:36 volumio systemd[1]: Started Music Player Daemon. Dec 16 14:09:36 volumio sudo[5959]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:36 volumio volumio[5924]: info: Plugin mpdemulation is not enabled Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "rest_api"... Dec 16 14:09:36 volumio volumio[5924]: info: Loading plugin "websocket"... Dec 16 14:09:36 volumio volumio[5924]: info: Loading i18n strings for locale zh Dec 16 14:09:36 volumio volumio[5924]: Updating browse sources language Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::initPlayerControls Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: Express server listening on port 3000 Dec 16 14:09:36 volumio volumio[5924]: [Metrics] WebUI: 5s 273.68ms Dec 16 14:09:36 volumio volumio[5924]: info: CoreStateMachine::resetVolumioState Dec 16 14:09:36 volumio volumio[5924]: info: CoreStateMachine::getcurrentVolume Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 14:09:36 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 14:09:36 volumio volumio[5924]: Forking 3 albumart workers Dec 16 14:09:36 volumio volumio[5924]: info: MPD Permissions set Dec 16 14:09:36 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 1 Dec 16 14:09:36 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 2 Dec 16 14:09:37 volumio volumio-remote-updater[438]: [2025-12-16 14:09:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765894174 101 Dec 16 14:09:37 volumio volumio[5924]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Dec 16 14:09:37 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 4 Dec 16 14:09:37 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 5 Dec 16 14:09:37 volumio volumio[5924]: info: MPD running with PID5964 Dec 16 14:09:37 volumio volumio[5924]: ,establishing connection Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:37 volumio volumio[5924]: info: Reloading queue from file Dec 16 14:09:37 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 6 Dec 16 14:09:37 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 7 Dec 16 14:09:37 volumio volumio[5924]: error: updateQueue error: null Dec 16 14:09:37 volumio volumio[5924]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 16 14:09:37 volumio volumio[5924]: info: VolumeController:: Volume=100 Mute =false Dec 16 14:09:37 volumio volumio[5924]: info: CoreStateMachine::pushState Dec 16 14:09:37 volumio volumio[5924]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::volumioPushState Dec 16 14:09:37 volumio volumio[5924]: info: CoreStateMachine::updateTrackBlock Dec 16 14:09:37 volumio volumio[5924]: info: CorePlayQueue::getTrackBlock Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 14:09:37 volumio volumio[5924]: info: CoreStateMachine::setRepeat null single undefined Dec 16 14:09:37 volumio volumio[5924]: info: CoreStateMachine::pushState Dec 16 14:09:37 volumio volumio[5924]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::volumioPushState Dec 16 14:09:37 volumio volumio[5924]: info: CoreStateMachine::setRandom null Dec 16 14:09:37 volumio volumio[5924]: info: CoreStateMachine::pushState Dec 16 14:09:37 volumio volumio[5924]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:37 volumio volumio[5924]: info: CoreCommandRouter::volumioPushState Dec 16 14:09:37 volumio volumio[5924]: info: Setting Device type: Raspberry PI Dec 16 14:09:37 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 8 Dec 16 14:09:38 volumio volumio[5924]: error: updateQueue error: null Dec 16 14:09:38 volumio volumio[5924]: info: Completed loading Core Plugins Dec 16 14:09:38 volumio volumio[5924]: info: Preparing to generate the ALSA configuration file Dec 16 14:09:38 volumio volumio[5924]: info: VolumeController:: Volume=100 Mute =false Dec 16 14:09:38 volumio volumio[5924]: info: CoreStateMachine::pushState Dec 16 14:09:38 volumio volumio[5924]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::volumioPushState Dec 16 14:09:38 volumio volumio[5924]: info: Discovery: adding 3163c3de-8a53-4ccc-82cc-d47f0cc105f1 Dec 16 14:09:38 volumio volumio[5924]: info: mDNS: Found device Volumio Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::volumioGetState Dec 16 14:09:38 volumio volumio[5924]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:38 volumio volumio[5924]: info: Asound.conf file unchanged, so no further update is needed Dec 16 14:09:38 volumio volumio[5924]: info: Output device has changed, restarting MPD Dec 16 14:09:38 volumio volumio[5924]: info: Output device has changed, restarting Shairport Sync Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 14:09:38 volumio sudo[6018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 14:09:38 volumio sudo[6018]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:38 volumio sudo[6018]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:38 volumio sudo[6020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 14:09:38 volumio sudo[6020]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:38 volumio volumio[5924]: Starting albumart workers Dec 16 14:09:38 volumio volumio[5924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 14:09:38 volumio volumio[5924]: info: ___________ START PLUGINS ___________ Dec 16 14:09:38 volumio systemd[1]: Stopping Music Player Daemon... Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 14:09:38 volumio volumio[5924]: info: [1765894178647] CoreMusicLibrary::Adding element Media Servers Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 14:09:38 volumio volumio[5924]: Cannot find translation for sourceMedia Servers Dec 16 14:09:38 volumio systemd[1]: mpd.service: Succeeded. Dec 16 14:09:38 volumio systemd[1]: Stopped Music Player Daemon. Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:38 volumio systemd[1]: Starting Music Player Daemon... Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 14:09:38 volumio volumio[5924]: Starting albumart workers Dec 16 14:09:38 volumio volumio[5924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 14:09:38 volumio volumio[5924]: Starting albumart workers Dec 16 14:09:38 volumio volumio[5924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 14:09:38 volumio volumio[5924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 14:09:38 volumio volumio[5924]: info: [1765894178998] CoreMusicLibrary::Adding element Last_100 Dec 16 14:09:39 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 14:09:39 volumio volumio[5924]: Cannot find translation for sourceMedia Servers Dec 16 14:09:39 volumio volumio[5924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 14:09:39 volumio volumio[5924]: info: [1765894179011] CoreMusicLibrary::Adding element Webradio Dec 16 14:09:39 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 14:09:39 volumio volumio[5924]: Cannot find translation for sourceMedia Servers Dec 16 14:09:39 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 14:09:39 volumio volumio[5924]: info: Volumio Calling Home Dec 16 14:09:39 volumio volumio[5924]: info: Completed starting Core Plugins Dec 16 14:09:39 volumio volumio[5924]: info: ------------------------------------------- Dec 16 14:09:39 volumio volumio[5924]: info: ----- MyVolumio plugins startup ---- Dec 16 14:09:39 volumio volumio[5924]: info: ------------------------------------------- Dec 16 14:09:39 volumio volumio[5924]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 16 14:09:39 volumio volumio[5924]: info: Discovery: this is already registered, 3163c3de-8a53-4ccc-82cc-d47f0cc105f1 Dec 16 14:09:39 volumio volumio[5924]: info: mDNS: Found device Volumio Dec 16 14:09:39 volumio volumio[5924]: info: CoreCommandRouter::volumioGetState Dec 16 14:09:39 volumio volumio[5924]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:40 volumio volumio[5924]: info: MPD Permissions set Dec 16 14:09:40 volumio volumio[5924]: info: Volumio called home Dec 16 14:09:40 volumio volumio[5924]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 14:09:40 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 10 Dec 16 14:09:40 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 11 Dec 16 14:09:40 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 12 Dec 16 14:09:40 volumio volumio[5924]: info: Starting Shairport Sync Dec 16 14:09:40 volumio volumio[5924]: info: Starting Shairport Sync Dec 16 14:09:40 volumio volumio[5924]: info: Starting Shairport Sync Dec 16 14:09:40 volumio sudo[6059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 14:09:40 volumio sudo[6059]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:40 volumio sudo[6062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 14:09:40 volumio sudo[6062]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 14:09:40 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 16 14:09:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 14:09:40 volumio sudo[6064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 14:09:40 volumio sudo[6064]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 14:09:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 14:09:40 volumio sudo[6059]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 14:09:40 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 16 14:09:40 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 16 14:09:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 14:09:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 14:09:40 volumio sudo[6064]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:40 volumio sudo[6062]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:40 volumio volumio[5924]: info: Shairport-Sync Started Dec 16 14:09:40 volumio volumio[5924]: Error adding Membership: Error: addMembership EINVAL Dec 16 14:09:40 volumio volumio[5924]: info: Shairport-Sync Started Dec 16 14:09:40 volumio volumio[5924]: info: Shairport-Sync Started Dec 16 14:09:40 volumio volumio[5924]: info: CoreCommandRouter::volumioGetState Dec 16 14:09:40 volumio volumio[5924]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:40 volumio volumio[5924]: 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/%E4%BA%BA%E9%97%B4%E7%83%9F%E7%81%AB/7ae63127-d47f-4491-8c67-94fc6f3aa711.jpg' Dec 16 14:09:41 volumio mpd[6036]: Dec 16 14:09 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 14:09:41 volumio mpd[6036]: Dec 16 14:09 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 14:09:41 volumio mpd[6036]: Dec 16 14:09 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 14:09:41 volumio systemd[1]: Started Music Player Daemon. Dec 16 14:09:41 volumio sudo[6020]: pam_unix(sudo:session): session closed for user root Dec 16 14:09:41 volumio volumio[5924]: error: updateQueue error: null Dec 16 14:09:41 volumio volumio[5924]: verbose: New Socket.io Connection to 192.168.9.5 from 192.168.9.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Total Clients: 13 Dec 16 14:09:41 volumio volumio[5924]: info: CoreCommandRouter::volumioGetState Dec 16 14:09:41 volumio volumio[5924]: info: CorePlayQueue::getTrack 0 Dec 16 14:09:41 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 14:09:41 volumio volumio[5924]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 14:09:41 volumio volumio[5924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 14:09:41 volumio volumio[5924]: info: Listing playlists Dec 16 14:09:41 volumio volumio[5924]: info: CoreCommandRouter::volumioGetQueue Dec 16 14:09:41 volumio volumio[5924]: info: CoreStateMachine::getQueue Dec 16 14:09:41 volumio volumio[5924]: info: CorePlayQueue::getQueue Dec 16 14:09:44 volumio volumio[5924]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 14:09:44 volumio volumio[5924]: Error: connect ECONNREFUSED 104.244.43.136:443 Dec 16 14:09:44 volumio volumio[5924]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 16 14:09:44 volumio volumio[5924]: errno: -111, Dec 16 14:09:44 volumio volumio[5924]: code: 'ECONNREFUSED', Dec 16 14:09:44 volumio volumio[5924]: syscall: 'connect', Dec 16 14:09:44 volumio volumio[5924]: address: '104.244.43.136', Dec 16 14:09:44 volumio volumio[5924]: port: 443 Dec 16 14:09:44 volumio volumio[5924]: } Dec 16 14:09:44 volumio volumio[5924]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 14:09:44 volumio sudo[6090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 14:08 Dec 16 14:09:44 volumio sudo[6090]: 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"