-- Logs begin at Sat 2025-11-15 07:41:13 UTC, end at Thu 2025-12-18 11:55:04 UTC. -- Dec 18 11:54:05 localhost systemd[1]: Starting Internet Detect for iFi Streamer... Dec 18 11:54:05 localhost systemd[1]: ifi-system-internet.service: Succeeded. Dec 18 11:54:05 localhost systemd[1]: Started Internet Detect for iFi Streamer. Dec 18 11:54:24 localhost volumio[1437]: info: ++++--++++ playItemsList: {"item":{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},"list":[{"service":"mpd","type":"song","title":"(352kHz)Divertimento 352.8-24","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac","albumart":"/albumart?cacheid=515_1766058773647&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"(48kHz)Divertimento 48-24.flac","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 96kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Hopak [TCHAIKOVSKY: Mazeppa] 176kHz","artist":"Minnesota Orchestra, Eiji Oue","album":"Exotic Dances From The Opera","uri":"music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Carmen 44kHz","artist":"Carmen","album":"RMAF 2010","uri":"music-library/USB/Samsung_USB/歌曲/Carmen 44.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"}],"index":6} Dec 18 11:54:24 localhost systemd[1]: Starting Internet Detect for iFi Streamer... Dec 18 11:54:24 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::ClearQueue Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::serviceStop Dec 18 11:54:24 localhost volumio[1437]: info: Received STOP, but no service to execute it Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::clearPlayQueue Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::addQueueItems Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::addQueueItems Dec 18 11:54:24 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 18 11:54:24 localhost volumio[1437]: info: ConvSwitch: Sent Play Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioPlay Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::play index 6 Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::addQueueItems Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::addQueueItems Dec 18 11:54:24 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 18 11:54:24 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 18 11:54:24 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::play index undefined Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::startPlaybackTimer Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 11:54:24 localhost volumio[1437]: info: ConvSwitch: Get message: success Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand stop took 8 milliseconds Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 11:54:24 localhost volumio[1437]: info: Dec 18 11:54:24 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:24 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand clear took 5 milliseconds Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac" Dec 18 11:54:24 localhost volumio[1437]: info: Dec 18 11:54:24 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:24 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:24 localhost volumio[1437]: error: updateQueue error: null Dec 18 11:54:24 localhost volumio[1437]: info: Dec 18 11:54:24 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:24 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:24 localhost volumio[1437]: info: ------------------------------ 16ms Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand add "USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac" took 13 milliseconds Dec 18 11:54:24 localhost volumio[1437]: info: ------------------------------ 12ms Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand play Dec 18 11:54:24 localhost volumio[1437]: info: Dec 18 11:54:24 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:24 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:24 localhost volumio[1437]: info: Dec 18 11:54:24 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:24 localhost volumio[1437]: info: ------------------------------ 22ms Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand play took 14 milliseconds Dec 18 11:54:24 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:24 localhost volumio[1437]: info: ------------------------------ 14ms Dec 18 11:54:24 localhost kernel: wm8804 0-003b: wm8804_hw_params rate: 0x2ee00 format: 0x6 Dec 18 11:54:24 localhost volumio[1437]: info: Dec 18 11:54:24 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:24 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand status took 12 milliseconds Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:24 localhost volumio[1437]: info: Dec 18 11:54:24 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:24 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand status took 19 milliseconds Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:24 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:24 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:54:24 localhost volumio[1437]: info: ------------------------------ 52ms Dec 18 11:54:24 localhost volumio[1437]: info: Dec 18 11:54:24 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:24 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand status took 31 milliseconds Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 18 11:54:24 localhost volumio[1437]: info: sendMpdCommand status took 4 milliseconds Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:24 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:24 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 18 11:54:24 localhost volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 18 11:54:24 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:24 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:24 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:25 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:25 localhost volumio[1437]: info: ------------------------------ 101ms Dec 18 11:54:25 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 18 11:54:25 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 41 milliseconds Dec 18 11:54:25 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:25 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:25 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:25 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:25 localhost volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:25 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 18 11:54:25 localhost volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:25 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:25 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:25 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:25 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:25 localhost volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":593,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3549 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:25 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 18 11:54:25 localhost volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:25 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:25 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:25 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:25 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:25 localhost volumio[1437]: info: ------------------------------ 150ms Dec 18 11:54:25 localhost volumio[1437]: info: ------------------------------ 124ms Dec 18 11:54:25 localhost kernel: dwmmc_rockchip ff520000.dwmmc: Unexpected interrupt latency Dec 18 11:54:25 localhost systemd[1]: ifi-system-internet.service: Succeeded. Dec 18 11:54:25 localhost systemd[1]: Started Internet Detect for iFi Streamer. Dec 18 11:54:25 localhost volumio[1437]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/TrondheimSolistene/DIVERTIMENTI/74d1c081-ce0f-4ca7-b4af-72c3bbe2a5ed.jpg' Dec 18 11:54:26 localhost volumio[1437]: info: MetaClient: Sent Meta Dec 18 11:54:26 localhost volumio[1437]: info: MetaClient: Get message: success Dec 18 11:54:28 localhost volumio[1437]: info: ++++--++++ getMenuItems Dec 18 11:54:29 localhost volumio[1437]: info: ++++--++++ getMenuItems Dec 18 11:54:29 localhost volumio[1437]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 18 11:54:29 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 11:54:31 localhost volumio[1437]: info: ++++--++++ callMethod: {"type":"controller","endpoint":"audio_interface/alsa_controller","method":"saveAlsaOptions","data":{"output_device":{"value":"5","label":"ZEN DAC 3 (UCA1)"}}} Dec 18 11:54:31 localhost volumio[1437]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 18 11:54:31 localhost volumio[1437]: info: Preparing to save Alsa Options, stopping services first Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::volumioPause Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::pause Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::servicePause Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::servicePause Dec 18 11:54:31 localhost volumio[1437]: info: ControllerMpd::pause Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand pause Dec 18 11:54:31 localhost volumio[1437]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"ZEN DAC 3 (UCA1)"}} Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , forceDoP Dec 18 11:54:31 localhost volumio[1437]: info: ZEN DAC 3 (UCA1): Does not support DSD Native. Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:31 localhost volumio[1437]: info: Ignoring Mixers Options Dec 18 11:54:31 localhost volumio[1437]: info: Device 5 does not have any Mixer Control Available Dec 18 11:54:31 localhost volumio[1437]: info: Restoring Previous Volume level: 100 false false Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 18 11:54:31 localhost volumio[1437]: info: Updating Volume Controller Parameters: Device: 5 Name: ZEN DAC 3 (UCA1) Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:31 localhost volumio[1437]: info: Preparing to generate the ALSA configuration file Dec 18 11:54:31 localhost volumio[1437]: info: Dec 18 11:54:31 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:31 localhost volumio[1437]: info: sendMpdCommand pause took 205 milliseconds Dec 18 11:54:31 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:31 localhost volumio[1437]: info: Dec 18 11:54:31 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:31 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:31 localhost volumio[1437]: info: sendMpdCommand status took 9 milliseconds Dec 18 11:54:31 localhost volumio[1437]: info: sendMpdCommand status took 6 milliseconds Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:31 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 18 11:54:31 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:31 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:31 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6909,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5650 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:31 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::syncState stateService pause Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus pause Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 18 11:54:31 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6909,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5650 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:31 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::syncState stateService pause Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus pause Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:31 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:31 localhost volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 18 11:54:31 localhost volumio[1437]: info: ------------------------------ 65ms Dec 18 11:54:31 localhost volumio[1437]: info: ------------------------------ 62ms Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: wizard , refreshWizarOutputDevices Dec 18 11:54:31 localhost volumio[1437]: info: Asound.conf file written Dec 18 11:54:31 localhost sudo[4336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 18 11:54:31 localhost sudo[4336]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:31 localhost sudo[4336]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:31 localhost volumio[1437]: No state is present for card rkifispdif Dec 18 11:54:31 localhost volumio[1437]: Found hardware: "rk-ifi-spdif" "" "" "" "" Dec 18 11:54:31 localhost volumio[1437]: Hardware is initialized using a generic method Dec 18 11:54:31 localhost volumio[1437]: No state is present for card rkifispdif Dec 18 11:54:31 localhost volumio[1437]: No state is present for card Z3UCA1 Dec 18 11:54:31 localhost volumio[1437]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:0010" "" "" Dec 18 11:54:31 localhost volumio[1437]: Hardware is initialized using a generic method Dec 18 11:54:31 localhost volumio[1437]: No state is present for card Z3UCA1 Dec 18 11:54:31 localhost volumio[1437]: info: Tidal connect: Output device has changed Dec 18 11:54:31 localhost volumio[1437]: info: Output device has changed, restarting MPD Dec 18 11:54:31 localhost volumio[1437]: info: Qobuz connect: Output device has changed Dec 18 11:54:31 localhost volumio[1437]: info: Roon Ready: Output device has changed Dec 18 11:54:31 localhost volumio[1437]: info: Spotify: Output device has changed Dec 18 11:54:31 localhost volumio[1437]: info: Tidal connect: Output device has changed Dec 18 11:54:31 localhost volumio[1437]: info: Output device has changed, restarting MPD Dec 18 11:54:31 localhost sudo[4341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 11:54:31 localhost sudo[4341]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:31 localhost sudo[4341]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:31 localhost volumio[1437]: info: Qobuz connect: Output device has changed Dec 18 11:54:31 localhost volumio[1437]: info: Roon Ready: Output device has changed Dec 18 11:54:31 localhost volumio[1437]: info: Spotify: Output device has changed Dec 18 11:54:31 localhost volumio[1437]: info: MPD Permissions set Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost sudo[4345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost sudo[4345]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost sudo[4345]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 11:54:31 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:31 localhost volumio[1437]: info: MPD Permissions set Dec 18 11:54:32 localhost volumio[1437]: info: Starting RoonReady Sync Dec 18 11:54:32 localhost volumio[1437]: info: Starting RoonReady Sync Dec 18 11:54:32 localhost sudo[4368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 18 11:54:32 localhost sudo[4368]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:32 localhost sudo[4370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 18 11:54:32 localhost sudo[4370]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:32 localhost sudo[4371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 18 11:54:32 localhost sudo[4371]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:32 localhost sudo[4376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 18 11:54:32 localhost sudo[4376]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:32 localhost node[542]: Closing connection with the client roon-ready Dec 18 11:54:32 localhost systemd[1]: Stopping iFi Streamer Roon Ready Service... Dec 18 11:54:32 localhost node[517]: ConvSwitch: Drop client roon-ready Dec 18 11:54:32 localhost systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Dec 18 11:54:32 localhost node[542]: Closing connection with the client qobuz-connect Dec 18 11:54:32 localhost node[517]: ConvSwitch: Drop client qobuz-connect Dec 18 11:54:32 localhost systemd[1]: Starting Internet Detect for iFi Streamer... Dec 18 11:54:32 localhost systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:32 localhost systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Dec 18 11:54:32 localhost systemd[1]: Stopped iFi Streamer Roon Ready Service. Dec 18 11:54:32 localhost systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:32 localhost systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Dec 18 11:54:32 localhost systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Dec 18 11:54:32 localhost sudo[4371]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:32 localhost sudo[4368]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:32 localhost systemd[1]: Started iFi Streamer Roon Ready Service. Dec 18 11:54:32 localhost sudo[4376]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:32 localhost sudo[4370]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:32 localhost volumio[1437]: info: RoonReady Started Dec 18 11:54:32 localhost volumio[1437]: info: RoonReady Started Dec 18 11:54:32 localhost play[4391]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Dec 18 11:54:32 localhost systemd[1]: ifi-system-internet.service: Succeeded. Dec 18 11:54:32 localhost systemd[1]: Started Internet Detect for iFi Streamer. Dec 18 11:54:32 localhost node[517]: ConvSwitch: Add client roon-ready Dec 18 11:54:32 localhost volumio[1437]: info: ConvSwitch: Get message: request:pause Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 18 11:54:32 localhost volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:32 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::serviceStop Dec 18 11:54:32 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::serviceStop Dec 18 11:54:32 localhost volumio[1437]: info: ControllerMpd::stop Dec 18 11:54:32 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:32 localhost volumio[1437]: info: Dec 18 11:54:32 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:32 localhost volumio[1437]: info: sendMpdCommand stop took 14 milliseconds Dec 18 11:54:32 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:32 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:32 localhost volumio[1437]: info: sendMpdCommand status took 2 milliseconds Dec 18 11:54:32 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:32 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:32 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:32 localhost volumio[1437]: info: Dec 18 11:54:32 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:32 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:32 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:32 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 18 11:54:32 localhost volumio[1437]: info: sendMpdCommand status took 6 milliseconds Dec 18 11:54:32 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:32 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:32 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:32 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:32 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:32 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:32 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:32 localhost volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:32 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:32 localhost volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 18 11:54:32 localhost volumio[1437]: info: ------------------------------ 51ms Dec 18 11:54:32 localhost volumio[1437]: info: Dec 18 11:54:32 localhost volumio[1437]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Dec 18 11:54:32 localhost volumio[1437]: info: Roon Ready playback start Dec 18 11:54:32 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:32 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:32 localhost volumio[1437]: info: Roon Ready metadata receiver is on Dec 18 11:54:33 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 43 milliseconds Dec 18 11:54:33 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:33 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:33 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:33 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:33 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:33 localhost volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:33 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:33 localhost volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 18 11:54:33 localhost volumio[1437]: info: ------------------------------ 81ms Dec 18 11:54:33 localhost sudo[4453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 18 11:54:33 localhost sudo[4453]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:33 localhost systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 18 11:54:33 localhost node[542]: Closing connection with the client tidal-connect Dec 18 11:54:33 localhost systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:33 localhost systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 18 11:54:33 localhost systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 18 11:54:33 localhost systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 18 11:54:33 localhost node[517]: ConvSwitch: Drop client tidal Dec 18 11:54:33 localhost systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:33 localhost systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 18 11:54:33 localhost systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 18 11:54:33 localhost systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 18 11:54:33 localhost sudo[4453]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:33 localhost sudo[4456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 18 11:54:33 localhost volumio[1437]: info: Tidal Connect Service Started Dec 18 11:54:33 localhost systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 18 11:54:33 localhost sudo[4456]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:33 localhost node[542]: A new connection has been established. Dec 18 11:54:33 localhost node[542]: Closing connection with the client tidal-connect Dec 18 11:54:33 localhost systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 18 11:54:33 localhost systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:33 localhost systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 18 11:54:33 localhost systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 18 11:54:33 localhost systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 18 11:54:33 localhost systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:33 localhost systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 18 11:54:33 localhost systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 18 11:54:33 localhost systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 18 11:54:33 localhost sudo[4456]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:33 localhost systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 18 11:54:33 localhost volumio[1437]: info: Tidal Connect Service Started Dec 18 11:54:33 localhost node[542]: A new connection has been established. Dec 18 11:54:33 localhost sudo[4476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 18 11:54:33 localhost sudo[4476]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:33 localhost sudo[4477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 18 11:54:33 localhost sudo[4477]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:33 localhost systemd[1]: Started iFi Streamer Qobuz Connect Service. Dec 18 11:54:33 localhost sudo[4476]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:33 localhost volumio[1437]: info: Qobuz Connect Service Started Dec 18 11:54:33 localhost sudo[4477]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:33 localhost volumio[1437]: info: Qobuz Connect Service Started Dec 18 11:54:33 localhost volumio[1437]: info: ++++--++++ playItemsList: {"item":{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},"list":[{"service":"mpd","type":"song","title":"(352kHz)Divertimento 352.8-24","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac","albumart":"/albumart?cacheid=515_1766058773647&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"(48kHz)Divertimento 48-24.flac","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 96kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Hopak [TCHAIKOVSKY: Mazeppa] 176kHz","artist":"Minnesota Orchestra, Eiji Oue","album":"Exotic Dances From The Opera","uri":"music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Carmen 44kHz","artist":"Carmen","album":"RMAF 2010","uri":"music-library/USB/Samsung_USB/歌曲/Carmen 44.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"}],"index":6} Dec 18 11:54:33 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:33 localhost volumio[1437]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::ClearQueue Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::clearPlayQueue Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:33 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::addQueueItems Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::addQueueItems Dec 18 11:54:33 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 18 11:54:33 localhost volumio[1437]: info: ConvSwitch: Sent Play Dec 18 11:54:33 localhost volumio[1437]: info: CoreCommandRouter::volumioPlay Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::play index 6 Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::addQueueItems Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::addQueueItems Dec 18 11:54:33 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 18 11:54:33 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 18 11:54:33 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:33 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::play index undefined Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:33 localhost volumio[1437]: info: CoreStateMachine::startPlaybackTimer Dec 18 11:54:33 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:33 localhost volumio[1437]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:33 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.825 [4498.4498] DEBUG SampleApp: Starting sample application Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.826 [4498.4498] DEBUG SampleApp: Device Name: ZEN Stream3 Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.826 [4498.4498] DEBUG SampleApp: Manufacturer: Sample manufacturer Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.826 [4498.4498] DEBUG SampleApp: Model: iFi Streamer Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.826 [4498.4498] DEBUG SampleApp: Serial Number: ifi:da:8f:57:2d:4e:73 Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.826 [4498.4498] DEBUG SampleApp: Audio output device: volumio Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.826 [4498.4498] DEBUG SampleApp: Volume control device: default Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.826 [4498.4498] DEBUG SampleApp: Volume control element: Master Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.826 [4498.4498] DEBUG SampleApp: Local config server port: 8000 Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.832 [4498.4498] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.832 [4498.4498] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Dec 18 11:54:33 localhost play[4480]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 18 11:54:33 localhost node[542]: A new connection has been established. Dec 18 11:54:33 localhost volumio[1437]: info: ConvSwitch: Get message: success Dec 18 11:54:33 localhost volumio[1437]: info: sendMpdCommand stop took 36 milliseconds Dec 18 11:54:33 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 11:54:33 localhost sudo[4502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 18 11:54:33 localhost sudo[4502]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:33 localhost volumio[1437]: info: Dec 18 11:54:33 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:33 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:33 localhost play[4480]: [Info] MetaClient: MetaClientInit Dec 18 11:54:33 localhost play[4480]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 18 11:54:33 localhost volumio[1437]: info: sendMpdCommand clear took 27 milliseconds Dec 18 11:54:33 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac" Dec 18 11:54:33 localhost volumio[1437]: info: Dec 18 11:54:33 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:33 localhost node[517]: ConvSwitch: Add client qobuz-connect Dec 18 11:54:33 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:33 localhost systemd[1]: mpd.socket: Succeeded. Dec 18 11:54:33 localhost systemd[1]: Closed mpd.socket. Dec 18 11:54:33 localhost volumio[1437]: error: updateQueue error: null Dec 18 11:54:33 localhost volumio[1437]: info: Dec 18 11:54:33 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:33 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:33 localhost volumio[1437]: info: ------------------------------ 22ms Dec 18 11:54:33 localhost volumio[1437]: info: sendMpdCommand add "USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac" took 18 milliseconds Dec 18 11:54:33 localhost volumio[1437]: info: ------------------------------ 18ms Dec 18 11:54:33 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand play Dec 18 11:54:33 localhost systemd[1]: Stopping Music Player Daemon... Dec 18 11:54:33 localhost sudo[4505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 18 11:54:33 localhost sudo[4505]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:33 localhost volumio[1437]: error: MPD error: Error: write EPIPE Dec 18 11:54:33 localhost volumio[1437]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Dec 18 11:54:33 localhost volumio[1437]: info: Dec 18 11:54:33 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:33 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:33 localhost play[4480]: [Info] ConvSwitch: ConvSwitchInit Dec 18 11:54:33 localhost volumio[1437]: error: MPD error: Error: write EPIPE Dec 18 11:54:33 localhost volumio[1437]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.940 [4498.4498] DEBUG ActiveStateManager: [0xab16c030]: Creating new Active State Manager Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.940 [4498.4498] DEBUG NetworkActivityTracker: [0xab16c0a0]: Creating new Network Activity Tracker Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.940 [4498.4498] DEBUG EndpointManager: [0xab16d018]: Creating Endpoint Manager Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.940 [4498.4498] DEBUG PlaybackSessionManager: [0xab16d408]: Creating Playback Session Manager Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG BackoffTimer: [0xab16d580]: Creating Backoff Timer Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG MediaEngine: [0xab16d6a0]: Creating new Media Engine Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG AudioRenderer: [0xab16d8c0]: Creating Audio Renderer Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] TRACE MediaEngineCommandSequencer: [0xab16da18]: Creating Media Engine Command Sequencer Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG PlaybackControlsManager: [0xab16da78]: Creating new Playback Controls Manager Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG VolumeManager: [0xab16db08]: Creating new Volume Manager Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG ProtocolHandler: [0xab16dde0]: Creating Protocol Handler Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG MessageReceiver: [0xab16de80]: Creating Message Receiver Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG RendererActivationController: [0xab16dff8]: Creating new Renderer Activation Controller Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] TRACE RendererActivationCommandSequencer: [0xab16e0a0]: Creating Renderer Activation Command Sequencer Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG ReportingManager: [0xab16e1b0]: Creating new Reporting Manager Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG StreamingEventReporter: [0xab16e1f8]: Creating Streaming Event Reporter Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG BackoffTimer: [0xab16e2d8]: Creating Backoff Timer Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG TokenExpiryController: [0xab16e3e8]: Creating Token Expiry Controller Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.941 [4498.4498] DEBUG UserInactivityManager: [0xab16e438]: Creating new User Inactivity Manager Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.942 [4498.4498] INFO VolumeManager: [0xab16db08]: Setting new playback volume: 100 Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.942 [4498.4498] INFO VolumeManager: [0xab16db08]: Setting new mute state: 1 Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.942 [4498.4498] DEBUG SampleApp: Qobuz Connect has been initialized Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.942 [4498.4498] TRACE SampleApp: Running event loop Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.942 [4498.4498] INFO QobuzConnect: [0xab16e4d8]: Client initialized! Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.942 [4498.4498] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream3, service name: _qobuz-connect._tcp Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.954 [4498.4498] DEBUG SampleApp: Avahi client state changed: RUNNING Dec 18 11:54:33 localhost systemd[1]: mpd.service: Succeeded. Dec 18 11:54:33 localhost systemd[1]: Stopped Music Player Daemon. Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.958 [4498.4498] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.964 [4498.4498] INFO LocalConfigManager: [0xab16d3e8]: Starting Local Configuration server Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.965 [4498.4498] INFO SampleApp: Starting Local configuration server Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.966 [4498.4498] INFO SampleApp: Playback mute state changed: 1 Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.966 [4498.4498] ERROR SampleApp: Unable to set mute state, no ALSA volume element Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.966 [4498.4498] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Dec 18 11:54:33 localhost sudo[4502]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.977 [4498.4498] INFO SampleApp: Playback volume changed: 100 Dec 18 11:54:33 localhost play[4480]: 20251218 11:54:33.977 [4498.4498] ERROR SampleApp: Unable to set volume, no ALSA volume element Dec 18 11:54:34 localhost sudo[4505]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:34 localhost sudo[4511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 18 11:54:34 localhost sudo[4511]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:34 localhost systemd[1]: Listening on mpd.socket. Dec 18 11:54:34 localhost sudo[4518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 18 11:54:34 localhost sudo[4518]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:34 localhost systemd[1]: Starting Music Player Daemon... Dec 18 11:54:34 localhost systemd[1]: mpd.socket: Succeeded. Dec 18 11:54:34 localhost systemd[1]: Closed mpd.socket. Dec 18 11:54:34 localhost systemd[1]: Stopping mpd.socket. Dec 18 11:54:34 localhost systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Dec 18 11:54:34 localhost systemd[1]: Failed to listen on mpd.socket. Dec 18 11:54:34 localhost systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 18 11:54:34 localhost systemd[1]: mpd.service: Succeeded. Dec 18 11:54:34 localhost systemd[1]: Stopped Music Player Daemon. Dec 18 11:54:34 localhost systemd[1]: Listening on mpd.socket. Dec 18 11:54:34 localhost systemd[1]: Starting Music Player Daemon... Dec 18 11:54:34 localhost sudo[4521]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 11:54:34 localhost sudo[4521]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:34 localhost sudo[4521]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:34 localhost node[542]: A new connection has been established. Dec 18 11:54:34 localhost play[4480]: 20251218 11:54:34.770 [4498.4498] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 18 11:54:35 localhost sudo[4530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 18 11:54:35 localhost sudo[4530]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 18 11:54:35 localhost volumio[1437]: info: CoreStateMachine::getcurrentVolume Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 18 11:54:35 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:35 localhost play[3557]: 2025-12-18 11:54:35.403 E esdk select error: 4 -> Interrupted system call Dec 18 11:54:35 localhost play[3557]: 2025-12-18 11:54:35.404 I app Exiting with error = 0 Dec 18 11:54:35 localhost play[3557]: 2025-12-18 11:54:35.404 I api SpFree() Dec 18 11:54:35 localhost play[3557]: 2025-12-18 11:54:35.405 I api SpFree [returned value: 0] Dec 18 11:54:35 localhost play[3557]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 18 11:54:35 localhost play[3557]: [Info] ConvSwitch: ConvSwitchInit Dec 18 11:54:35 localhost play[3557]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 18 11:54:35 localhost play[3557]: [Info] MetaClient: MetaClientInit Dec 18 11:54:35 localhost play[3557]: [Info] ConvSwitch: ConvSwitchExit Dec 18 11:54:35 localhost play[3557]: [Info] MetaClient: MetaClientExit Dec 18 11:54:35 localhost systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:35 localhost node[517]: ConvSwitch: Drop client spotify Dec 18 11:54:35 localhost node[542]: Closing connection with the client spotify Dec 18 11:54:35 localhost systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:35 localhost systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 18 11:54:35 localhost systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 18 11:54:35 localhost systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 18 11:54:35 localhost sudo[4530]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:35 localhost volumio[1437]: info: Spotify Connect Service Started Dec 18 11:54:35 localhost sudo[4534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 18 11:54:35 localhost sudo[4534]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:35 localhost systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 18 11:54:35 localhost systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:35 localhost systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 18 11:54:35 localhost systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 18 11:54:35 localhost systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 18 11:54:35 localhost sudo[4534]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:35 localhost volumio[1437]: info: Spotify Connect Service Started Dec 18 11:54:35 localhost play[4542]: output: volumio mixer: hw:5 Dec 18 11:54:35 localhost node[517]: ConvSwitch: Add client spotify Dec 18 11:54:35 localhost node[542]: A new connection has been established. Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.782 I api SpRegisterDebugCallbacks [returned value: 0] Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.783 I api SpInit(0xff7fbf10, API v66) Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.783 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.783 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.784 I esdk Successfully registered dns callback: dns_lookup_callback Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.784 I download Using 128 kB for cache buffer Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.784 I download Using 621 kB for compressed audio buffer Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.784 I esdk Successfully registered error callback Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.796 I esdk Creating IPv4 socket (domain 2) Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.796 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.796 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.796 I esdk Creating IPv4 socket (domain 2) Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.797 I api SpInit [returned value: 0] Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.797 I api SpRegisterConnectionCallbacks(0xff7fbef8, (nil)) Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.797 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.797 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.797 I api SpRegisterPlaybackCallbacks(0xff7fbee0, (nil)) Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.797 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.797 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.803 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 18 11:54:35 localhost play[4542]: 2025-12-18 11:54:35.803 I app Initialized! Dec 18 11:54:35 localhost volumio[1437]: info: VolumeController::SetAlsaVolume100 Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 18 11:54:35 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:35 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:35 localhost node[517]: ConvSwitch: Add client tidal Dec 18 11:54:35 localhost tidal_connect_application[4551]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 18 11:54:35 localhost tidal_connect_application[4551]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 11:54:35 localhost tidal_connect_application[4551]: *** WARNING *** For more information see Dec 18 11:54:36 localhost ifi_metadata[4465]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 18 11:54:36 localhost ifi_metadata[4465]: [Info] MetaClient: MetaClientInit Dec 18 11:54:36 localhost ifi_metadata[4465]: Connected Dec 18 11:54:36 localhost mpd[4525]: Dec 18 11:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 11:54:36 localhost systemd[1]: Started Music Player Daemon. Dec 18 11:54:36 localhost sudo[4518]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:36 localhost sudo[4511]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:36 localhost volumio[1437]: info: Restarted MPD. Dec 18 11:54:36 localhost volumio[1437]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Dec 18 11:54:36 localhost volumio[1437]: Job for mpd.socket failed. Dec 18 11:54:36 localhost volumio[1437]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Dec 18 11:54:36 localhost volumio[1437]: error: MPD error: The expression evaluated to a falsy value: Dec 18 11:54:36 localhost volumio[1437]: assert.ok(self.idling) Dec 18 11:54:36 localhost volumio[1437]: error: The expression evaluated to a falsy value: Dec 18 11:54:36 localhost volumio[1437]: assert.ok(self.idling) Dec 18 11:54:37 localhost volumio[1437]: error: updateQueue error: null Dec 18 11:54:37 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::serviceStop Dec 18 11:54:37 localhost volumio[1437]: info: CoreCommandRouter::serviceStop Dec 18 11:54:37 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:37 localhost volumio[1437]: info: Roon Ready Stop Dec 18 11:54:37 localhost volumio[1437]: info: ++++--++++ playItemsList: {"item":{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},"list":[{"service":"mpd","type":"song","title":"(352kHz)Divertimento 352.8-24","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac","albumart":"/albumart?cacheid=515_1766058773647&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"(48kHz)Divertimento 48-24.flac","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","albumart":"/albumart?cacheid=515_1766058773648&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 96kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Hopak [TCHAIKOVSKY: Mazeppa] 176kHz","artist":"Minnesota Orchestra, Eiji Oue","album":"Exotic Dances From The Opera","uri":"music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Carmen 44kHz","artist":"Carmen","album":"RMAF 2010","uri":"music-library/USB/Samsung_USB/歌曲/Carmen 44.flac","albumart":"/albumart?cacheid=515_1766058773649&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"}],"index":6} Dec 18 11:54:37 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:37 localhost volumio[1437]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::ClearQueue Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::clearPlayQueue Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:37 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::addQueueItems Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::addQueueItems Dec 18 11:54:37 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 18 11:54:37 localhost volumio[1437]: info: ConvSwitch: Sent Play Dec 18 11:54:37 localhost volumio[1437]: info: CoreCommandRouter::volumioPlay Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::play index 6 Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::addQueueItems Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::addQueueItems Dec 18 11:54:37 localhost volumio[1437]: info: Preload queue cleared Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 18 11:54:37 localhost volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 18 11:54:37 localhost volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:37 localhost volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::saveQueue Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::play index undefined Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:37 localhost volumio[1437]: info: CoreStateMachine::startPlaybackTimer Dec 18 11:54:37 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:37 localhost volumio[1437]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 18 11:54:37 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 11:54:37 localhost volumio[1437]: info: ConvSwitch: Get message: success Dec 18 11:54:37 localhost volumio[1437]: info: sendMpdCommand stop took 11 milliseconds Dec 18 11:54:37 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 11:54:37 localhost volumio[1437]: info: Dec 18 11:54:37 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:37 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:37 localhost volumio[1437]: info: sendMpdCommand clear took 36 milliseconds Dec 18 11:54:37 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac" Dec 18 11:54:37 localhost volumio[1437]: info: Dec 18 11:54:37 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:37 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:37 localhost volumio[1437]: error: updateQueue error: null Dec 18 11:54:37 localhost volumio[1437]: info: Dec 18 11:54:37 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:37 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:37 localhost volumio[1437]: info: ------------------------------ 18ms Dec 18 11:54:37 localhost volumio[1437]: info: sendMpdCommand add "USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac" took 14 milliseconds Dec 18 11:54:37 localhost volumio[1437]: info: ------------------------------ 11ms Dec 18 11:54:37 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand play Dec 18 11:54:37 localhost volumio[1437]: info: Dec 18 11:54:37 localhost volumio[1437]: ---------------------------- MPD announces system playlist update Dec 18 11:54:37 localhost volumio[1437]: info: Ignoring MPD Status Update Dec 18 11:54:37 localhost volumio[1437]: info: Dec 18 11:54:37 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:37 localhost volumio[1437]: info: ------------------------------ 18ms Dec 18 11:54:37 localhost volumio[1437]: info: sendMpdCommand play took 14 milliseconds Dec 18 11:54:37 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:37 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:37 localhost volumio[1437]: info: ------------------------------ 14ms Dec 18 11:54:37 localhost volumio[1437]: info: Dec 18 11:54:37 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:37 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:37 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:38 localhost volumio[1437]: info: sendMpdCommand status took 78 milliseconds Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:38 localhost volumio[1437]: info: Dec 18 11:54:38 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:38 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:38 localhost volumio[1437]: info: sendMpdCommand status took 89 milliseconds Dec 18 11:54:38 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:38 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:38 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:54:38 localhost volumio[1437]: info: ------------------------------ 135ms Dec 18 11:54:38 localhost volumio[1437]: info: Dec 18 11:54:38 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:38 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:38 localhost volumio[1437]: info: sendMpdCommand status took 43 milliseconds Dec 18 11:54:38 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 18 11:54:38 localhost volumio[1437]: info: sendMpdCommand status took 5 milliseconds Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:38 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3439 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:38 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 18 11:54:38 localhost volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: ------------------------------ 189ms Dec 18 11:54:38 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 48 milliseconds Dec 18 11:54:38 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 68 milliseconds Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:38 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:38 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":356,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3451 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:38 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 18 11:54:38 localhost volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":380,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3458 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:38 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 18 11:54:38 localhost volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:38 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:38 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:38 localhost volumio[1437]: info: ------------------------------ 218ms Dec 18 11:54:38 localhost volumio[1437]: info: ------------------------------ 179ms Dec 18 11:54:39 localhost volumio[1437]: info: MetaClient: Sent Meta Dec 18 11:54:39 localhost volumio[1437]: info: MetaClient: Get message: success Dec 18 11:54:46 localhost streamer-irq[1439]: file inexistence, touch: /data/ifi-k2-mode Dec 18 11:54:46 localhost streamer-irq[1439]: had touch: /data/ifi-k2-mode Dec 18 11:54:51 localhost streamer-irq[1439]: unlink: /data/ifi-k2-mode Dec 18 11:54:54 localhost volumio[1437]: info: ++++--++++ pause Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::volumioPause Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::pause Dec 18 11:54:54 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::servicePause Dec 18 11:54:54 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::servicePause Dec 18 11:54:54 localhost volumio[1437]: info: ControllerMpd::pause Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand pause Dec 18 11:54:54 localhost volumio[1437]: info: Dec 18 11:54:54 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:54 localhost volumio[1437]: info: sendMpdCommand pause took 4 milliseconds Dec 18 11:54:54 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:54 localhost volumio[1437]: info: Dec 18 11:54:54 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:54 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:54 localhost volumio[1437]: info: sendMpdCommand status took 7 milliseconds Dec 18 11:54:54 localhost volumio[1437]: info: sendMpdCommand status took 3 milliseconds Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:54 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:54 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:54 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 18 11:54:54 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:54 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:54 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:54 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:54 localhost volumio[1437]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16881,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4956 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:54 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::syncState stateService pause Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus pause Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:54 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 18 11:54:54 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:54 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:54 localhost volumio[1437]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16881,"duration":182,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4956 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:54 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::syncState stateService pause Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::syncState currentStatus pause Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:54 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:54 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:54 localhost volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 18 11:54:54 localhost volumio[1437]: info: ------------------------------ 81ms Dec 18 11:54:54 localhost volumio[1437]: info: ------------------------------ 73ms Dec 18 11:54:56 localhost kernel: usb 4-1.3: USB disconnect, device number 6 Dec 18 11:54:56 localhost systemd[1]: Starting Internet Detect for iFi Streamer... Dec 18 11:54:56 localhost volumio[1437]: info: Dec 18 11:54:56 localhost volumio[1437]: ---------------------------- USB Audio Device Detached Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:54:56 localhost volumio[1437]: info: Preparing to save Alsa Options, stopping services first Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:56 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::volumioPause Dec 18 11:54:56 localhost volumio[1437]: info: CoreStateMachine::pause Dec 18 11:54:56 localhost volumio[1437]: info: Saving Audio Output to: {"disallowPush":false,"output_device":{"value":"0,0","label":"SPDIF"}} Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , forceDoP Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:56 localhost volumio[1437]: info: Found match in Cards Database: setting mixer for card SPDIF Dec 18 11:54:56 localhost volumio[1437]: info: Ignoring Mixers Options Dec 18 11:54:56 localhost volumio[1437]: info: Device 0,0 does not have any Mixer Control Available Dec 18 11:54:56 localhost volumio[1437]: info: Restoring Previous Volume level: 100 false false Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 18 11:54:56 localhost volumio[1437]: info: Updating Volume Controller Parameters: Device: 0,0 Name: SPDIF Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 18 11:54:56 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:56 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:56 localhost systemd[1]: ifi-system-internet.service: Succeeded. Dec 18 11:54:56 localhost systemd[1]: Started Internet Detect for iFi Streamer. Dec 18 11:54:56 localhost volumio[1437]: info: Preparing to generate the ALSA configuration file Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: wizard , refreshWizarOutputDevices Dec 18 11:54:56 localhost volumio[1437]: info: Asound.conf file written Dec 18 11:54:56 localhost sudo[4839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 18 11:54:56 localhost sudo[4839]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:56 localhost sudo[4839]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:56 localhost volumio[1437]: No state is present for card rkifispdif Dec 18 11:54:56 localhost volumio[1437]: Found hardware: "rk-ifi-spdif" "" "" "" "" Dec 18 11:54:56 localhost volumio[1437]: Hardware is initialized using a generic method Dec 18 11:54:56 localhost volumio[1437]: No state is present for card rkifispdif Dec 18 11:54:56 localhost volumio[1437]: info: Tidal connect: Output device has changed Dec 18 11:54:56 localhost volumio[1437]: info: Output device has changed, restarting MPD Dec 18 11:54:56 localhost volumio[1437]: info: Qobuz connect: Output device has changed Dec 18 11:54:56 localhost volumio[1437]: info: Roon Ready: Output device has changed Dec 18 11:54:56 localhost volumio[1437]: info: Spotify: Output device has changed Dec 18 11:54:56 localhost volumio[1437]: info: Tidal connect: Output device has changed Dec 18 11:54:56 localhost volumio[1437]: info: Output device has changed, restarting MPD Dec 18 11:54:56 localhost sudo[4844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 11:54:56 localhost sudo[4844]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:56 localhost volumio[1437]: info: Qobuz connect: Output device has changed Dec 18 11:54:56 localhost volumio[1437]: info: Roon Ready: Output device has changed Dec 18 11:54:56 localhost volumio[1437]: info: Spotify: Output device has changed Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost sudo[4844]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost sudo[4846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 11:54:56 localhost sudo[4846]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:56 localhost sudo[4846]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 11:54:56 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 11:54:56 localhost volumio[1437]: info: MPD Permissions set Dec 18 11:54:56 localhost volumio[1437]: info: MPD Permissions set Dec 18 11:54:57 localhost volumio[1437]: info: Starting RoonReady Sync Dec 18 11:54:57 localhost sudo[4863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 18 11:54:57 localhost sudo[4863]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:57 localhost volumio[1437]: info: Starting RoonReady Sync Dec 18 11:54:57 localhost sudo[4865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 18 11:54:57 localhost sudo[4865]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:57 localhost sudo[4868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 18 11:54:57 localhost sudo[4868]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:57 localhost systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Dec 18 11:54:57 localhost node[542]: Closing connection with the client qobuz-connect Dec 18 11:54:57 localhost node[517]: ConvSwitch: Drop client qobuz-connect Dec 18 11:54:57 localhost systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:57 localhost systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Dec 18 11:54:57 localhost systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Dec 18 11:54:57 localhost sudo[4872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 18 11:54:57 localhost sudo[4872]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:57 localhost sudo[4863]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:57 localhost node[542]: Closing connection with the client roon-ready Dec 18 11:54:57 localhost systemd[1]: Stopping iFi Streamer Roon Ready Service... Dec 18 11:54:57 localhost node[517]: ConvSwitch: Drop client roon-ready Dec 18 11:54:57 localhost systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:57 localhost systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Dec 18 11:54:57 localhost systemd[1]: Stopped iFi Streamer Roon Ready Service. Dec 18 11:54:57 localhost sudo[4868]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:57 localhost systemd[1]: Started iFi Streamer Roon Ready Service. Dec 18 11:54:57 localhost sudo[4865]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:57 localhost sudo[4872]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:57 localhost volumio[1437]: info: RoonReady Started Dec 18 11:54:57 localhost volumio[1437]: info: RoonReady Started Dec 18 11:54:57 localhost play[4874]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Dec 18 11:54:57 localhost node[517]: ConvSwitch: Add client roon-ready Dec 18 11:54:57 localhost volumio[1437]: info: ConvSwitch: Get message: request:pause Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::serviceStop Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::serviceStop Dec 18 11:54:57 localhost volumio[1437]: info: ControllerMpd::stop Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:57 localhost volumio[1437]: info: Dec 18 11:54:57 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:57 localhost volumio[1437]: info: sendMpdCommand stop took 15 milliseconds Dec 18 11:54:57 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:57 localhost volumio[1437]: info: sendMpdCommand status took 2 milliseconds Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:57 localhost volumio[1437]: info: Dec 18 11:54:57 localhost volumio[1437]: ---------------------------- MPD announces state update: player Dec 18 11:54:57 localhost volumio[1437]: info: ControllerMpd::getState Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:54:57 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 18 11:54:57 localhost volumio[1437]: info: sendMpdCommand status took 6 milliseconds Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::parseState Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 11:54:57 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:57 localhost volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:57 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:57 localhost volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 18 11:54:57 localhost volumio[1437]: info: ------------------------------ 46ms Dec 18 11:54:57 localhost volumio[1437]: info: Dec 18 11:54:57 localhost volumio[1437]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Dec 18 11:54:57 localhost volumio[1437]: info: Roon Ready playback start Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:54:57 localhost volumio[1437]: info: Roon Ready metadata receiver is on Dec 18 11:54:57 localhost volumio[1437]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 18 11:54:57 localhost volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 18 11:54:57 localhost volumio[1437]: info: ControllerMpd::pushState Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::servicePushState Dec 18 11:54:57 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:54:57 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:54:57 localhost volumio[1437]: info: CorePlayQueue::getTrack 6 Dec 18 11:54:57 localhost volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","trackType":"flac"} Dec 18 11:54:57 localhost volumio[1437]: verbose: CURRENT POSITION 6 Dec 18 11:54:57 localhost volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 18 11:54:57 localhost volumio[1437]: info: ------------------------------ 74ms Dec 18 11:54:58 localhost sudo[4930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 18 11:54:58 localhost sudo[4930]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:58 localhost systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 18 11:54:58 localhost node[542]: Closing connection with the client tidal-connect Dec 18 11:54:58 localhost systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:58 localhost systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 18 11:54:58 localhost systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 18 11:54:58 localhost systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 18 11:54:58 localhost systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:58 localhost node[517]: ConvSwitch: Drop client tidal Dec 18 11:54:58 localhost systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 18 11:54:58 localhost systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 18 11:54:58 localhost systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 18 11:54:58 localhost sudo[4930]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:58 localhost sudo[4935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 18 11:54:58 localhost sudo[4935]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:58 localhost volumio[1437]: info: Tidal Connect Service Started Dec 18 11:54:58 localhost systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 18 11:54:58 localhost node[542]: A new connection has been established. Dec 18 11:54:58 localhost node[542]: Closing connection with the client tidal-connect Dec 18 11:54:58 localhost systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 18 11:54:58 localhost systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:58 localhost systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 18 11:54:58 localhost systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 18 11:54:58 localhost systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 18 11:54:58 localhost systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:54:58 localhost systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 18 11:54:58 localhost systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 18 11:54:58 localhost systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 18 11:54:58 localhost sudo[4935]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:58 localhost systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 18 11:54:58 localhost volumio[1437]: info: Tidal Connect Service Started Dec 18 11:54:58 localhost node[542]: A new connection has been established. Dec 18 11:54:58 localhost sudo[4946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 18 11:54:58 localhost sudo[4946]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:58 localhost systemd[1]: Started iFi Streamer Qobuz Connect Service. Dec 18 11:54:58 localhost sudo[4946]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:58 localhost volumio[1437]: info: Qobuz Connect Service Started Dec 18 11:54:58 localhost sudo[4954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 18 11:54:58 localhost sudo[4954]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:58 localhost sudo[4954]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:58 localhost volumio[1437]: info: Qobuz Connect Service Started Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Starting sample application Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Device Name: ZEN Stream3 Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Manufacturer: Sample manufacturer Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Model: iFi Streamer Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Serial Number: ifi:da:8f:57:2d:4e:73 Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Audio output device: volumio Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Volume control device: default Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Volume control element: Master Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.667 [4980.4980] DEBUG SampleApp: Local config server port: 8000 Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.672 [4980.4980] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.672 [4980.4980] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Dec 18 11:54:58 localhost play[4958]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 18 11:54:58 localhost node[542]: A new connection has been established. Dec 18 11:54:58 localhost play[4958]: [Info] MetaClient: MetaClientInit Dec 18 11:54:58 localhost play[4958]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 18 11:54:58 localhost node[517]: ConvSwitch: Add client qobuz-connect Dec 18 11:54:58 localhost play[4958]: [Info] ConvSwitch: ConvSwitchInit Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.779 [4980.4980] DEBUG ActiveStateManager: [0xab84a098]: Creating new Active State Manager Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.779 [4980.4980] DEBUG NetworkActivityTracker: [0xab84a108]: Creating new Network Activity Tracker Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.779 [4980.4980] DEBUG EndpointManager: [0xab84b080]: Creating Endpoint Manager Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] DEBUG PlaybackSessionManager: [0xab84b470]: Creating Playback Session Manager Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] DEBUG BackoffTimer: [0xab84b5e8]: Creating Backoff Timer Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] DEBUG MediaEngine: [0xab84b708]: Creating new Media Engine Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] DEBUG AudioRenderer: [0xab84b928]: Creating Audio Renderer Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] TRACE MediaEngineCommandSequencer: [0xab84ba80]: Creating Media Engine Command Sequencer Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] DEBUG PlaybackControlsManager: [0xab84bae0]: Creating new Playback Controls Manager Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] DEBUG VolumeManager: [0xab84bb70]: Creating new Volume Manager Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] DEBUG ProtocolHandler: [0xab84be48]: Creating Protocol Handler Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.787 [4980.4980] DEBUG MessageReceiver: [0xab84bee8]: Creating Message Receiver Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] DEBUG RendererActivationController: [0xab84c060]: Creating new Renderer Activation Controller Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] TRACE RendererActivationCommandSequencer: [0xab84c108]: Creating Renderer Activation Command Sequencer Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] DEBUG ReportingManager: [0xab84c218]: Creating new Reporting Manager Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] DEBUG StreamingEventReporter: [0xab84c260]: Creating Streaming Event Reporter Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] DEBUG BackoffTimer: [0xab84c340]: Creating Backoff Timer Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] DEBUG TokenExpiryController: [0xab84c450]: Creating Token Expiry Controller Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] DEBUG UserInactivityManager: [0xab84c4a0]: Creating new User Inactivity Manager Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] INFO VolumeManager: [0xab84bb70]: Setting new playback volume: 100 Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] INFO VolumeManager: [0xab84bb70]: Setting new mute state: 1 Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] DEBUG SampleApp: Qobuz Connect has been initialized Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] TRACE SampleApp: Running event loop Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] INFO QobuzConnect: [0xab84c540]: Client initialized! Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.788 [4980.4980] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream3, service name: _qobuz-connect._tcp Dec 18 11:54:58 localhost sudo[4984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 18 11:54:58 localhost sudo[4984]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.812 [4980.4980] DEBUG SampleApp: Avahi client state changed: RUNNING Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.817 [4980.4980] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Dec 18 11:54:58 localhost systemd[1]: mpd.socket: Succeeded. Dec 18 11:54:58 localhost systemd[1]: Closed mpd.socket. Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.827 [4980.4980] INFO LocalConfigManager: [0xab84b450]: Starting Local Configuration server Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.828 [4980.4980] INFO SampleApp: Starting Local configuration server Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.829 [4980.4980] INFO SampleApp: Playback mute state changed: 1 Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.829 [4980.4980] ERROR SampleApp: Unable to set mute state, no ALSA volume element Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.829 [4980.4980] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.829 [4980.4980] INFO SampleApp: Playback volume changed: 100 Dec 18 11:54:58 localhost play[4958]: 20251218 11:54:58.829 [4980.4980] ERROR SampleApp: Unable to set volume, no ALSA volume element Dec 18 11:54:58 localhost systemd[1]: Stopping Music Player Daemon... Dec 18 11:54:58 localhost sudo[4988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 18 11:54:58 localhost sudo[4988]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:58 localhost systemd[1]: mpd.service: Succeeded. Dec 18 11:54:58 localhost systemd[1]: Stopped Music Player Daemon. Dec 18 11:54:58 localhost sudo[4984]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:58 localhost sudo[4988]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:58 localhost sudo[4993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 18 11:54:58 localhost sudo[4993]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:59 localhost systemd[1]: Listening on mpd.socket. Dec 18 11:54:59 localhost systemd[1]: Starting Music Player Daemon... Dec 18 11:54:59 localhost sudo[4996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 18 11:54:59 localhost sudo[4996]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:59 localhost systemd[1]: mpd.socket: Succeeded. Dec 18 11:54:59 localhost systemd[1]: Closed mpd.socket. Dec 18 11:54:59 localhost systemd[1]: Stopping mpd.socket. Dec 18 11:54:59 localhost systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Dec 18 11:54:59 localhost systemd[1]: Failed to listen on mpd.socket. Dec 18 11:54:59 localhost systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 18 11:54:59 localhost systemd[1]: mpd.service: Succeeded. Dec 18 11:54:59 localhost systemd[1]: Stopped Music Player Daemon. Dec 18 11:54:59 localhost systemd[1]: Listening on mpd.socket. Dec 18 11:54:59 localhost systemd[1]: Starting Music Player Daemon... Dec 18 11:54:59 localhost sudo[4999]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 11:54:59 localhost sudo[4999]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:54:59 localhost sudo[4999]: pam_unix(sudo:session): session closed for user root Dec 18 11:54:59 localhost node[542]: A new connection has been established. Dec 18 11:54:59 localhost play[4958]: 20251218 11:54:59.629 [4980.4980] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 18 11:55:00 localhost sudo[5009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 18 11:55:00 localhost sudo[5009]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:55:00 localhost play[4542]: 2025-12-18 11:55:00.349 E esdk select error: 4 -> Interrupted system call Dec 18 11:55:00 localhost play[4542]: 2025-12-18 11:55:00.350 I app Exiting with error = 0 Dec 18 11:55:00 localhost play[4542]: 2025-12-18 11:55:00.350 I api SpFree() Dec 18 11:55:00 localhost play[4542]: 2025-12-18 11:55:00.351 I api SpFree [returned value: 0] Dec 18 11:55:00 localhost play[4542]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 18 11:55:00 localhost play[4542]: [Info] ConvSwitch: ConvSwitchInit Dec 18 11:55:00 localhost play[4542]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 18 11:55:00 localhost play[4542]: [Info] MetaClient: MetaClientInit Dec 18 11:55:00 localhost play[4542]: [Info] ConvSwitch: ConvSwitchExit Dec 18 11:55:00 localhost play[4542]: [Info] MetaClient: MetaClientExit Dec 18 11:55:00 localhost node[517]: ConvSwitch: Drop client spotify Dec 18 11:55:00 localhost node[542]: Closing connection with the client spotify Dec 18 11:55:00 localhost systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 18 11:55:00 localhost systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:55:00 localhost systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 18 11:55:00 localhost systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 18 11:55:00 localhost volumio[1437]: info: CoreStateMachine::getcurrentVolume Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 18 11:55:00 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:55:00 localhost systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:55:00 localhost sudo[5009]: pam_unix(sudo:session): session closed for user root Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:55:00 localhost sudo[5012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 18 11:55:00 localhost sudo[5012]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:55:00 localhost volumio[1437]: info: Spotify Connect Service Started Dec 18 11:55:00 localhost systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 18 11:55:00 localhost systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 18 11:55:00 localhost systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 18 11:55:00 localhost systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 18 11:55:00 localhost systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 18 11:55:00 localhost sudo[5012]: pam_unix(sudo:session): session closed for user root Dec 18 11:55:00 localhost volumio[1437]: info: Spotify Connect Service Started Dec 18 11:55:00 localhost play[5017]: output: volumio mixer: hw:0 Dec 18 11:55:00 localhost node[517]: ConvSwitch: Add client spotify Dec 18 11:55:00 localhost node[542]: A new connection has been established. Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.691 I api SpRegisterDebugCallbacks [returned value: 0] Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.691 I api SpInit(0xffa55380, API v66) Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.691 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.692 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.692 I esdk Successfully registered dns callback: dns_lookup_callback Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.692 I download Using 128 kB for cache buffer Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.692 I download Using 621 kB for compressed audio buffer Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.692 I esdk Successfully registered error callback Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.704 I esdk Creating IPv4 socket (domain 2) Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.705 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.705 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.705 I esdk Creating IPv4 socket (domain 2) Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.705 I api SpInit [returned value: 0] Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.705 I api SpRegisterConnectionCallbacks(0xffa55368, (nil)) Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.705 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.705 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.706 I api SpRegisterPlaybackCallbacks(0xffa55350, (nil)) Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.706 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.706 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.712 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 18 11:55:00 localhost play[5017]: 2025-12-18 11:55:00.712 I app Initialized! Dec 18 11:55:00 localhost volumio[1437]: info: VolumeController::SetAlsaVolume100 Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 18 11:55:00 localhost volumio[1437]: info: CoreStateMachine::pushState Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 18 11:55:00 localhost volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:55:00 localhost node[517]: ConvSwitch: Add client tidal Dec 18 11:55:00 localhost tidal_connect_application[5034]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 18 11:55:00 localhost tidal_connect_application[5034]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 11:55:00 localhost tidal_connect_application[5034]: *** WARNING *** For more information see Dec 18 11:55:01 localhost ifi_metadata[4945]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 18 11:55:01 localhost ifi_metadata[4945]: [Info] MetaClient: MetaClientInit Dec 18 11:55:01 localhost ifi_metadata[4945]: Connected Dec 18 11:55:01 localhost mpd[5001]: Dec 18 11:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 11:55:01 localhost systemd[1]: Started Music Player Daemon. Dec 18 11:55:01 localhost sudo[4993]: pam_unix(sudo:session): session closed for user root Dec 18 11:55:01 localhost sudo[4996]: pam_unix(sudo:session): session closed for user root Dec 18 11:55:01 localhost volumio[1437]: info: Restarted MPD. Dec 18 11:55:01 localhost volumio[1437]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Dec 18 11:55:01 localhost volumio[1437]: Job for mpd.socket failed. Dec 18 11:55:01 localhost volumio[1437]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Dec 18 11:55:01 localhost volumio[1437]: error: MPD error: The expression evaluated to a falsy value: Dec 18 11:55:01 localhost volumio[1437]: assert.ok(self.idling) Dec 18 11:55:01 localhost volumio[1437]: error: The expression evaluated to a falsy value: Dec 18 11:55:01 localhost volumio[1437]: assert.ok(self.idling) Dec 18 11:55:01 localhost systemd[1]: Starting Internet Detect for iFi Streamer... Dec 18 11:55:01 localhost volumio[1437]: error: updateQueue error: null Dec 18 11:55:02 localhost systemd[1]: ifi-system-internet.service: Succeeded. Dec 18 11:55:02 localhost systemd[1]: Started Internet Detect for iFi Streamer. Dec 18 11:55:02 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:55:02 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:55:02 localhost volumio[1437]: info: CoreStateMachine::serviceStop Dec 18 11:55:02 localhost volumio[1437]: info: CoreCommandRouter::serviceStop Dec 18 11:55:02 localhost volumio[1437]: info: CoreCommandRouter::volumioStop Dec 18 11:55:02 localhost volumio[1437]: info: CoreStateMachine::stop Dec 18 11:55:02 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:55:02 localhost volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 11:55:02 localhost volumio[1437]: info: Roon Ready Stop Dec 18 11:55:03 localhost volumio[1437]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 11:55:04 localhost volumio[1437]: Error: connect ETIMEDOUT 199.59.149.206:443 Dec 18 11:55:04 localhost volumio[1437]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 18 11:55:04 localhost volumio[1437]: errno: -110, Dec 18 11:55:04 localhost volumio[1437]: code: 'ETIMEDOUT', Dec 18 11:55:04 localhost volumio[1437]: syscall: 'connect', Dec 18 11:55:04 localhost volumio[1437]: address: '199.59.149.206', Dec 18 11:55:04 localhost volumio[1437]: port: 443 Dec 18 11:55:04 localhost volumio[1437]: } Dec 18 11:55:04 localhost volumio[1437]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 11:55:04 localhost sudo[5120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 11:54 Dec 18 11:55:04 localhost sudo[5120]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="45aad196ab181636cec04ab79f52572f8ba3c549" VOLUMIO_FE_VERSION="8a7c1663e5becc06293ef92fd46324fc2cb9ce6e" VOLUMIO_FE3_VERSION="c5cad6acce062070373bc18affd0d2a1a9956a16" VOLUMIO_BE_VERSION="0fad662d4c47d7729fc677cd069fd5e675bebf9c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Nov 15 15:45:37 CST 2025" VOLUMIO_VERSION="0.00.7" VOLUMIO_HARDWARE="zen-stream3" VOLUMIO_DEVICENAME="ZEN Stream3" VOLUMIO_HASH="3350ed41a04d50b70f39bebb8e49f746"