-- Logs begin at Tue 2025-01-07 04:10:44 CST, end at Fri 2025-01-10 21:05:36 CST. -- Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioSeek Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::seek Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I142603.flac","realUri":"upnp/folder/http://192.168.1.28:9050/dev0/srv1/control@0$1$16$2639$2640","type":"song","albumart":"http://192.168.1.28:9050/disk/DLNA-PNJPEG_TN-OP01-CI1-FLAGS00d00000/defaa/A/O0$1$8I142603.jpg?scale=org","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","name":"Amor De Loca Juventud","title":"Amor De Loca Juventud","duration":203} Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::startPlaybackTimer Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: [1736564641051] ControllerUPNPBrowser::seek Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::seek Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: error: null Jan 10 21:04:01 minidsp-shd volumio[632]: info: Jan 10 21:04:01 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: Jan 10 21:04:01 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:01 minidsp-shd volumio[632]: info: Jan 10 21:04:01 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand status took 44 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand status took 68 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand status took 66 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 100 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 41 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124000,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Amor De Loca Juventud","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I142603.flac","trackType":"flac"} Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: CURRENT POSITION 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:01 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124137,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Amor De Loca Juventud","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I142603.flac","trackType":"flac"} Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: CURRENT POSITION 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:01 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124343,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Amor De Loca Juventud","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I142603.flac","trackType":"flac"} Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: CURRENT POSITION 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:01 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: ------------------------------ 281ms Jan 10 21:04:01 minidsp-shd volumio[632]: info: ------------------------------ 245ms Jan 10 21:04:01 minidsp-shd volumio[632]: info: ------------------------------ 245ms Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioSeek Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::seek Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I142603.flac","realUri":"upnp/folder/http://192.168.1.28:9050/dev0/srv1/control@0$1$16$2639$2640","type":"song","albumart":"http://192.168.1.28:9050/disk/DLNA-PNJPEG_TN-OP01-CI1-FLAGS00d00000/defaa/A/O0$1$8I142603.jpg?scale=org","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","name":"Amor De Loca Juventud","title":"Amor De Loca Juventud","duration":203} Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::startPlaybackTimer Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: [1736564641473] ControllerUPNPBrowser::seek Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::seek Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: error: null Jan 10 21:04:01 minidsp-shd volumio[632]: info: Jan 10 21:04:01 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:01 minidsp-shd volumio[632]: info: Jan 10 21:04:01 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:01 minidsp-shd volumio[632]: info: Jan 10 21:04:01 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand status took 10 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand status took 12 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand status took 9 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151045,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Amor De Loca Juventud","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I142603.flac","trackType":"flac"} Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: CURRENT POSITION 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:01 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: ------------------------------ 60ms Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151045,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Amor De Loca Juventud","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I142603.flac","trackType":"flac"} Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: CURRENT POSITION 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:01 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151068,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Amor De Loca Juventud","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I142603.flac","trackType":"flac"} Jan 10 21:04:01 minidsp-shd volumio[632]: verbose: CURRENT POSITION 18 Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:01 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:01 minidsp-shd volumio[632]: info: ------------------------------ 178ms Jan 10 21:04:01 minidsp-shd volumio[632]: info: ------------------------------ 178ms Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:01 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:08 minidsp-shd volumio[632]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:04:08 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 10 21:04:08 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 10 21:04:08 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 10 21:04:08 minidsp-shd volumio[632]: info: Received Get System Version Jan 10 21:04:08 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 21:04:08 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jan 10 21:04:18 minidsp-shd volumio[632]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:04:19 minidsp-shd volumio[632]: xcb_connection_has_error() returned true Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 21:04:19 minidsp-shd volumio[632]: xcb_connection_has_error() returned true Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 21:04:19 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jan 10 21:04:23 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 21:04:23 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 21:04:23 minidsp-shd volumio[632]: info: Discovery: Getting this device information Jan 10 21:04:23 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetState Jan 10 21:04:23 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 21:04:48 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 18 Jan 10 21:04:48 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 19 Jan 10 21:04:48 minidsp-shd volumio[632]: info: Prefetching next song Jan 10 21:04:48 minidsp-shd volumio[632]: info: Doing Prefetch in UPNP Jan 10 21:04:48 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I143371.flac" Jan 10 21:04:48 minidsp-shd volumio[632]: info: sendMpdCommand add "http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I143371.flac" took 3 milliseconds Jan 10 21:04:48 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 10 21:04:48 minidsp-shd volumio[632]: info: Jan 10 21:04:48 minidsp-shd volumio[632]: ---------------------------- MPD announces system playlist update Jan 10 21:04:48 minidsp-shd volumio[632]: info: Ignoring MPD Status Update Jan 10 21:04:48 minidsp-shd volumio[632]: info: sendMpdCommand consume 1 took 3 milliseconds Jan 10 21:04:48 minidsp-shd volumio[632]: info: Jan 10 21:04:48 minidsp-shd volumio[632]: ---------------------------- MPD announces system playlist update Jan 10 21:04:48 minidsp-shd volumio[632]: info: Ignoring MPD Status Update Jan 10 21:04:48 minidsp-shd volumio[632]: info: Jan 10 21:04:48 minidsp-shd volumio[632]: ---------------------------- MPD announces system playlist update Jan 10 21:04:48 minidsp-shd volumio[632]: info: Ignoring MPD Status Update Jan 10 21:04:48 minidsp-shd volumio[632]: info: ------------------------------ 10ms Jan 10 21:04:48 minidsp-shd volumio[632]: info: ------------------------------ 7ms Jan 10 21:04:48 minidsp-shd volumio[632]: info: ------------------------------ 5ms Jan 10 21:04:48 minidsp-shd volumio[632]: info: Executing endpoint metavolumio Jan 10 21:04:48 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 21:04:48 minidsp-shd volumio[632]: info: Executing endpoint metavolumio Jan 10 21:04:48 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 21:04:53 minidsp-shd volumio[632]: info: Jan 10 21:04:53 minidsp-shd volumio[632]: ---------------------------- MPD announces system playlist update Jan 10 21:04:53 minidsp-shd volumio[632]: info: Ignoring MPD Status Update Jan 10 21:04:53 minidsp-shd volumio[632]: info: Jan 10 21:04:53 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:53 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:53 minidsp-shd volumio[632]: info: Jan 10 21:04:53 minidsp-shd volumio[632]: ---------------------------- MPD announces system playlist update Jan 10 21:04:53 minidsp-shd volumio[632]: info: Ignoring MPD Status Update Jan 10 21:04:53 minidsp-shd volumio[632]: info: Jan 10 21:04:53 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:53 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:53 minidsp-shd volumio[632]: info: Jan 10 21:04:53 minidsp-shd volumio[632]: ---------------------------- MPD announces system playlist update Jan 10 21:04:53 minidsp-shd volumio[632]: info: Ignoring MPD Status Update Jan 10 21:04:53 minidsp-shd volumio[632]: info: Jan 10 21:04:53 minidsp-shd volumio[632]: ---------------------------- MPD announces state update: player Jan 10 21:04:53 minidsp-shd volumio[632]: info: ControllerMpd::getState Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:04:53 minidsp-shd volumio[632]: info: ------------------------------ 109ms Jan 10 21:04:53 minidsp-shd volumio[632]: info: sendMpdCommand status took 80 milliseconds Jan 10 21:04:53 minidsp-shd volumio[632]: info: ------------------------------ 70ms Jan 10 21:04:53 minidsp-shd volumio[632]: info: sendMpdCommand status took 56 milliseconds Jan 10 21:04:53 minidsp-shd volumio[632]: info: ------------------------------ 40ms Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::startPlaybackTimer Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 19 Jan 10 21:04:53 minidsp-shd volumio[632]: info: sendMpdCommand status took 84 milliseconds Jan 10 21:04:53 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 43 milliseconds Jan 10 21:04:53 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 39 milliseconds Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::parseState Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:53 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 19 Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Buena Vista Social Club","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I143371.flac","trackType":"flac"} Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: CURRENT POSITION 19 Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:53 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 19 Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Buena Vista Social Club","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I143371.flac","trackType":"flac"} Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: CURRENT POSITION 19 Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:53 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: ------------------------------ 373ms Jan 10 21:04:53 minidsp-shd volumio[632]: info: ------------------------------ 337ms Jan 10 21:04:53 minidsp-shd volumio[632]: info: sendMpdCommand playlistinfo took 201 milliseconds Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:04:53 minidsp-shd volumio[632]: info: ControllerMpd::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::servicePushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getTrack 19 Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Buena Vista Social Club","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.1.28:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I143371.flac","trackType":"flac"} Jan 10 21:04:53 minidsp-shd volumio[632]: verbose: CURRENT POSITION 19 Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::syncState stateService play Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:04:53 minidsp-shd volumio[632]: info: Received an update from plugin. extracting info from payload Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: ------------------------------ 357ms Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 21:04:53 minidsp-shd volumio[632]: info: Discovery: Getting this device information Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:53 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:58 minidsp-shd volumio[632]: info: msSurfaceDial volumioupdatevolume callback: {"vol":67,"dbVolume":-42,"mute":false,"disableVolumeControl":false} Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:58 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:04:58 minidsp-shd volumio[632]: info: msSurfaceDial volumioupdatevolume callback: {"vol":66,"dbVolume":-43,"mute":false,"disableVolumeControl":false} Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreStateMachine::pushState Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioPushState Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetQueue Jan 10 21:04:58 minidsp-shd volumio[632]: info: CoreStateMachine::getQueue Jan 10 21:04:58 minidsp-shd volumio[632]: info: CorePlayQueue::getQueue Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:05:22 minidsp-shd volumio[632]: info: Retrieving Cloud Streaming UI Jan 10 21:05:22 minidsp-shd volumio[632]: info: Getting Tidal Cloud Configuration Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:05:22 minidsp-shd volumio[632]: info: Getting Qobuz Cloud Configuration Jan 10 21:05:22 minidsp-shd volumio[632]: info: Asking plugin for UI Config Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:05:22 minidsp-shd volumio[632]: info: Getting Spotify Cloud Configuration Jan 10 21:05:22 minidsp-shd volumio[632]: info: Asking plugin for UI Config Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:05:22 minidsp-shd volumio[632]: info: Saving Spotify Acccount Jan 10 21:05:22 minidsp-shd volumio[632]: info: Got it Jan 10 21:05:22 minidsp-shd volumio[632]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 10 21:05:22 minidsp-shd volumio[632]: info: Got Tidal Cloud Configuration Jan 10 21:05:22 minidsp-shd volumio[632]: info: Got it Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 21:05:22 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 10 21:05:23 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 21:05:23 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 21:05:23 minidsp-shd volumio[632]: info: Discovery: Getting this device information Jan 10 21:05:23 minidsp-shd volumio[632]: info: CoreCommandRouter::volumioGetState Jan 10 21:05:23 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 21:05:23 minidsp-shd volumio[632]: info: Disabling MyMusic plugin bluetooth Jan 10 21:05:23 minidsp-shd sudo[21291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Jan 10 21:05:23 minidsp-shd sudo[21291]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:05:23 minidsp-shd systemd[1]: Stopping Volumio Bluetooth Module... Jan 10 21:05:23 minidsp-shd volumiobt[21293]: Killing pulse Jan 10 21:05:23 minidsp-shd volumiobt[21293]: Killing BT Pair agent Jan 10 21:05:23 minidsp-shd volumio[632]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 21:05:23 minidsp-shd systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Jan 10 21:05:23 minidsp-shd systemd[1]: volumiobt.service: Succeeded. Jan 10 21:05:23 minidsp-shd systemd[1]: Stopped Volumio Bluetooth Module. Jan 10 21:05:23 minidsp-shd sudo[21291]: pam_unix(sudo:session): session closed for user root Jan 10 21:05:23 minidsp-shd volumio[632]: info: Volumio BT Module successfully stopped Jan 10 21:05:23 minidsp-shd volumio[632]: info: Disabling plugin bluetooth Jan 10 21:05:23 minidsp-shd volumio[632]: info: Done. Jan 10 21:05:26 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 21:05:30 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 21:05:31 minidsp-shd volumio[632]: info: Enabling MyMusic plugin bluetooth Jan 10 21:05:31 minidsp-shd volumio[632]: info: Enabling plugin bluetooth Jan 10 21:05:31 minidsp-shd volumio[632]: info: Loading plugin "bluetooth"... Jan 10 21:05:31 minidsp-shd volumio[632]: info: [1736564731760] Starting BluetoothController Jan 10 21:05:31 minidsp-shd volumio[632]: info: PLUGIN START: bluetooth Jan 10 21:05:31 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 21:05:31 minidsp-shd volumio[632]: info: Done. Jan 10 21:05:31 minidsp-shd sudo[21314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Jan 10 21:05:31 minidsp-shd sudo[21314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:05:31 minidsp-shd sudo[21314]: pam_unix(sudo:session): session closed for user root Jan 10 21:05:31 minidsp-shd volumio[632]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Jan 10 21:05:31 minidsp-shd volumio[632]: Can't get device info: No such device Jan 10 21:05:34 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 21:05:34 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 10 21:05:34 minidsp-shd volumio[632]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 10 21:05:34 minidsp-shd sudo[21324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 10 21:05:34 minidsp-shd sudo[21324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:05:34 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Jan 10 21:05:35 minidsp-shd sudo[21324]: pam_unix(sudo:session): session closed for user root Jan 10 21:05:35 minidsp-shd sudo[21328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 10 21:05:35 minidsp-shd sudo[21328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:05:35 minidsp-shd volumiobt[21326]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 10 21:05:35 minidsp-shd sudo[21328]: pam_unix(sudo:session): session closed for user root Jan 10 21:05:35 minidsp-shd volumio[632]: info: Volumio BT Module successfully started Jan 10 21:05:35 minidsp-shd volumio[632]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 21:05:35 minidsp-shd volumio[632]: Error: getaddrinfo -3007 Jan 10 21:05:35 minidsp-shd volumio[632]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jan 10 21:05:35 minidsp-shd volumio[632]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jan 10 21:05:35 minidsp-shd volumio[632]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jan 10 21:05:35 minidsp-shd volumio[632]: code: -3007, Jan 10 21:05:35 minidsp-shd volumio[632]: errno: -3007, Jan 10 21:05:35 minidsp-shd volumio[632]: syscall: 'getaddrinfo' Jan 10 21:05:35 minidsp-shd volumio[632]: } Jan 10 21:05:35 minidsp-shd volumio[632]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 21:05:36 minidsp-shd sudo[21343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 10 21:05:36 minidsp-shd sudo[21343]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:05:36 minidsp-shd volumiobt[21326]: pulseaudio: no process found Jan 10 21:05:36 minidsp-shd sudo[21343]: pam_unix(sudo:session): session closed for user root Jan 10 21:05:36 minidsp-shd sudo[21349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-10 21:04 Jan 10 21:05:36 minidsp-shd sudo[21349]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"