Jan 28 11:43:05 mini volumio[1256]: info: VolumeController::SetAlsaVolume90 Jan 28 11:43:05 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:05 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:05 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:43:05 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:05 mini volumio[1256]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Jan 28 11:43:06 mini volumio[1256]: info: VolumeController::SetAlsaVolume85 Jan 28 11:43:06 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:06 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:06 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:43:06 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:06 mini volumio[1256]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Jan 28 11:43:13 mini volumio[1256]: info: CoreCommandRouter::volumioPlay Jan 28 11:43:13 mini volumio[1256]: info: CoreStateMachine::play index undefined Jan 28 11:43:13 mini volumio[1256]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:43:13 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:15 mini volumio[1256]: info: CoreCommandRouter::volumioPlay Jan 28 11:43:15 mini volumio[1256]: info: CoreStateMachine::play index undefined Jan 28 11:43:15 mini volumio[1256]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:43:15 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 11:43:19 mini volumio[1256]: info: Retrieving Cloud Streaming UI Jan 28 11:43:19 mini volumio[1256]: info: Getting Tidal Cloud Configuration Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 11:43:19 mini volumio[1256]: info: Getting Qobuz Cloud Configuration Jan 28 11:43:19 mini volumio[1256]: info: Asking plugin for UI Config Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 11:43:19 mini volumio[1256]: info: Getting Spotify Cloud Configuration Jan 28 11:43:19 mini volumio[1256]: info: Asking plugin for UI Config Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 11:43:19 mini volumio[1256]: info: Saving Spotify Acccount Jan 28 11:43:19 mini volumio[1256]: info: Got Tidal Cloud Configuration Jan 28 11:43:19 mini volumio[1256]: info: Got it Jan 28 11:43:19 mini volumio[1256]: info: Got it Jan 28 11:43:19 mini volumio[1256]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 11:43:19 mini volumio[1256]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 11:43:19 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 28 11:43:20 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand rescan Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:20 mini volumio[1256]: info: Jan 28 11:43:20 mini volumio[1256]: ---------------------------- MPD announces state update: update Jan 28 11:43:20 mini volumio[1256]: info: sendMpdCommand rescan took 6 milliseconds Jan 28 11:43:20 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:20 mini volumio[1256]: info: Jan 28 11:43:20 mini volumio[1256]: ---------------------------- MPD announces state update: update Jan 28 11:43:20 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:20 mini volumio[1256]: info: sendMpdCommand status took 7 milliseconds Jan 28 11:43:20 mini volumio[1256]: info: sendMpdCommand status took 4 milliseconds Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:20 mini volumio[1256]: info: Command Router : Notfying DB Updatetrue Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:20 mini volumio[1256]: info: sendMpdCommand status took 62 milliseconds Jan 28 11:43:20 mini volumio[1256]: info: sendMpdCommand status took 59 milliseconds Jan 28 11:43:20 mini volumio[1256]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:20 mini volumio[1256]: info: Command Router : Notfying DB Updatetrue Jan 28 11:43:20 mini volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:20 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:20 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:20 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:20 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:20 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:20 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:20 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:20 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:20 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:20 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:43:20 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:20 mini volumio[1256]: info: ------------------------------ 131ms Jan 28 11:43:20 mini volumio[1256]: info: sendMpdCommand playlistinfo took 45 milliseconds Jan 28 11:43:20 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:20 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:20 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:20 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:20 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:20 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:20 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:20 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:20 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:20 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:20 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:20 mini volumio[1256]: info: ------------------------------ 168ms Jan 28 11:43:23 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 11:43:25 mini volumio[1256]: info: MPD Database updated - AlbumList cache refreshed Jan 28 11:43:25 mini volumio[1256]: info: Jan 28 11:43:25 mini volumio[1256]: ---------------------------- MPD announces state update: database Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:25 mini volumio[1256]: info: Jan 28 11:43:25 mini volumio[1256]: ---------------------------- MPD announces state update: update Jan 28 11:43:25 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:25 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:25 mini volumio[1256]: info: MPD Database updated - AlbumList cache refreshed Jan 28 11:43:25 mini volumio[1256]: info: Jan 28 11:43:25 mini volumio[1256]: ---------------------------- MPD announces state update: database Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:25 mini volumio[1256]: info: Jan 28 11:43:25 mini volumio[1256]: ---------------------------- MPD announces state update: update Jan 28 11:43:25 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:25 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand status took 37 milliseconds Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand status took 39 milliseconds Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand status took 40 milliseconds Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand status took 42 milliseconds Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand status took 41 milliseconds Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand status took 28 milliseconds Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:25 mini volumio[1256]: info: Command Router : Notfying DB Updatefalse Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:25 mini volumio[1256]: info: Command Router : Notfying DB Updatefalse Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand playlistinfo took 54 milliseconds Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand playlistinfo took 56 milliseconds Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand playlistinfo took 41 milliseconds Jan 28 11:43:25 mini volumio[1256]: info: sendMpdCommand playlistinfo took 41 milliseconds Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:25 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:25 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:25 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:25 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:25 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:25 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:25 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:25 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:25 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:25 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:25 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:25 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:25 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:25 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:25 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:25 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:25 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:25 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:25 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:25 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:25 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:25 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:25 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:25 mini volumio[1256]: info: ------------------------------ 218ms Jan 28 11:43:25 mini volumio[1256]: info: ------------------------------ 218ms Jan 28 11:43:25 mini volumio[1256]: info: ------------------------------ 217ms Jan 28 11:43:25 mini volumio[1256]: info: ------------------------------ 218ms Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand update Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:26 mini volumio[1256]: info: Jan 28 11:43:26 mini volumio[1256]: ---------------------------- MPD announces state update: update Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand update took 3 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:26 mini volumio[1256]: info: Jan 28 11:43:26 mini volumio[1256]: ---------------------------- MPD announces state update: update Jan 28 11:43:26 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand status took 5 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand status took 4 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand status took 3 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand status took 3 milliseconds Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:26 mini volumio[1256]: info: Command Router : Notfying DB Updatetrue Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:26 mini volumio[1256]: info: Command Router : Notfying DB Updatetrue Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:26 mini volumio[1256]: info: Jan 28 11:43:26 mini volumio[1256]: ---------------------------- MPD announces state update: update Jan 28 11:43:26 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand playlistinfo took 35 milliseconds Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:26 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:26 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:26 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:26 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:26 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:26 mini volumio[1256]: info: ------------------------------ 96ms Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:26 mini volumio[1256]: info: Jan 28 11:43:26 mini volumio[1256]: ---------------------------- MPD announces state update: update Jan 28 11:43:26 mini volumio[1256]: info: ControllerMpd::getState Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand playlistinfo took 45 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand status took 30 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand status took 30 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand status took 10 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand status took 10 milliseconds Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:26 mini volumio[1256]: info: Command Router : Notfying DB Updatefalse Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:26 mini volumio[1256]: info: Command Router : Notfying DB Updatefalse Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseState Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:43:26 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:26 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:26 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:26 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:26 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:26 mini volumio[1256]: info: ------------------------------ 160ms Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand playlistinfo took 37 milliseconds Jan 28 11:43:26 mini volumio[1256]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:26 mini volumio[1256]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:43:26 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:26 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:26 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:26 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:26 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:26 mini volumio[1256]: info: ControllerMpd::pushState Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::servicePushState Jan 28 11:43:26 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:26 mini volumio[1256]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 6232/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jan 28 11:43:26 mini volumio[1256]: verbose: CURRENT POSITION 0 Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::syncState stateService pause Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:43:26 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:26 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:26 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:26 mini volumio[1256]: info: ------------------------------ 106ms Jan 28 11:43:26 mini volumio[1256]: info: ------------------------------ 87ms Jan 28 11:43:27 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 11:43:31 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 11:43:36 mini volumio[1256]: Invalid card number '5'. Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 11:43:36 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 11:43:40 mini volumio[1256]: info: CoreCommandRouter::volumioPlay Jan 28 11:43:40 mini volumio[1256]: info: CoreStateMachine::play index undefined Jan 28 11:43:40 mini volumio[1256]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:43:40 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:47 mini volumio[1256]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 28 11:43:47 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 28 11:43:47 mini volumio[1256]: info: Preparing to save Alsa Options, stopping services first Jan 28 11:43:47 mini volumio[1256]: info: CoreCommandRouter::volumioGetState Jan 28 11:43:47 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:47 mini volumio[1256]: info: CoreCommandRouter::volumioPause Jan 28 11:43:47 mini volumio[1256]: info: CoreStateMachine::pause Jan 28 11:43:47 mini volumio[1256]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Jan 28 11:43:47 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 11:43:47 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 11:43:47 mini volumio[1256]: info: Found match in Cards Database: setting mixer for card HDMI Jan 28 11:43:47 mini volumio[1256]: info: Setting mixer IEC958 for card HDMI Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 28 11:43:48 mini volumio[1256]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 28 11:43:48 mini volumio[1256]: info: Disabling external Volume Control Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 11:43:48 mini volumio[1256]: info: Preparing to generate the ALSA configuration file Jan 28 11:43:48 mini volumio[1256]: Unable to parse: Jan 28 11:43:48 mini volumio[1256]: Simple mixer control 'IEC958',0 Jan 28 11:43:48 mini volumio[1256]: Capabilities: pswitch pswitch-joined Jan 28 11:43:48 mini volumio[1256]: Playback channels: Mono Jan 28 11:43:48 mini volumio[1256]: Mono: Playback [on] Jan 28 11:43:48 mini volumio[1256]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 11:43:48 mini volumio[1256]: Unable to parse: Jan 28 11:43:48 mini volumio[1256]: Simple mixer control 'IEC958',0 Jan 28 11:43:48 mini volumio[1256]: Capabilities: pswitch pswitch-joined Jan 28 11:43:48 mini volumio[1256]: Playback channels: Mono Jan 28 11:43:48 mini volumio[1256]: Mono: Playback [on] Jan 28 11:43:48 mini volumio[1256]: info: VolumeController:: Volume=undefined Mute =false Jan 28 11:43:48 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:48 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:48 mini volumio[1256]: info: Asound.conf file written Jan 28 11:43:48 mini sudo[3266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 28 11:43:48 mini sudo[3266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 11:43:48 mini sudo[3266]: pam_unix(sudo:session): session closed for user root Jan 28 11:43:48 mini volumio[1256]: info: Output device has changed, restarting MPD Jan 28 11:43:48 mini volumio[1256]: info: Output device has changed, restarting Shairport Sync Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 11:43:48 mini sudo[3272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 11:43:48 mini sudo[3272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 11:43:48 mini sudo[3274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 11:43:48 mini sudo[3272]: pam_unix(sudo:session): session closed for user root Jan 28 11:43:48 mini sudo[3274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 11:43:48 mini volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 11:43:48 mini volumio[1256]: info: Output device has changed, restarting MPD Jan 28 11:43:48 mini volumio[1256]: info: Output device has changed, restarting Shairport Sync Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 11:43:48 mini systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 11:43:48 mini sudo[3282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 11:43:48 mini sudo[3282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 11:43:48 mini sudo[3284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 11:43:48 mini sudo[3284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 11:43:48 mini volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 11:43:48 mini volumio[1256]: info: MPD Permissions set Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini systemd[1]: mpd.service: Deactivated successfully. Jan 28 11:43:48 mini systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 11:43:48 mini systemd[1]: mpd.service: Consumed 1.853s CPU time. Jan 28 11:43:48 mini sudo[3282]: pam_unix(sudo:session): session closed for user root Jan 28 11:43:48 mini systemd[1]: mpd.socket: Deactivated successfully. Jan 28 11:43:48 mini systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 11:43:48 mini systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 11:43:48 mini systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 11:43:48 mini systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 11:43:48 mini systemd[1]: mpd.service: Deactivated successfully. Jan 28 11:43:48 mini systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 11:43:48 mini systemd[1]: mpd.socket: Deactivated successfully. Jan 28 11:43:48 mini systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 11:43:48 mini systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 11:43:48 mini systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 11:43:48 mini volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 11:43:48 mini volumio[1256]: info: MPD Permissions set Jan 28 11:43:48 mini volumio[1256]: info: Starting Shairport Sync Jan 28 11:43:48 mini volumio[1256]: info: Starting Shairport Sync Jan 28 11:43:48 mini sudo[3315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 11:43:48 mini sudo[3315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 11:43:48 mini sudo[3317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 11:43:48 mini sudo[3317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 11:43:48 mini sudo[3310]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 11:43:48 mini sudo[3310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 11:43:48 mini sudo[3310]: pam_unix(sudo:session): session closed for user root Jan 28 11:43:48 mini systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 11:43:48 mini systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 11:43:48 mini systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 11:43:48 mini systemd[1]: shairport-sync.service: Consumed 2.739s CPU time. Jan 28 11:43:48 mini systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 11:43:48 mini sudo[3315]: pam_unix(sudo:session): session closed for user root Jan 28 11:43:48 mini sudo[3317]: pam_unix(sudo:session): session closed for user root Jan 28 11:43:48 mini volumio[1256]: info: Shairport-Sync Started Jan 28 11:43:48 mini volumio[1256]: info: Shairport-Sync Started Jan 28 11:43:49 mini mpd[3321]: 2026-01-28T11:43:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 11:43:49 mini systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 11:43:49 mini sudo[3284]: pam_unix(sudo:session): session closed for user root Jan 28 11:43:49 mini sudo[3274]: pam_unix(sudo:session): session closed for user root Jan 28 11:43:49 mini volumio[1256]: error: MPD error: The expression evaluated to a falsy value: Jan 28 11:43:49 mini volumio[1256]: assert.ok(self.idling) Jan 28 11:43:49 mini volumio[1256]: error: The expression evaluated to a falsy value: Jan 28 11:43:49 mini volumio[1256]: assert.ok(self.idling) Jan 28 11:43:49 mini volumio[1256]: error: updateQueue error: null Jan 28 11:43:52 mini volumio[1256]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 28 11:43:52 mini volumio[1256]: info: CoreStateMachine::getcurrentVolume Jan 28 11:43:52 mini volumio[1256]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 11:43:52 mini volumio[1256]: Unable to parse: Jan 28 11:43:52 mini volumio[1256]: Simple mixer control 'IEC958',0 Jan 28 11:43:52 mini volumio[1256]: Capabilities: pswitch pswitch-joined Jan 28 11:43:52 mini volumio[1256]: Playback channels: Mono Jan 28 11:43:52 mini volumio[1256]: Mono: Playback [on] Jan 28 11:43:52 mini volumio[1256]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 28 11:43:52 mini volumio[1256]: Unable to parse: Jan 28 11:43:52 mini volumio[1256]: Simple mixer control 'IEC958',0 Jan 28 11:43:52 mini volumio[1256]: Capabilities: pswitch pswitch-joined Jan 28 11:43:52 mini volumio[1256]: Playback channels: Mono Jan 28 11:43:52 mini volumio[1256]: Mono: Playback [on] Jan 28 11:43:52 mini volumio[1256]: info: VolumeController:: Volume=undefined Mute =false Jan 28 11:43:52 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:52 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:52 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:52 mini volumio[1256]: info: CoreStateMachine::updateTrackBlock Jan 28 11:43:52 mini volumio[1256]: info: CorePlayQueue::getTrackBlock Jan 28 11:43:52 mini volumio[1256]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 11:43:52 mini volumio[1256]: Unable to parse: Jan 28 11:43:52 mini volumio[1256]: Simple mixer control 'IEC958',0 Jan 28 11:43:52 mini volumio[1256]: Capabilities: pswitch pswitch-joined Jan 28 11:43:52 mini volumio[1256]: Playback channels: Mono Jan 28 11:43:52 mini volumio[1256]: Mono: Playback [on] Jan 28 11:43:52 mini volumio[1256]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 28 11:43:52 mini volumio[1256]: Unable to parse: Jan 28 11:43:52 mini volumio[1256]: Simple mixer control 'IEC958',0 Jan 28 11:43:52 mini volumio[1256]: Capabilities: pswitch pswitch-joined Jan 28 11:43:52 mini volumio[1256]: Playback channels: Mono Jan 28 11:43:52 mini volumio[1256]: Mono: Playback [on] Jan 28 11:43:52 mini volumio[1256]: info: VolumeController:: Volume=undefined Mute =false Jan 28 11:43:52 mini volumio[1256]: info: CoreStateMachine::pushState Jan 28 11:43:52 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:43:52 mini volumio[1256]: info: CoreCommandRouter::volumioPushState Jan 28 11:43:55 mini volumio[1256]: info: CoreCommandRouter::volumioPlay Jan 28 11:43:55 mini volumio[1256]: info: CoreStateMachine::play index undefined Jan 28 11:43:55 mini volumio[1256]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:43:55 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:44:00 mini volumio[1256]: info: CoreCommandRouter::volumioPlay Jan 28 11:44:00 mini volumio[1256]: info: CoreStateMachine::play index undefined Jan 28 11:44:00 mini volumio[1256]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:44:00 mini volumio[1256]: info: CorePlayQueue::getTrack 0 Jan 28 11:44:02 mini volumio[1256]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 11:44:02 mini volumio[1256]: Error: connect ETIMEDOUT 173.252.108.21:443 Jan 28 11:44:02 mini volumio[1256]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 28 11:44:02 mini volumio[1256]: errno: -110, Jan 28 11:44:02 mini volumio[1256]: code: 'ETIMEDOUT', Jan 28 11:44:02 mini volumio[1256]: syscall: 'connect', Jan 28 11:44:02 mini volumio[1256]: address: '173.252.108.21', Jan 28 11:44:02 mini volumio[1256]: port: 443 Jan 28 11:44:02 mini volumio[1256]: } Jan 28 11:44:02 mini volumio[1256]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 11:44:02 mini sudo[3388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 11:43' Jan 28 11:44:02 mini sudo[3388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"