-- Logs begin at Sat 2025-01-11 20:14:36 CET, end at Sat 2025-01-11 20:36:14 CET. -- Jan 11 20:35:05 cocirivo volumio[3128]: info: Clearing queue after UPNP request Jan 11 20:35:05 cocirivo volumio[3128]: info: CoreStateMachine::ClearQueue Jan 11 20:35:05 cocirivo volumio[3128]: info: CoreStateMachine::stop Jan 11 20:35:05 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:35:05 cocirivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Jan 11 20:35:05 cocirivo volumio[3128]: info: CorePlayQueue::saveQueue Jan 11 20:35:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Jan 11 20:35:05 cocirivo volumio[3128]: info: Jan 11 20:35:05 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:05 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:05 cocirivo volumio[3128]: info: Jan 11 20:35:05 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:05 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:05 cocirivo volumio[3128]: info: Jan 11 20:35:05 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:05 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:05 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:05 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:05 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:05 cocirivo volumio[3128]: info: ------------------------------ 11ms Jan 11 20:35:05 cocirivo volumio[3128]: info: ------------------------------ 10ms Jan 11 20:35:05 cocirivo volumio[3128]: info: ------------------------------ 8ms Jan 11 20:35:05 cocirivo volumio[3128]: info: Starting UPNP Playback Jan 11 20:35:05 cocirivo volumio[3128]: info: Preparing playback through UPNP Jan 11 20:35:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetState Jan 11 20:35:05 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:05 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 11ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 10ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 9ms Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 8ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 7ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 10ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 8ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: Jan 11 20:35:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:06 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:35:07 cocirivo volumio[3128]: info: Jan 11 20:35:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:07 cocirivo volumio[3128]: info: Jan 11 20:35:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:07 cocirivo volumio[3128]: info: Jan 11 20:35:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:07 cocirivo volumio[3128]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:35:07 cocirivo volumio[3128]: info: sendMpdCommand status took 3 milliseconds Jan 11 20:35:07 cocirivo volumio[3128]: info: sendMpdCommand status took 2 milliseconds Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 20:35:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 20:35:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:07 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: No code Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:07 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: No code Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:07 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: No code Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:07 cocirivo volumio[3128]: info: ------------------------------ 89ms Jan 11 20:35:07 cocirivo volumio[3128]: info: ------------------------------ 88ms Jan 11 20:35:07 cocirivo volumio[3128]: info: ------------------------------ 87ms Jan 11 20:35:09 cocirivo volumio[3128]: info: Clearing queue after UPNP request Jan 11 20:35:09 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:09 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:09 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreStateMachine::ClearQueue Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreStateMachine::stop Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:35:09 cocirivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Jan 11 20:35:09 cocirivo volumio[3128]: info: CorePlayQueue::saveQueue Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Jan 11 20:35:09 cocirivo volumio[3128]: info: Jan 11 20:35:09 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:09 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:09 cocirivo volumio[3128]: info: Jan 11 20:35:09 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:09 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:09 cocirivo volumio[3128]: info: Jan 11 20:35:09 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:09 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:09 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:09 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:09 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:09 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:09 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:09 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:09 cocirivo volumio[3128]: info: Starting UPNP Playback Jan 11 20:35:09 cocirivo volumio[3128]: info: Preparing playback through UPNP Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetState Jan 11 20:35:09 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:09 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 8ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 7ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:10 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:10 cocirivo kernel: aml_spdif_open Jan 11 20:35:10 cocirivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:10 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:35:10 cocirivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 11 20:35:10 cocirivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 11 20:35:10 cocirivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 11 20:35:10 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:35:10 cocirivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 11 20:35:10 cocirivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 11 20:35:10 cocirivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 Jan 11 20:35:10 cocirivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand status took 7 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:10 cocirivo volumio[3128]: info: Jan 11 20:35:10 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand status took 10 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand status took 8 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:10 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:10 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:10 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:10 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:10 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 32ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 42ms Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 28 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 26 milliseconds Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:10 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:10 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:10 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:10 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:10 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:10 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:10 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:10 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:10 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:10 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:10 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:10 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:10 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:10 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 91ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 90ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 86ms Jan 11 20:35:10 cocirivo volumio[3128]: info: ------------------------------ 86ms Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:10 cocirivo volumio[3128]: info: Signalling Playback active due to playback status change Jan 11 20:35:10 cocirivo volumio[3128]: info: MCU Signalled Playback Active Jan 11 20:35:11 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 11 20:35:11 cocirivo kernel: spdif_a keep clk continuous Jan 11 20:35:11 cocirivo kernel: aml_spdif_close Jan 11 20:35:11 cocirivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 11 20:35:11 cocirivo volumio[3128]: info: Jan 11 20:35:11 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:11 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:11 cocirivo volumio[3128]: info: Jan 11 20:35:11 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:11 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:11 cocirivo volumio[3128]: info: Jan 11 20:35:11 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:11 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:11 cocirivo volumio[3128]: info: sendMpdCommand status took 6 milliseconds Jan 11 20:35:11 cocirivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:35:11 cocirivo volumio[3128]: info: sendMpdCommand status took 3 milliseconds Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:11 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 20:35:11 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 20:35:11 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:11 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:11 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:11 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:11 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:35:11 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:11 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:11 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:11 cocirivo volumio[3128]: info: No code Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:11 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:11 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:11 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:11 cocirivo volumio[3128]: info: No code Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:11 cocirivo volumio[3128]: info: ------------------------------ 69ms Jan 11 20:35:11 cocirivo volumio[3128]: info: ------------------------------ 72ms Jan 11 20:35:11 cocirivo volumio[3128]: info: ------------------------------ 72ms Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:11 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:11 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:11 cocirivo volumio[3128]: info: MCU Signalled Playback Inactive Jan 11 20:35:13 cocirivo volumio[3128]: info: Clearing queue after UPNP request Jan 11 20:35:13 cocirivo volumio[3128]: info: CoreStateMachine::ClearQueue Jan 11 20:35:13 cocirivo volumio[3128]: info: CoreStateMachine::stop Jan 11 20:35:13 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:35:13 cocirivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Jan 11 20:35:13 cocirivo volumio[3128]: info: CorePlayQueue::saveQueue Jan 11 20:35:13 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:13 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Jan 11 20:35:13 cocirivo volumio[3128]: info: Jan 11 20:35:13 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:13 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:13 cocirivo volumio[3128]: info: Jan 11 20:35:13 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:13 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:13 cocirivo volumio[3128]: info: Jan 11 20:35:13 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:13 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:13 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:13 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:13 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:13 cocirivo volumio[3128]: info: ------------------------------ 8ms Jan 11 20:35:13 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:13 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:13 cocirivo volumio[3128]: info: Starting UPNP Playback Jan 11 20:35:13 cocirivo volumio[3128]: info: Preparing playback through UPNP Jan 11 20:35:13 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetState Jan 11 20:35:13 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:13 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 8ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 7ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 8ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:14 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:14 cocirivo kernel: aml_spdif_open Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:14 cocirivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 11 20:35:14 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:35:14 cocirivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 11 20:35:14 cocirivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 11 20:35:14 cocirivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 11 20:35:14 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:35:14 cocirivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 11 20:35:14 cocirivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 11 20:35:14 cocirivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 Jan 11 20:35:14 cocirivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand status took 10 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand status took 8 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand status took 10 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:14 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:14 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:14 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:14 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:14 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 31ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 36ms Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:14 cocirivo volumio[3128]: info: Jan 11 20:35:14 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 25 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 26 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand status took 3 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:14 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:14 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:14 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:14 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:14 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:14 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 78ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 73ms Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: Signalling Playback active due to playback status change Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 72 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 71 milliseconds Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:14 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:14 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:14 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:14 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:14 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:14 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:14 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:14 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:14 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 114ms Jan 11 20:35:14 cocirivo volumio[3128]: info: ------------------------------ 114ms Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:14 cocirivo volumio[3128]: info: MCU Signalled Playback Active Jan 11 20:35:15 cocirivo volumio[3128]: info: Jan 11 20:35:15 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:15 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 11 20:35:15 cocirivo kernel: spdif_a keep clk continuous Jan 11 20:35:15 cocirivo kernel: aml_spdif_close Jan 11 20:35:15 cocirivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 11 20:35:15 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:15 cocirivo volumio[3128]: info: Jan 11 20:35:15 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:15 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:15 cocirivo volumio[3128]: info: Jan 11 20:35:15 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:15 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:15 cocirivo volumio[3128]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:15 cocirivo volumio[3128]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:35:15 cocirivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:35:15 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:15 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:15 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:15 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:35:15 cocirivo volumio[3128]: info: ------------------------------ 28ms Jan 11 20:35:15 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 20:35:15 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:15 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:15 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:15 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:15 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:15 cocirivo volumio[3128]: info: No code Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:15 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:15 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:15 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:15 cocirivo volumio[3128]: info: No code Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:15 cocirivo volumio[3128]: info: ------------------------------ 60ms Jan 11 20:35:15 cocirivo volumio[3128]: info: ------------------------------ 60ms Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:15 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:15 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:15 cocirivo volumio[3128]: info: MCU Signalled Playback Inactive Jan 11 20:35:18 cocirivo volumio[3128]: info: Clearing queue after UPNP request Jan 11 20:35:18 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:18 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:18 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreStateMachine::ClearQueue Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreStateMachine::stop Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:35:18 cocirivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Jan 11 20:35:18 cocirivo volumio[3128]: info: CorePlayQueue::saveQueue Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Jan 11 20:35:18 cocirivo volumio[3128]: info: Jan 11 20:35:18 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:18 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:18 cocirivo volumio[3128]: info: Jan 11 20:35:18 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:18 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:18 cocirivo volumio[3128]: info: Jan 11 20:35:18 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:18 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:18 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:18 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:18 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:18 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:18 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:18 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:18 cocirivo volumio[3128]: info: Starting UPNP Playback Jan 11 20:35:18 cocirivo volumio[3128]: info: Preparing playback through UPNP Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetState Jan 11 20:35:18 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:18 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 20:35:19 cocirivo volumio[3128]: info: Discovery: Getting this device information Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:19 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 8ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:19 cocirivo kernel: aml_spdif_open Jan 11 20:35:19 cocirivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 11 20:35:19 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:35:19 cocirivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 11 20:35:19 cocirivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 11 20:35:19 cocirivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 11 20:35:19 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:35:19 cocirivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 11 20:35:19 cocirivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 11 20:35:19 cocirivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 Jan 11 20:35:19 cocirivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 10 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 9 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 6 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 12 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 11 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:19 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:19 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:19 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:19 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:19 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 44ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 54ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 51ms Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 40 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 36 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 37 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:19 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:19 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:19 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:19 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 87ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 88ms Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: Signalling Playback active due to playback status change Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 79 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 11 20:35:19 cocirivo kernel: spdif_a keep clk continuous Jan 11 20:35:19 cocirivo kernel: aml_spdif_close Jan 11 20:35:19 cocirivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:19 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:19 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 138ms Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: Jan 11 20:35:19 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 14 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 12 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand status took 11 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:19 cocirivo volumio[3128]: 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":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:19 cocirivo volumio[3128]: 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":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: No code Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:19 cocirivo volumio[3128]: 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":"L'Incoronazione di Poppea, SV 308: Pur ti miro","artist":"Guillemette Laurens","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/5D590BA0557F60C82A10B419C5042B58.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:19 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: No code Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 84ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 84ms Jan 11 20:35:19 cocirivo volumio[3128]: info: ------------------------------ 83ms Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:19 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:20 cocirivo volumio[3128]: info: MCU Signalled Playback Active Jan 11 20:35:20 cocirivo volumio[3128]: info: MCU Signalled Playback Inactive Jan 11 20:35:21 cocirivo volumio[3128]: info: Clearing queue after UPNP request Jan 11 20:35:22 cocirivo volumio[3128]: info: CoreStateMachine::ClearQueue Jan 11 20:35:22 cocirivo volumio[3128]: info: CoreStateMachine::stop Jan 11 20:35:22 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:35:22 cocirivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Jan 11 20:35:22 cocirivo volumio[3128]: info: CorePlayQueue::saveQueue Jan 11 20:35:22 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:22 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:22 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:22 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:22 cocirivo volumio[3128]: info: Starting UPNP Playback Jan 11 20:35:22 cocirivo volumio[3128]: info: Preparing playback through UPNP Jan 11 20:35:22 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetState Jan 11 20:35:22 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:35:22 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 10ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: Jan 11 20:35:22 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:35:22 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:35:22 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:35:23 cocirivo volumio[3128]: info: Jan 11 20:35:23 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:23 cocirivo volumio[3128]: info: Jan 11 20:35:23 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:23 cocirivo kernel: aml_spdif_open Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:23 cocirivo volumio[3128]: info: Jan 11 20:35:23 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:23 cocirivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 11 20:35:23 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:35:23 cocirivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 11 20:35:23 cocirivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 11 20:35:23 cocirivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 11 20:35:23 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:35:23 cocirivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 11 20:35:23 cocirivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 11 20:35:23 cocirivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 Jan 11 20:35:23 cocirivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jan 11 20:35:23 cocirivo volumio[3128]: info: Jan 11 20:35:23 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand status took 10 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand status took 9 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand status took 8 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:23 cocirivo volumio[3128]: info: Jan 11 20:35:23 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:23 cocirivo volumio[3128]: info: Jan 11 20:35:23 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand status took 3 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:23 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:23 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:23 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:23 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:23 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:23 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:23 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:23 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:23 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:23 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:23 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: ------------------------------ 61ms Jan 11 20:35:23 cocirivo volumio[3128]: info: ------------------------------ 73ms Jan 11 20:35:23 cocirivo volumio[3128]: info: ------------------------------ 72ms Jan 11 20:35:23 cocirivo volumio[3128]: info: ------------------------------ 67ms Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 45 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 44 milliseconds Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:23 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:23 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:23 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:23 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:35:23 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:35:23 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:35:23 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:35:23 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:35:23 cocirivo volumio[3128]: info: ------------------------------ 86ms Jan 11 20:35:23 cocirivo volumio[3128]: info: ------------------------------ 84ms Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:35:23 cocirivo volumio[3128]: info: Signalling Playback active due to playback status change Jan 11 20:35:23 cocirivo volumio[3128]: info: MCU Signalled Playback Active Jan 11 20:35:23 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 11 20:35:25 cocirivo volumio[3128]: error: Failed request for metavolumio API Jan 11 20:35:25 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:25 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:25 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:25 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:25 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:35:25 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:35:49 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 20:35:49 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 20:35:49 cocirivo volumio[3128]: info: Discovery: Getting this device information Jan 11 20:35:49 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetState Jan 11 20:35:49 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 20:36:04 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 11 20:36:04 cocirivo kernel: spdif_a keep clk continuous Jan 11 20:36:04 cocirivo kernel: aml_spdif_close Jan 11 20:36:04 cocirivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 11 20:36:04 cocirivo volumio[3128]: info: Jan 11 20:36:04 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:04 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:04 cocirivo volumio[3128]: info: Jan 11 20:36:04 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:04 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:04 cocirivo volumio[3128]: info: Jan 11 20:36:04 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:04 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:04 cocirivo volumio[3128]: info: sendMpdCommand status took 6 milliseconds Jan 11 20:36:04 cocirivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:36:04 cocirivo volumio[3128]: info: sendMpdCommand status took 3 milliseconds Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:04 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 20:36:04 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 20:36:04 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:04 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:04 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:04 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:04 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:04 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:04 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:36:04 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:04 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Jan 11 20:36:05 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:36:05 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:05 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:05 cocirivo volumio[3128]: info: No code Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:05 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:36:05 cocirivo volumio[3128]: 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":"A Trace of Grace","artist":"Michel Godard","album":"Monteverdi - A Trace of Grace","uri":"http://192.168.178.110:57645/proxy/tidal/BB8277628397A9B46B75F951F061AE87.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:05 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:05 cocirivo volumio[3128]: info: No code Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:05 cocirivo volumio[3128]: info: ------------------------------ 64ms Jan 11 20:36:05 cocirivo volumio[3128]: info: ------------------------------ 62ms Jan 11 20:36:05 cocirivo volumio[3128]: info: ------------------------------ 61ms Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:05 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:05 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:05 cocirivo volumio[3128]: info: MCU Signalled Playback Inactive Jan 11 20:36:06 cocirivo volumio[3128]: info: Clearing queue after UPNP request Jan 11 20:36:06 cocirivo volumio[3128]: info: CoreStateMachine::ClearQueue Jan 11 20:36:06 cocirivo volumio[3128]: info: CoreStateMachine::stop Jan 11 20:36:06 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 20:36:06 cocirivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Jan 11 20:36:06 cocirivo volumio[3128]: info: CorePlayQueue::saveQueue Jan 11 20:36:06 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:06 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:36:06 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:36:06 cocirivo volumio[3128]: error: updateQueue error: null Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 6ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:36:06 cocirivo volumio[3128]: info: Starting UPNP Playback Jan 11 20:36:06 cocirivo volumio[3128]: info: Preparing playback through UPNP Jan 11 20:36:06 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetState Jan 11 20:36:06 cocirivo volumio[3128]: info: CorePlayQueue::getTrack 0 Jan 11 20:36:06 cocirivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 5ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 3ms Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: Jan 11 20:36:06 cocirivo volumio[3128]: ---------------------------- MPD announces system playlist update Jan 11 20:36:06 cocirivo volumio[3128]: info: Ignoring MPD Status Update Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 4ms Jan 11 20:36:06 cocirivo volumio[3128]: info: ------------------------------ 2ms Jan 11 20:36:07 cocirivo volumio[3128]: info: Jan 11 20:36:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:07 cocirivo kernel: aml_spdif_open Jan 11 20:36:07 cocirivo volumio[3128]: info: Jan 11 20:36:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:07 cocirivo volumio[3128]: info: Jan 11 20:36:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:07 cocirivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 11 20:36:07 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:36:07 cocirivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 11 20:36:07 cocirivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 11 20:36:07 cocirivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 11 20:36:07 cocirivo kernel: set normal 512 fs /4 fs Jan 11 20:36:07 cocirivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 11 20:36:07 cocirivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 11 20:36:07 cocirivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 11 20:36:07 cocirivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand status took 10 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:07 cocirivo volumio[3128]: info: Jan 11 20:36:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:07 cocirivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand status took 12 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand status took 11 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:36:07 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Four Days in November","artist":"Abisko Lights","album":"Point of View","uri":"http://192.168.178.110:57645/proxy/tidal/4B23F3F5B680809F122929CFE0BFCFDC.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Jan 11 20:36:07 cocirivo volumio[3128]: info: ------------------------------ 26ms Jan 11 20:36:07 cocirivo volumio[3128]: info: Jan 11 20:36:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:07 cocirivo volumio[3128]: info: Jan 11 20:36:07 cocirivo volumio[3128]: ---------------------------- MPD announces state update: player Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::getState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand status took 18 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:36:07 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Four Days in November","artist":"Abisko Lights","album":"Point of View","uri":"http://192.168.178.110:57645/proxy/tidal/4B23F3F5B680809F122929CFE0BFCFDC.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:36:07 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:36:07 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Four Days in November","artist":"Abisko Lights","album":"Point of View","uri":"http://192.168.178.110:57645/proxy/tidal/4B23F3F5B680809F122929CFE0BFCFDC.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:36:07 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: ------------------------------ 80ms Jan 11 20:36:07 cocirivo volumio[3128]: info: ------------------------------ 79ms Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 50 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 50 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: info: sendMpdCommand playlistinfo took 50 milliseconds Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:07 cocirivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:36:07 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":402,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"301 Kbps","isStreaming":false,"title":"Four Days in November","artist":"Abisko Lights","album":"Point of View","uri":"http://192.168.178.110:57645/proxy/tidal/4B23F3F5B680809F122929CFE0BFCFDC.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:36:07 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:36:07 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":402,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"375 Kbps","isStreaming":false,"title":"Four Days in November","artist":"Abisko Lights","album":"Point of View","uri":"http://192.168.178.110:57645/proxy/tidal/4B23F3F5B680809F122929CFE0BFCFDC.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:36:07 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: ControllerMpd::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::servicePushState Jan 11 20:36:07 cocirivo volumio[3128]: verbose: In UPNP mode Jan 11 20:36:07 cocirivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":402,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"300 Kbps","isStreaming":false,"title":"Four Days in November","artist":"Abisko Lights","album":"Point of View","uri":"http://192.168.178.110:57645/proxy/tidal/4B23F3F5B680809F122929CFE0BFCFDC.flac?proxy=true","trackType":"tidal"} Jan 11 20:36:07 cocirivo volumio[3128]: verbose: CURRENT POSITION 0 Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState stateService play Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Jan 11 20:36:07 cocirivo volumio[3128]: info: Received an update from plugin. extracting info from payload Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::pushState Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioPushState Jan 11 20:36:07 cocirivo volumio[3128]: info: ------------------------------ 127ms Jan 11 20:36:07 cocirivo volumio[3128]: info: ------------------------------ 115ms Jan 11 20:36:07 cocirivo volumio[3128]: info: ------------------------------ 113ms Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CoreStateMachine::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: CorePlayQueue::getQueue Jan 11 20:36:07 cocirivo volumio[3128]: info: Signalling Playback active due to playback status change Jan 11 20:36:07 cocirivo volumio[3128]: info: MCU Signalled Playback Active Jan 11 20:36:10 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:36:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:36:10 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:36:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:36:10 cocirivo volumio[3128]: info: Executing endpoint metavolumio Jan 11 20:36:10 cocirivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 20:36:13 cocirivo volumio[3128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 20:36:13 cocirivo volumio[3128]: Error: connect ETIMEDOUT 151.101.242.79:443 Jan 11 20:36:13 cocirivo volumio[3128]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 11 20:36:13 cocirivo volumio[3128]: errno: -110, Jan 11 20:36:13 cocirivo volumio[3128]: code: 'ETIMEDOUT', Jan 11 20:36:13 cocirivo volumio[3128]: syscall: 'connect', Jan 11 20:36:13 cocirivo volumio[3128]: address: '151.101.242.79', Jan 11 20:36:13 cocirivo volumio[3128]: port: 443 Jan 11 20:36:13 cocirivo volumio[3128]: } Jan 11 20:36:13 cocirivo volumio[3128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 20:36:14 cocirivo sudo[7788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 20:35 Jan 11 20:36:14 cocirivo sudo[7788]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"