-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Wed 2025-01-15 16:12:42 +07. -- Jan 15 16:11:03 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:09 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:15 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:21 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:27 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:28 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 15 16:11:28 volumio volumio[980]: info: Preload queue cleared Jan 15 16:11:30 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 15 16:11:30 volumio volumio[980]: info: Preload queue cleared Jan 15 16:11:34 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:39 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 15 16:11:39 volumio volumio[980]: info: Preload queue cleared Jan 15 16:11:41 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:47 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:53 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 15 16:11:55 volumio volumio[980]: info: Getting Alsa Cards List without I2S DAC Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 15 16:11:55 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 15 16:11:59 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:12:05 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 16:12:07 volumio volumio[980]: info: Retrieving Cloud Streaming UI Jan 15 16:12:07 volumio volumio[980]: info: Getting Tidal Cloud Configuration Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 16:12:07 volumio volumio[980]: info: Getting Qobuz Cloud Configuration Jan 15 16:12:07 volumio volumio[980]: info: Asking plugin for UI Config Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 16:12:07 volumio volumio[980]: info: Getting Spotify Cloud Configuration Jan 15 16:12:07 volumio volumio[980]: info: Asking plugin for UI Config Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 16:12:07 volumio volumio[980]: info: Saving Spotify Acccount Jan 15 16:12:07 volumio volumio[980]: info: Got Tidal Cloud Configuration Jan 15 16:12:07 volumio volumio[980]: info: Got it Jan 15 16:12:07 volumio volumio[980]: info: Got it Jan 15 16:12:07 volumio volumio[980]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 16:12:07 volumio volumio[980]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::volumioGetBrowseSources Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::volumioGetBrowseSources Jan 15 16:12:07 volumio volumio[980]: info: CoreCommandRouter::volumioGetBrowseSources Jan 15 16:12:08 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:08 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 15 16:12:11 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:12:12 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand update Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: Jan 15 16:12:15 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand update took 9 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 15 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 13 milliseconds Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:15 volumio volumio[980]: info: Command Router : Notfying DB Updatetrue Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: Jan 15 16:12:15 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:15 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: Jan 15 16:12:15 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:15 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: Jan 15 16:12:15 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:15 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 11 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 10 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 9 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 9 milliseconds Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:15 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:15 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:15 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:15 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:15 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:15 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:15 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:15 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:15 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:15 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:15 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:15 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:15 volumio volumio[980]: info: No code Jan 15 16:12:15 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:15 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:15 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:15 volumio volumio[980]: info: ------------------------------ 342ms Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: Jan 15 16:12:15 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:15 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: Jan 15 16:12:15 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:15 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:15 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:15 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:15 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:15 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:15 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:15 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 272 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 270 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand playlistinfo took 195 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand playlistinfo took 169 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 54 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 53 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 52 milliseconds Jan 15 16:12:15 volumio volumio[980]: info: sendMpdCommand status took 49 milliseconds Jan 15 16:12:15 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:16 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:16 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:16 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:16 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:16 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: No code Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:16 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: No code Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: ------------------------------ 633ms Jan 15 16:12:16 volumio volumio[980]: info: ------------------------------ 630ms Jan 15 16:12:16 volumio volumio[980]: info: sendMpdCommand playlistinfo took 268 milliseconds Jan 15 16:12:16 volumio volumio[980]: info: sendMpdCommand playlistinfo took 190 milliseconds Jan 15 16:12:16 volumio volumio[980]: info: sendMpdCommand playlistinfo took 141 milliseconds Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:16 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:16 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:16 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: No code Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:16 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: No code Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:16 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: No code Jan 15 16:12:16 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:16 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:16 volumio volumio[980]: info: ------------------------------ 801ms Jan 15 16:12:16 volumio volumio[980]: info: ------------------------------ 581ms Jan 15 16:12:16 volumio volumio[980]: info: ------------------------------ 577ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: undefined Jan 15 16:12:16 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 15 16:12:16 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:17 volumio volumio[980]: info: [ASDebug] Togle GPIO: OFF Jan 15 16:12:17 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:12:20 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:23 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:12:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:28 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:29 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:12:32 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:33 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand rescan Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:33 volumio volumio[980]: info: Jan 15 16:12:33 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:33 volumio volumio[980]: info: sendMpdCommand rescan took 8 milliseconds Jan 15 16:12:33 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:33 volumio volumio[980]: info: Jan 15 16:12:33 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:33 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:33 volumio volumio[980]: info: Jan 15 16:12:33 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:33 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:33 volumio volumio[980]: info: Jan 15 16:12:33 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:33 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:33 volumio volumio[980]: info: sendMpdCommand status took 13 milliseconds Jan 15 16:12:33 volumio volumio[980]: info: sendMpdCommand status took 11 milliseconds Jan 15 16:12:33 volumio volumio[980]: info: sendMpdCommand status took 10 milliseconds Jan 15 16:12:33 volumio volumio[980]: info: sendMpdCommand status took 9 milliseconds Jan 15 16:12:33 volumio volumio[980]: info: sendMpdCommand status took 8 milliseconds Jan 15 16:12:33 volumio volumio[980]: info: sendMpdCommand status took 6 milliseconds Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:33 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:33 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:33 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:33 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:33 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:34 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:34 volumio volumio[980]: info: Jan 15 16:12:34 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:34 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:34 volumio volumio[980]: info: Jan 15 16:12:34 volumio volumio[980]: ---------------------------- MPD announces state update: update Jan 15 16:12:34 volumio volumio[980]: info: ControllerMpd::getState Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand status took 157 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand status took 156 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand playlistinfo took 113 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand playlistinfo took 72 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand playlistinfo took 45 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand status took 13 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand status took 10 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand status took 10 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand status took 8 milliseconds Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:34 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:34 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:34 volumio volumio[980]: info: Command Router : Notfying DB Updatefalse Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseState Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 15 16:12:34 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:34 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: No code Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:34 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: No code Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:34 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: No code Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: ------------------------------ 482ms Jan 15 16:12:34 volumio volumio[980]: info: ------------------------------ 480ms Jan 15 16:12:34 volumio volumio[980]: info: ------------------------------ 478ms Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand playlistinfo took 306 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand playlistinfo took 275 milliseconds Jan 15 16:12:34 volumio volumio[980]: info: sendMpdCommand playlistinfo took 237 milliseconds Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:34 volumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Jan 15 16:12:34 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:34 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: No code Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:34 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: No code Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: ControllerMpd::pushState Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::servicePushState Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","artist":null,"album":null,"uri":"http://192.168.0.167:10243/WMPNSSv4/3198049708/0_MTRfZmZjM2U0NTlfOWZmNDc3MF83OWQ0OTMyNV8yYWJlMGZjNV9jMTY5ZDJiMS0xMjM4MQ","trackType":""} Jan 15 16:12:34 volumio volumio[980]: verbose: CURRENT POSITION 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState stateService stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::syncState currentStatus stop Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: No code Jan 15 16:12:34 volumio volumio[980]: info: CoreStateMachine::pushState Jan 15 16:12:34 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:34 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Jan 15 16:12:34 volumio volumio[980]: info: ------------------------------ 665ms Jan 15 16:12:34 volumio volumio[980]: info: ------------------------------ 519ms Jan 15 16:12:34 volumio volumio[980]: info: ------------------------------ 516ms Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:34 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: stop Jan 15 16:12:35 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:12:36 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:40 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 16:12:41 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 15 16:12:41 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 15 16:12:41 volumio volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 16:12:41 volumio volumio[980]: Error: getaddrinfo -3007 Jan 15 16:12:41 volumio volumio[980]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jan 15 16:12:41 volumio volumio[980]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jan 15 16:12:41 volumio volumio[980]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jan 15 16:12:41 volumio volumio[980]: code: -3007, Jan 15 16:12:41 volumio volumio[980]: errno: -3007, Jan 15 16:12:41 volumio volumio[980]: syscall: 'getaddrinfo' Jan 15 16:12:41 volumio volumio[980]: } Jan 15 16:12:41 volumio volumio[980]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 16:12:41 volumio wpa_supplicant[856]: RRM: Ignoring radio measurement request: Not RRM network Jan 15 16:12:42 volumio sudo[2672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-15 16:11 Jan 15 16:12:42 volumio sudo[2672]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"