Jan 10 14:55:00 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:55:00 volumio volumio[1139]: info: CURURI: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon Jan 10 14:55:00 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/01. Speak to Me.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/03. On the Run.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/04. Time.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/05. The Great Gig in the Sky.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/06. Money.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/07. Us and Them.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/08. Any Colour You Like.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/09. Brain Damage.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/10. Eclipse.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/01. Speak to Me.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/03. On the Run.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/04. Time.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/05. The Great Gig in the Sky.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/06. Money.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F01.%20Speak%20to%20Me.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/01. Speak to Me.m4a Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F02.%20Breathe.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F03.%20On%20the%20Run.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/03. On the Run.m4a Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F04.%20Time.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/04. Time.m4a Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F05.%20The%20Great%20Gig%20in%20the%20Sky.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/05. The Great Gig in the Sky.m4a Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F06.%20Money.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/06. Money.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:55:00 volumio volumio[1139]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::ClearQueue Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::stop Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::clearPlayQueue Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::saveQueue Jan 10 14:55:00 volumio volumio[1139]: info: CoreCommandRouter::volumioPushQueue Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::addQueueItems Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::addQueueItems Jan 10 14:55:00 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/01. Speak to Me.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/01. Speak to Me.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a Jan 10 14:55:00 volumio volumio[1139]: info: CoreCommandRouter::volumioPushQueue Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::saveQueue Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jan 10 14:55:00 volumio volumio[1139]: info: CoreCommandRouter::volumioPlay Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::play index 1 Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::addQueueItems Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::addQueueItems Jan 10 14:55:00 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/03. On the Run.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/03. On the Run.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/04. Time.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/04. Time.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/05. The Great Gig in the Sky.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/05. The Great Gig in the Sky.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/06. Money.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/06. Money.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/07. Us and Them.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/07. Us and Them.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/08. Any Colour You Like.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/08. Any Colour You Like.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/09. Brain Damage.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/09. Brain Damage.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/10. Eclipse.m4a Jan 10 14:55:00 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/10. Eclipse.m4a in service mpd Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::stop Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::play index undefined Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::startPlaybackTimer Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:00 volumio volumio[1139]: verbose: ControllerMpd::clearAddPlayTracks NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a Jan 10 14:55:00 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F07.%20Us%20and%20Them.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/07. Us and Them.m4a Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F08.%20Any%20Colour%20You%20Like.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/08. Any Colour You Like.m4a Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F09.%20Brain%20Damage.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/09. Brain Damage.m4a Jan 10 14:55:00 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Pink%20Floyd/The%20Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FALAC%2F1973%20Dark%20Side%20Of%20The%20Moon%2F10.%20Eclipse.m4a&metadata=false Jan 10 14:55:00 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/10. Eclipse.m4a Jan 10 14:55:00 volumio volumio[1139]: info: sendMpdCommand stop took 8 milliseconds Jan 10 14:55:00 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 14:55:00 volumio volumio[1139]: info: CoreCommandRouter::volumioPushQueue Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::saveQueue Jan 10 14:55:00 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jan 10 14:55:00 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jan 10 14:55:02 volumio volumio[1139]: info: Jan 10 14:55:02 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jan 10 14:55:02 volumio volumio[1139]: info: Ignoring MPD Status Update Jan 10 14:55:02 volumio volumio[1139]: info: sendMpdCommand clear took 2087 milliseconds Jan 10 14:55:02 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand add "NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a" Jan 10 14:55:02 volumio volumio[1139]: info: Jan 10 14:55:02 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jan 10 14:55:02 volumio volumio[1139]: info: Ignoring MPD Status Update Jan 10 14:55:02 volumio volumio[1139]: info: Jan 10 14:55:02 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jan 10 14:55:02 volumio volumio[1139]: info: Ignoring MPD Status Update Jan 10 14:55:02 volumio volumio[1139]: error: updateQueue error: null Jan 10 14:55:02 volumio volumio[1139]: info: Jan 10 14:55:02 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jan 10 14:55:02 volumio volumio[1139]: info: Ignoring MPD Status Update Jan 10 14:55:02 volumio volumio[1139]: info: ------------------------------ 20ms Jan 10 14:55:02 volumio volumio[1139]: info: sendMpdCommand add "NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a" took 18 milliseconds Jan 10 14:55:02 volumio volumio[1139]: info: ------------------------------ 14ms Jan 10 14:55:02 volumio volumio[1139]: info: ------------------------------ 13ms Jan 10 14:55:02 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand play Jan 10 14:55:02 volumio volumio[1139]: info: Jan 10 14:55:02 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jan 10 14:55:02 volumio volumio[1139]: info: Ignoring MPD Status Update Jan 10 14:55:02 volumio volumio[1139]: info: Jan 10 14:55:02 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jan 10 14:55:02 volumio volumio[1139]: info: Ignoring MPD Status Update Jan 10 14:55:02 volumio volumio[1139]: info: ------------------------------ 7ms Jan 10 14:55:02 volumio volumio[1139]: info: sendMpdCommand play took 6 milliseconds Jan 10 14:55:02 volumio volumio[1139]: info: ------------------------------ 3ms Jan 10 14:55:02 volumio volumio[1139]: info: ------------------------------ 3ms Jan 10 14:55:03 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 10 14:55:03 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:55:03 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:55:03 volumio upmpdcli[1907]: Could not open config: /tmp/upmpdcli.conf Jan 10 14:55:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 14:55:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 14:55:03 volumio volumio[1139]: info: Jan 10 14:55:03 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:03 volumio volumio[1139]: info: Jan 10 14:55:03 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:03 volumio volumio[1139]: info: Jan 10 14:55:03 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:03 volumio volumio[1139]: info: Jan 10 14:55:03 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:03 volumio volumio[1139]: info: Jan 10 14:55:03 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:03 volumio volumio[1139]: info: Jan 10 14:55:03 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand status took 3 milliseconds Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand status took 3 milliseconds Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand status took 5 milliseconds Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand status took 4 milliseconds Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand status took 4 milliseconds Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand status took 4 milliseconds Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:03 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus stop Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:03 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:55:03 volumio volumio[1139]: info: Received an update from plugin. extracting info from payload Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:03 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:55:03 volumio volumio[1139]: info: Received an update from plugin. extracting info from payload Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: ------------------------------ 28ms Jan 10 14:55:03 volumio volumio[1139]: info: ------------------------------ 38ms Jan 10 14:55:03 volumio volumio[1139]: info: ------------------------------ 38ms Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 10 14:55:03 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:03 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:03 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:55:03 volumio volumio[1139]: info: Received an update from plugin. extracting info from payload Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:03 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:55:03 volumio volumio[1139]: info: Received an update from plugin. extracting info from payload Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:03 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:55:03 volumio volumio[1139]: info: Received an update from plugin. extracting info from payload Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:03 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:03 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:03 volumio volumio[1139]: info: ------------------------------ 70ms Jan 10 14:55:03 volumio volumio[1139]: info: ------------------------------ 70ms Jan 10 14:55:03 volumio volumio[1139]: info: ------------------------------ 69ms Jan 10 14:55:06 volumio ntpd[1074]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jan 10 14:55:06 volumio ntpd[1074]: DNS: dns_check: DNS error: -11, System error Jan 10 14:55:06 volumio ntpd[1074]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jan 10 14:55:07 volumio ntpd[1074]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 10 14:55:18 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Jan 10 14:55:18 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:55:18 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:55:18 volumio upmpdcli[1947]: Could not open config: /tmp/upmpdcli.conf Jan 10 14:55:18 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 14:55:18 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 10 14:55:22 volumio volumio[1139]: info: Adding plugin bluetooth to MyMusic Plugins Jan 10 14:55:22 volumio volumio[1139]: info: Adding plugin multiroom to MyMusic Plugins Jan 10 14:55:22 volumio volumio[1139]: info: Adding plugin metavolumio to MyMusic Plugins Jan 10 14:55:22 volumio volumio[1139]: info: Adding plugin cd_controller to MyMusic Plugins Jan 10 14:55:22 volumio volumio[1139]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 10 14:55:22 volumio volumio[1139]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 10 14:55:22 volumio volumio[1139]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 10 14:55:22 volumio volumio[1139]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 10 14:55:23 volumio volumio[1139]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 10 14:55:23 volumio volumio[1139]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 10 14:55:23 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 14:55:23 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 14:55:23 volumio volumio[1139]: info: Starting MyVolumio Remote Streaming Endpoints Jan 10 14:55:23 volumio volumio[1139]: info: MyVolumio not started Jan 10 14:55:23 volumio volumio[1139]: info: Initializing device activation check Jan 10 14:55:23 volumio volumio[1139]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 10 14:55:23 volumio volumio[1139]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 10 14:55:23 volumio volumio[1139]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 10 14:55:23 volumio volumio[1139]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 10 14:55:23 volumio volumio[1139]: info: Streaming services startup Jan 10 14:55:23 volumio volumio[1139]: info: Starting Streaming Daemon Jan 10 14:55:23 volumio volumio[1139]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 10 14:55:23 volumio sudo[1970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 10 14:55:23 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 14:55:23 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root Jan 10 14:55:23 volumio volumio[1139]: error: Cannot start Volumio Streaming Daemon Jan 10 14:55:23 volumio volumio[1139]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 10 14:55:23 volumio volumio[1139]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 10 14:55:26 volumio ntpd[1074]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jan 10 14:55:26 volumio ntpd[1074]: DNS: Pool taking: 151.0.2.54 Jan 10 14:55:26 volumio ntpd[1074]: DNS: Pool taking: 194.87.84.92 Jan 10 14:55:26 volumio ntpd[1074]: DNS: Pool taking: 213.33.141.134 Jan 10 14:55:26 volumio ntpd[1074]: DNS: Pool taking: 109.195.84.246 Jan 10 14:55:26 volumio ntpd[1074]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jan 10 14:55:27 volumio ntpd[1074]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 10 14:55:31 volumio kernel: usb 1-2: USB disconnect, device number 3 Jan 10 14:55:31 volumio volumio[1139]: info: Jan 10 14:55:31 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:31 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:31 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:32 volumio volumio[1139]: info: Jan 10 14:55:32 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:32 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:32 volumio volumio[1139]: info: Jan 10 14:55:32 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:32 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand status took 10 milliseconds Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand status took 7 milliseconds Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand status took 21 milliseconds Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand clearerror took 15 milliseconds Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand clearerror took 15 milliseconds Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:32 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:32 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:32 volumio volumio[1139]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36027,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1653 Kbps","isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:32 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::syncState stateService pause Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:32 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:32 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:32 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:32 volumio volumio[1139]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36027,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1653 Kbps","isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:32 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::syncState stateService pause Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:32 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:32 volumio volumio[1139]: info: ------------------------------ 39ms Jan 10 14:55:32 volumio volumio[1139]: info: ------------------------------ 36ms Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand clearerror took 17 milliseconds Jan 10 14:55:32 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 10 14:55:32 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:55:32 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:55:32 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:32 volumio volumio[1139]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36027,"duration":166,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1653 Kbps","isStreaming":false,"title":"Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Xenon_Audio/ALAC/1973 Dark Side Of The Moon/02. Breathe.m4a","trackType":"m4a"} Jan 10 14:55:32 volumio volumio[1139]: verbose: CURRENT POSITION 1 Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::syncState stateService pause Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:55:32 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:32 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:32 volumio volumio[1139]: info: ------------------------------ 46ms Jan 10 14:55:32 volumio volumio[1139]: info: Jan 10 14:55:32 volumio volumio[1139]: ---------------------------- USB Audio Device Detached Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 14:55:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 10 14:55:32 volumio ntpd[1074]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jan 10 14:55:32 volumio ntpd[1074]: DNS: Pool taking: 193.106.93.116 Jan 10 14:55:32 volumio ntpd[1074]: DNS: Pool taking: 45.141.102.99 Jan 10 14:55:32 volumio ntpd[1074]: DNS: Pool taking: 89.179.240.219 Jan 10 14:55:32 volumio ntpd[1074]: DNS: Pool taking: 151.0.2.53 Jan 10 14:55:32 volumio ntpd[1074]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jan 10 14:55:33 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Jan 10 14:55:33 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:55:33 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:55:33 volumio upmpdcli[2021]: Could not open config: /tmp/upmpdcli.conf Jan 10 14:55:33 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 14:55:33 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 14:55:33 volumio ntpd[1074]: CLOCK: time stepped by -1.012860 Jan 10 14:55:33 volumio ntpd[1074]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jan 10 14:55:33 volumio systemd-journald[545]: Time jumped backwards, rotating. Jan 10 14:55:34 volumio kernel: usb 1-2: new high-speed USB device number 4 using ehci-pci Jan 10 14:55:34 volumio kernel: usb 1-2: New USB device found, idVendor=16d0, idProduct=0a23, bcdDevice= 1.9b Jan 10 14:55:34 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jan 10 14:55:34 volumio kernel: usb 1-2: Product: Combo384 Amanero Jan 10 14:55:34 volumio kernel: usb 1-2: Manufacturer: Amanero Technologies Jan 10 14:55:34 volumio kernel: usb 1-2: SerialNumber: 415-001 Jan 10 14:55:34 volumio kernel: usb 1-2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Jan 10 14:55:34 volumio kernel: usb 1-2: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Jan 10 14:55:34 volumio kernel: usb 1-2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Jan 10 14:55:34 volumio kernel: usb 1-2: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Jan 10 14:55:35 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 10 14:55:35 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 10 14:55:35 volumio volumio[1139]: info: Jan 10 14:55:35 volumio volumio[1139]: ---------------------------- USB Audio Device Attached Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::Close All Modals sent Jan 10 14:55:35 volumio volumio[1139]: info: Preparing to save Alsa Options, stopping services first Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jan 10 14:55:35 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::volumioPause Jan 10 14:55:35 volumio volumio[1139]: info: CoreStateMachine::pause Jan 10 14:55:35 volumio volumio[1139]: info: CoreStateMachine::stPlaybackTimer Jan 10 14:55:35 volumio volumio[1139]: info: CoreStateMachine::servicePause Jan 10 14:55:35 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::servicePause Jan 10 14:55:35 volumio volumio[1139]: info: ControllerMpd::pause Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand pause Jan 10 14:55:35 volumio volumio[1139]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 14:55:35 volumio volumio[1139]: info: Setting mixer PCM for card Combo384 Amanero Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 10 14:55:35 volumio volumio[1139]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 10 14:55:35 volumio volumio[1139]: info: Disabling external Volume Control Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 14:55:35 volumio volumio[1139]: info: Preparing to generate the ALSA configuration file Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 14:55:35 volumio volumio[1139]: info: Asound.conf file unchanged, so no further update is needed Jan 10 14:55:35 volumio volumio[1139]: info: Output device has changed, restarting MPD Jan 10 14:55:35 volumio sudo[2060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 14:55:35 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 14:55:35 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Jan 10 14:55:35 volumio volumio[1139]: info: VolumeController:: Volume=100 Mute =false Jan 10 14:55:35 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:35 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:35 volumio sudo[2062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 14:55:35 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 14:55:35 volumio volumio[1139]: info: Jan 10 14:55:35 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:35 volumio volumio[1139]: info: sendMpdCommand pause took 183 milliseconds Jan 10 14:55:35 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:35 volumio volumio[1139]: info: Jan 10 14:55:35 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:35 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:35 volumio volumio[1139]: info: Jan 10 14:55:35 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:55:35 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:55:35 volumio volumio[1139]: info: MPD Permissions set Jan 10 14:55:35 volumio volumio[1139]: info: sendMpdCommand status took 34 milliseconds Jan 10 14:55:35 volumio volumio[1139]: info: sendMpdCommand status took 33 milliseconds Jan 10 14:55:35 volumio volumio[1139]: info: sendMpdCommand status took 27 milliseconds Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:35 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:55:35 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:35 volumio volumio[1139]: error: MPD error: Error: read ECONNRESET Jan 10 14:55:35 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 10 14:55:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 10 14:55:35 volumio systemd[1]: mpd.service: Consumed 2.155s CPU time. Jan 10 14:55:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 10 14:55:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 10 14:55:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 10 14:55:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 10 14:55:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 10 14:55:35 volumio sudo[2083]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 10 14:55:35 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 10 14:55:35 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Jan 10 14:55:35 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jan 10 14:55:36 volumio mpd[2085]: 2026-01-10T14:55:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 10 14:55:36 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 10 14:55:36 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Jan 10 14:55:36 volumio volumio[1139]: error: updateQueue error: null Jan 10 14:55:37 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 14:55:37 volumio volumio[1139]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 10 14:55:37 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jan 10 14:55:37 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:39 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 10 14:55:39 volumio volumio[1139]: info: CoreStateMachine::getcurrentVolume Jan 10 14:55:39 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 14:55:39 volumio volumio[1139]: info: VolumeController:: Volume=100 Mute =false Jan 10 14:55:39 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:39 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:39 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:55:39 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:39 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jan 10 14:55:39 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jan 10 14:55:39 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 14:55:39 volumio sudo[2096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 10 14:55:39 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 14:55:39 volumio volumio[1139]: info: VolumeController:: Volume=100 Mute =false Jan 10 14:55:39 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:39 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:39 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:39 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 10 14:55:39 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 10 14:55:39 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 10 14:55:39 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 10 14:55:39 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Jan 10 14:55:39 volumio mpd_monitor.sh[2099]: MPD Monitor Service: Starting MPD Monitor Service Jan 10 14:55:39 volumio volumio[1139]: info: Successfully started MPD Monitor Jan 10 14:55:40 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:40 volumio volumio[1139]: error: updateQueue error: null Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jan 10 14:55:42 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:42 volumio volumio[1139]: info: CoreCommandRouter::volumioPlay Jan 10 14:55:42 volumio volumio[1139]: info: CoreStateMachine::play index undefined Jan 10 14:55:42 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:55:42 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:42 volumio volumio[1139]: info: CoreStateMachine::startPlaybackTimer Jan 10 14:55:42 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:42 volumio volumio[1139]: info: ControllerMpd::resume Jan 10 14:55:42 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand play Jan 10 14:55:42 volumio volumio[1139]: info: sendMpdCommand play took 1 milliseconds Jan 10 14:55:47 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Jan 10 14:55:47 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:55:47 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:55:47 volumio upmpdcli[2126]: Could not open config: /tmp/upmpdcli.conf Jan 10 14:55:47 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 14:55:47 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 14:55:51 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 14:55:51 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 14:55:51 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 14:55:51 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 10 14:55:52 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 14:55:59 volumio volumio[1139]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jan 10 14:55:59 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:59 volumio volumio[1139]: info: Restoring Previous Volume level: 100 false false Jan 10 14:55:59 volumio volumio[1139]: info: Output device has changed, restarting MPD Jan 10 14:55:59 volumio sudo[2162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 14:55:59 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 14:55:59 volumio sudo[2162]: pam_unix(sudo:session): session closed for user root Jan 10 14:55:59 volumio volumio[1139]: info: Volume configurations have been set Jan 10 14:55:59 volumio sudo[2165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 14:55:59 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 14:55:59 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 10 14:55:59 volumio volumio[1139]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 10 14:55:59 volumio volumio[1139]: info: Disabling external Volume Control Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 14:55:59 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:55:59 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:55:59 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:55:59 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 10 14:55:59 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 10 14:55:59 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 10 14:55:59 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 10 14:55:59 volumio volumio[1139]: info: MPD Permissions set Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 10 14:55:59 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 14:55:59 volumio sudo[2175]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 10 14:55:59 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 10 14:55:59 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 10 14:55:59 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 14:56:00 volumio mpd[2189]: 2026-01-10T14:56:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 10 14:56:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 10 14:56:00 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root Jan 10 14:56:00 volumio volumio[1139]: error: updateQueue error: null Jan 10 14:56:03 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Jan 10 14:56:03 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:56:03 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:56:03 volumio upmpdcli[2209]: Could not open config: /tmp/upmpdcli.conf Jan 10 14:56:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 14:56:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 14:56:03 volumio sudo[2211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 10 14:56:03 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 14:56:03 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 10 14:56:03 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 10 14:56:03 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 10 14:56:03 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 10 14:56:03 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root Jan 10 14:56:03 volumio mpd_monitor.sh[2213]: MPD Monitor Service: Starting MPD Monitor Service Jan 10 14:56:03 volumio volumio[1139]: info: Successfully started MPD Monitor Jan 10 14:56:04 volumio volumio[1139]: info: VolumeController::SetAlsaVolume100 Jan 10 14:56:04 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:56:04 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:56:04 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:56:04 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:56:05 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jan 10 14:56:05 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:56:06 volumio volumio[1139]: info: CoreCommandRouter::volumioPlay Jan 10 14:56:06 volumio volumio[1139]: info: CoreStateMachine::play index undefined Jan 10 14:56:06 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:56:06 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:56:06 volumio volumio[1139]: info: CoreStateMachine::startPlaybackTimer Jan 10 14:56:06 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:56:06 volumio volumio[1139]: info: ControllerMpd::resume Jan 10 14:56:06 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand play Jan 10 14:56:06 volumio volumio[1139]: info: sendMpdCommand play took 1 milliseconds Jan 10 14:56:11 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:56:11 volumio volumio[1139]: info: CURURI: music-library/NAS/Xenon_Audio/FLAC Jan 10 14:56:11 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:56:15 volumio volumio[1139]: info: CURURI: music-library/NAS/Xenon_Audio/MQA Jan 10 14:56:16 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:16 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/MQA/David Elias - The Window - Vision Of Her (352.8 kHz).mqa.flac Jan 10 14:56:16 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/MQA/David Elias - The Window - Vision Of Her (352.8 kHz).mqa.flac in service mpd Jan 10 14:56:16 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=David%20Elias/The%20Window/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FMQA%2FDavid%20Elias%20-%20The%20Window%20-%20Vision%20Of%20Her%20(352.8%20kHz).mqa.flac&metadata=false Jan 10 14:56:16 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/MQA/David Elias - The Window - Vision Of Her (352.8 kHz).mqa.flac Jan 10 14:56:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:56:17 volumio volumio[1139]: info: CURURI: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021 Jan 10 14:56:17 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:17 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/01. Howler.flac Jan 10 14:56:17 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/02. Mandrill.flac Jan 10 14:56:17 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac Jan 10 14:56:17 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/04. Vervet.flac Jan 10 14:56:17 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/05. Howler's End.flac Jan 10 14:56:17 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/01. Howler.flac in service mpd Jan 10 14:56:17 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/02. Mandrill.flac in service mpd Jan 10 14:56:17 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac in service mpd Jan 10 14:56:17 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/04. Vervet.flac in service mpd Jan 10 14:56:17 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Martin%20Gore/The%20Third%20Chimpanzee%20(EP)/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FMQA%2FMartin%20Gore%20-%20The%20Third%20Chimpanzee%20(EP)%20%5BMQA%2024-44%2C1%5D%202021%2F01.%20Howler.flac&metadata=false Jan 10 14:56:17 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/01. Howler.flac Jan 10 14:56:17 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Martin%20Gore/The%20Third%20Chimpanzee%20(EP)/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FMQA%2FMartin%20Gore%20-%20The%20Third%20Chimpanzee%20(EP)%20%5BMQA%2024-44%2C1%5D%202021%2F02.%20Mandrill.flac&metadata=false Jan 10 14:56:17 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/02. Mandrill.flac Jan 10 14:56:17 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Martin%20Gore/The%20Third%20Chimpanzee%20(EP)/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FMQA%2FMartin%20Gore%20-%20The%20Third%20Chimpanzee%20(EP)%20%5BMQA%2024-44%2C1%5D%202021%2F03.%20Capuchin.flac&metadata=false Jan 10 14:56:17 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac Jan 10 14:56:17 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Martin%20Gore/The%20Third%20Chimpanzee%20(EP)/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FMQA%2FMartin%20Gore%20-%20The%20Third%20Chimpanzee%20(EP)%20%5BMQA%2024-44%2C1%5D%202021%2F04.%20Vervet.flac&metadata=false Jan 10 14:56:17 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/04. Vervet.flac Jan 10 14:56:17 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/05. Howler's End.flac in service mpd Jan 10 14:56:17 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Martin%20Gore/The%20Third%20Chimpanzee%20(EP)/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FMQA%2FMartin%20Gore%20-%20The%20Third%20Chimpanzee%20(EP)%20%5BMQA%2024-44%2C1%5D%202021%2F05.%20Howler's%20End.flac&metadata=false Jan 10 14:56:17 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/05. Howler's End.flac Jan 10 14:56:18 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8. Jan 10 14:56:18 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:56:18 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:56:18 volumio upmpdcli[2249]: Could not open config: /tmp/upmpdcli.conf Jan 10 14:56:18 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 14:56:18 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 14:56:18 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:18 volumio volumio[1139]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::ClearQueue Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::stop Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::stPlaybackTimer Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:56:18 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:56:18 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::serviceStop Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::getTrack 1 Jan 10 14:56:18 volumio volumio[1139]: info: CoreCommandRouter::serviceStop Jan 10 14:56:18 volumio volumio[1139]: info: ControllerMpd::stop Jan 10 14:56:18 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::clearPlayQueue Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::saveQueue Jan 10 14:56:18 volumio volumio[1139]: info: CoreCommandRouter::volumioPushQueue Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::addQueueItems Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::addQueueItems Jan 10 14:56:18 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:18 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/01. Howler.flac Jan 10 14:56:18 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/01. Howler.flac Jan 10 14:56:18 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/02. Mandrill.flac Jan 10 14:56:18 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/02. Mandrill.flac Jan 10 14:56:18 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac Jan 10 14:56:18 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac Jan 10 14:56:18 volumio volumio[1139]: info: CoreCommandRouter::volumioPushQueue Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::saveQueue Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jan 10 14:56:18 volumio volumio[1139]: info: CoreCommandRouter::volumioPlay Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::play index 2 Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::addQueueItems Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::addQueueItems Jan 10 14:56:18 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:18 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/04. Vervet.flac Jan 10 14:56:18 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/04. Vervet.flac Jan 10 14:56:18 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/05. Howler's End.flac Jan 10 14:56:18 volumio volumio[1139]: info: Using cached record of: music-library/NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/05. Howler's End.flac Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::stop Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:56:18 volumio volumio[1139]: info: CoreCommandRouter::volumioPushQueue Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::saveQueue Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::play index undefined Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:18 volumio volumio[1139]: info: CoreStateMachine::startPlaybackTimer Jan 10 14:56:18 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:18 volumio volumio[1139]: verbose: ControllerMpd::clearAddPlayTracks NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac Jan 10 14:56:18 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 14:56:18 volumio volumio[1139]: info: sendMpdCommand stop took 21 milliseconds Jan 10 14:56:18 volumio volumio[1139]: info: sendMpdCommand stop took 3 milliseconds Jan 10 14:56:18 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 14:56:18 volumio volumio[1139]: info: Jan 10 14:56:18 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jan 10 14:56:18 volumio volumio[1139]: info: Ignoring MPD Status Update Jan 10 14:56:18 volumio volumio[1139]: info: sendMpdCommand clear took 4 milliseconds Jan 10 14:56:18 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand add "NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac" Jan 10 14:56:18 volumio volumio[1139]: error: updateQueue error: null Jan 10 14:56:18 volumio volumio[1139]: info: Jan 10 14:56:18 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jan 10 14:56:18 volumio volumio[1139]: info: Ignoring MPD Status Update Jan 10 14:56:18 volumio volumio[1139]: info: ------------------------------ 2ms Jan 10 14:56:18 volumio volumio[1139]: info: sendMpdCommand add "NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac" took 1 milliseconds Jan 10 14:56:18 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand play Jan 10 14:56:18 volumio volumio[1139]: info: ------------------------------ 1ms Jan 10 14:56:18 volumio volumio[1139]: info: sendMpdCommand play took 1 milliseconds Jan 10 14:56:20 volumio volumio[1139]: info: Jan 10 14:56:20 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:56:20 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:56:20 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:56:20 volumio volumio[1139]: info: Jan 10 14:56:20 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:56:20 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:56:20 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:56:20 volumio volumio[1139]: info: sendMpdCommand status took 8 milliseconds Jan 10 14:56:20 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:56:20 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:56:20 volumio volumio[1139]: info: sendMpdCommand status took 3 milliseconds Jan 10 14:56:20 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 14:56:20 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:56:20 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:56:20 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:56:20 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:56:20 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:56:20 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:20 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2235,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1359 Kbps","isStreaming":false,"title":"Capuchin","artist":"Martin Gore","album":"The Third Chimpanzee (EP)","uri":"NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac","trackType":"flac"} Jan 10 14:56:20 volumio volumio[1139]: verbose: CURRENT POSITION 2 Jan 10 14:56:20 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jan 10 14:56:20 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus stop Jan 10 14:56:20 volumio volumio[1139]: info: ------------------------------ 15ms Jan 10 14:56:20 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 14:56:20 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:56:20 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:56:20 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:56:20 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:20 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2235,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1276 Kbps","isStreaming":false,"title":"Capuchin","artist":"Martin Gore","album":"The Third Chimpanzee (EP)","uri":"NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac","trackType":"flac"} Jan 10 14:56:20 volumio volumio[1139]: verbose: CURRENT POSITION 2 Jan 10 14:56:20 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jan 10 14:56:20 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jan 10 14:56:20 volumio volumio[1139]: info: Received an update from plugin. extracting info from payload Jan 10 14:56:20 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:56:20 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:20 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:56:20 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:56:20 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:56:20 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:20 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:56:20 volumio volumio[1139]: info: ------------------------------ 28ms Jan 10 14:56:23 volumio volumio[1139]: info: CoreCommandRouter::volumioPause Jan 10 14:56:23 volumio volumio[1139]: info: CoreStateMachine::pause Jan 10 14:56:23 volumio volumio[1139]: info: CoreStateMachine::stPlaybackTimer Jan 10 14:56:23 volumio volumio[1139]: info: CoreStateMachine::servicePause Jan 10 14:56:23 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:23 volumio volumio[1139]: info: CoreCommandRouter::servicePause Jan 10 14:56:23 volumio volumio[1139]: info: ControllerMpd::pause Jan 10 14:56:23 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand pause Jan 10 14:56:23 volumio volumio[1139]: info: Jan 10 14:56:23 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jan 10 14:56:23 volumio volumio[1139]: info: sendMpdCommand pause took 8 milliseconds Jan 10 14:56:23 volumio volumio[1139]: info: ControllerMpd::getState Jan 10 14:56:23 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jan 10 14:56:23 volumio volumio[1139]: info: sendMpdCommand status took 0 milliseconds Jan 10 14:56:23 volumio volumio[1139]: verbose: ControllerMpd::parseState Jan 10 14:56:23 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 14:56:23 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 10 14:56:23 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jan 10 14:56:23 volumio volumio[1139]: info: ControllerMpd::pushState Jan 10 14:56:23 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 10 14:56:23 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:23 volumio volumio[1139]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5486,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1484 Kbps","isStreaming":false,"title":"Capuchin","artist":"Martin Gore","album":"The Third Chimpanzee (EP)","uri":"NAS/Xenon_Audio/MQA/Martin Gore - The Third Chimpanzee (EP) [MQA 24-44,1] 2021/03. Capuchin.flac","trackType":"flac"} Jan 10 14:56:23 volumio volumio[1139]: verbose: CURRENT POSITION 2 Jan 10 14:56:23 volumio volumio[1139]: info: CoreStateMachine::syncState stateService pause Jan 10 14:56:23 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus pause Jan 10 14:56:23 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:56:23 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:23 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:56:23 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:56:23 volumio volumio[1139]: info: CoreStateMachine::stPlaybackTimer Jan 10 14:56:23 volumio volumio[1139]: info: ------------------------------ 32ms Jan 10 14:56:26 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:56:26 volumio volumio[1139]: info: CURURI: music-library Jan 10 14:56:26 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:27 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:56:27 volumio volumio[1139]: info: CURURI: music-library/NAS Jan 10 14:56:27 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:28 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:56:28 volumio volumio[1139]: info: CURURI: music-library/NAS/Xenon_Audio Jan 10 14:56:28 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:28 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/apple.mp3 Jan 10 14:56:28 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/apple.mp3 in service mpd Jan 10 14:56:28 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Mactracker/Startup%20Chimes/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2Fapple.mp3&metadata=false Jan 10 14:56:28 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/apple.mp3 Jan 10 14:56:30 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:56:30 volumio volumio[1139]: info: CURURI: music-library/NAS/Xenon_Audio/DSD Jan 10 14:56:30 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:30 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/DSD/32 - Cinema Paradiso.dsf Jan 10 14:56:30 volumio volumio[1139]: info: Preloading song: music-library/NAS/Xenon_Audio/DSD/33 - Love Theme (from Cinema Paradiso).dsf Jan 10 14:56:31 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/DSD/32 - Cinema Paradiso.dsf in service mpd Jan 10 14:56:31 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2F32%20-%20Cinema%20Paradiso.dsf&metadata=false Jan 10 14:56:31 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/32 - Cinema Paradiso.dsf Jan 10 14:56:31 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/DSD/33 - Love Theme (from Cinema Paradiso).dsf in service mpd Jan 10 14:56:31 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2F33%20-%20Love%20Theme%20(from%20Cinema%20Paradiso).dsf&metadata=false Jan 10 14:56:31 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/33 - Love Theme (from Cinema Paradiso).dsf Jan 10 14:56:33 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9. Jan 10 14:56:33 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:56:33 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:56:33 volumio upmpdcli[2305]: Could not open config: /tmp/upmpdcli.conf Jan 10 14:56:33 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 14:56:33 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 14:56:44 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 14:56:44 volumio volumio[1139]: info: CURURI: music-library/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO) Jan 10 14:56:44 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:48 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 10. Jan 10 14:56:48 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:56:48 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 14:56:48 volumio upmpdcli[2339]: Could not open config: /tmp/upmpdcli.conf Jan 10 14:56:48 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 14:56:48 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 14:56:53 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:53 volumio volumio[1139]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 14:56:53 volumio volumio[1139]: info: CoreStateMachine::ClearQueue Jan 10 14:56:53 volumio volumio[1139]: info: CoreStateMachine::stop Jan 10 14:56:53 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 14:56:53 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jan 10 14:56:53 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jan 10 14:56:53 volumio volumio[1139]: info: CoreStateMachine::stPlaybackTimer Jan 10 14:56:53 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 10 14:56:53 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:53 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 14:56:53 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 10 14:56:53 volumio volumio[1139]: info: CoreStateMachine::serviceStop Jan 10 14:56:53 volumio volumio[1139]: info: CorePlayQueue::getTrack 2 Jan 10 14:56:53 volumio volumio[1139]: info: CoreCommandRouter::serviceStop Jan 10 14:56:53 volumio volumio[1139]: info: ControllerMpd::stop Jan 10 14:56:53 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 14:56:53 volumio volumio[1139]: info: CorePlayQueue::clearPlayQueue Jan 10 14:56:53 volumio volumio[1139]: info: CorePlayQueue::saveQueue Jan 10 14:56:53 volumio volumio[1139]: info: CoreCommandRouter::volumioPushQueue Jan 10 14:56:53 volumio volumio[1139]: info: CoreStateMachine::addQueueItems Jan 10 14:56:53 volumio volumio[1139]: info: CorePlayQueue::addQueueItems Jan 10 14:56:53 volumio volumio[1139]: info: Preload queue cleared Jan 10 14:56:53 volumio volumio[1139]: info: Adding Item to queue: music-library/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO) Jan 10 14:56:53 volumio volumio[1139]: info: Exploding uri music-library/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO) in service mpd Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F01.%20Willkommen%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/01. Willkommen - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F02.%20Sommerregen%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/02. Sommerregen - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F03.%20I%C2%B4ve%20Seen%20It%20All%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/03. I´ve Seen It All - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F04.%20Zukunft%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/04. Zukunft - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F05.%20Liebe%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/05. Liebe - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F06.%20Einklang%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/06. Einklang - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F07.%20I%20Miss%20You%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/07. I Miss You - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F08.%20Leben%20-%20I%20Feel%20You%20-%20Fernsehfassung%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/08. Leben - I Feel You - Fernsehfassung SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F09.%20Mittelerde%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/09. Mittelerde - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F10.%20The%20Smile%20-%20Fernsehfassung%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/10. The Smile - Fernsehfassung SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F11.%20Dreiklang%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/11. Dreiklang - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F12.%20Babel%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/12. Babel - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F13.%20Desire%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/13. Desire - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F14.%20Stille%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/14. Stille - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F15.%20Vergangenheit%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/15. Vergangenheit - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F16.%20Delicately%20Yours%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/16. Delicately Yours - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2F17.%20Ausklang%20-%20SACD%20Stereo.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/17. Ausklang - SACD Stereo.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_01.%20Willkommen%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_01. Willkommen - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_02.%20Sommerregen%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_02. Sommerregen - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_03.%20I%C2%B4ve%20Seen%20It%20All%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_03. I´ve Seen It All - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_04.%20Zukunft%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_04. Zukunft - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_05.%20Liebe%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_05. Liebe - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_06.%20Einklang%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_06. Einklang - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_07.%20I%20Miss%20You%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_07. I Miss You - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_08.%20Leben%20-%20I%20Feel%20You%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_08. Leben - I Feel You - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_09.%20Mittelerde%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_09. Mittelerde - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_10.%20The%20Smile%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_10. The Smile - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_11.%20Dreiklang%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_11. Dreiklang - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_12.%20Babel%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_12. Babel - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_13.%20Desire%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_13. Desire - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_14.%20Stille%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_14. Stille - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_15.%20Vergangenheit%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_15. Vergangenheit - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_16.%20Delicately%20Yours%20-%20SACD%20Multichannel.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_16. Delicately Yours - SACD Multichannel.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=//extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FMULCHTOC_17.%20Ausklang%20-%20SACD%20Multichannel%205.1.dff&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/MULCHTOC_17. Ausklang - SACD Multichannel 5.1.dff Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Schiller/Leben/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FSchiller%20-%20Leben..cue&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/Schiller - Leben..cue Jan 10 14:56:53 volumio volumio[1139]: info: ALBUMART /albumart?cacheid=954&web=Schiller/Leben/extralarge&path=%2Fmnt%2FNAS%2FXenon_Audio%2FDSD%2FSchiller%20-%20Leben%20(2004)%20%5BSACD%5D%20(ISO)%2FSchiller%20-%20Leben..cue&metadata=false Jan 10 14:56:53 volumio volumio[1139]: info: URI /mnt/NAS/Xenon_Audio/DSD/Schiller - Leben (2004) [SACD] (ISO)/Schiller - Leben..cue Jan 10 14:56:53 volumio volumio[1139]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 14:56:53 volumio volumio[1139]: Error: Unable to resolve or reject the same promise twice Jan 10 14:56:53 volumio volumio[1139]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 10 14:56:53 volumio volumio[1139]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Jan 10 14:56:53 volumio volumio[1139]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 10 14:56:53 volumio volumio[1139]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 10 14:56:53 volumio volumio[1139]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 10 14:56:53 volumio volumio[1139]: at Socket.emit (node:events:514:28) Jan 10 14:56:53 volumio volumio[1139]: at addChunk (node:internal/streams/readable:343:12) Jan 10 14:56:53 volumio volumio[1139]: at readableAddChunk (node:internal/streams/readable:312:11) Jan 10 14:56:53 volumio volumio[1139]: at Readable.push (node:internal/streams/readable:253:10) Jan 10 14:56:53 volumio volumio[1139]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jan 10 14:56:53 volumio volumio[1139]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 14:56:53 volumio sudo[2372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-10 14:55' Jan 10 14:56:53 volumio sudo[2372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"