-- Logs begin at Thu 2024-09-26 13:17:06 CEST, end at Tue 2024-10-01 13:19:55 CEST. -- Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPlay Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::play index undefined Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::startPlaybackTimer Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: [1727781486741] ControllerTidal::clearAddPlayTrack Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Getting stream with soundQuality LOSSLESS Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand stop Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand stop took 3 milliseconds Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand clear Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand clear took 1 milliseconds Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS" Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces system playlist update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Ignoring MPD Status Update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces system playlist update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Ignoring MPD Status Update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS" took 6 milliseconds Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand play Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces system playlist update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Ignoring MPD Status Update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces system playlist update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Ignoring MPD Status Update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: error: Upnp client error: Error: This socket has been ended by the other party Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 18ms Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 17ms Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand play took 14 milliseconds Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 12ms Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 11ms Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces system playlist update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Ignoring MPD Status Update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces system playlist update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Ignoring MPD Status Update Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 6ms Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 4ms Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: STREAMING PROXY: Handling url /?data=tidal://song/49651324&soundQuality=LOSSLESS Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: Executing endpoint getStreamUrltidal Oct 01 13:18:06 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: getStreamUrl took 511 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidjODEyNjRiNjg0MTIxZjA5NmRmNWI1M2VmYjY0ZjFkMV82MS5tcDQ/0.flac?Expires=1727784917&Signature=U2kMiaeH3uO4VgIL~9O4B3yKUMfAo7gIv4-7kGsIPRihLWSTC6QlBcasTWXXUmx-qxUmUIRUwHUnPBVXF8oFXoEt9PECFCtnXUCItIjLeApxrnJhV4A9jySXI8K96EAavXfgfeB7iHFPQerW0avnKjh-l~PnUgYc980ZYgj5occVelQbT2hg9AKQExhlD~fGefxnT63DG7KiJ49~yQUKuWME3yBaLDAcXuoKnGpZYNmqzcedxxWBwJk3-Wwo5MIExfBgiKoRzxmdWQYi6ZgLWP9qQQemS7mdtdqPwyAUsyvLWOYB-kkw23DQknZrjwST5yRLnY67bQHf0O-X66brQw__&Key-Pair-Id=K14LZCZ9QUI4JL Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: STREAMING PROXY: Response: 200, length: 18904857 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: aml_spdif_open Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: set normal 512 fs /4 fs Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: set spdifout clk:5644800, mpll:22579200 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: get spdifout clk:5644797, mpll:22579186 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: set normal 512 fs /4 fs Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: set spdifout clk:5644800, mpll:22579200 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: get spdifout clk:5644797, mpll:22579186 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 22 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 29 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 27 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 12 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus stop Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 61ms Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 9 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Received an update from plugin. extracting info from payload Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Received an update from plugin. extracting info from payload Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"804 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Received an update from plugin. extracting info from payload Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:07 tom-juste-nielsen-rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 303ms Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 302ms Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 289ms Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 247 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 238 milliseconds Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus play Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Received an update from plugin. extracting info from payload Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 336ms Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:07 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 174 milliseconds Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus play Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: Received an update from plugin. extracting info from payload Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 496ms Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: STREAMING PROXY: Client dropped request, destroying Oct 01 13:18:08 tom-juste-nielsen-rivo volumio[3515]: info: MCU Signalled Playback Active Oct 01 13:18:10 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 13:18:10 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 13:18:10 tom-juste-nielsen-rivo volumio[3515]: info: Discovery: Getting this device information Oct 01 13:18:10 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioGetState Oct 01 13:18:10 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 13:18:14 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 01 13:18:14 tom-juste-nielsen-rivo volumio[3515]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 01 13:18:14 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 01 13:18:15 tom-juste-nielsen-rivo volumio[3515]: info: Received Get System Version Oct 01 13:18:15 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 01 13:18:15 tom-juste-nielsen-rivo volumio[3515]: info: Received Get System Info Oct 01 13:18:15 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 13:18:15 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 13:18:15 tom-juste-nielsen-rivo volumio[3515]: info: Discovery: Getting this device information Oct 01 13:18:15 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioGetState Oct 01 13:18:15 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 13:18:39 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 13:18:39 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 13:18:39 tom-juste-nielsen-rivo volumio[3515]: info: Discovery: Getting this device information Oct 01 13:18:39 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioGetState Oct 01 13:18:39 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:49 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:50 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:51 tom-juste-nielsen-rivo volumio[3515]: info: VolumeController::SetAlsaVolume- Oct 01 13:18:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:18:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:18:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:18:51 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:18:51 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:18:51 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:18:55 tom-juste-nielsen-rivo volumiologrotate[2818]: ls: cannot access '/var/log/samba/log.wb-TOM': No such file or directory Oct 01 13:18:55 tom-juste-nielsen-rivo volumiologrotate[2818]: ls: cannot access 'JUSTE': No such file or directory Oct 01 13:18:55 tom-juste-nielsen-rivo volumiologrotate[2818]: ls: cannot access 'NIELSEN': No such file or directory Oct 01 13:18:55 tom-juste-nielsen-rivo volumiologrotate[2818]: ls: cannot access 'RIVO': No such file or directory Oct 01 13:19:09 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 13:19:09 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 13:19:09 tom-juste-nielsen-rivo volumio[3515]: info: Discovery: Getting this device information Oct 01 13:19:09 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioGetState Oct 01 13:19:09 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 13:19:39 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 13:19:39 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 13:19:39 tom-juste-nielsen-rivo volumio[3515]: info: Discovery: Getting this device information Oct 01 13:19:39 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioGetState Oct 01 13:19:39 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: Pausing Remote Device: http://192.168.0.41 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- Client requests Volumio pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::stPlaybackTimer Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::servicePause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: [1727781591191] ControllerTidal::pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand pause Oct 01 13:19:51 tom-juste-nielsen-rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Oct 01 13:19:51 tom-juste-nielsen-rivo kernel: spdif_a keep clk continuous Oct 01 13:19:51 tom-juste-nielsen-rivo kernel: aml_spdif_close Oct 01 13:19:51 tom-juste-nielsen-rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand pause took 2 milliseconds Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: Done pausing: Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 11 milliseconds Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 9 milliseconds Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 8 milliseconds Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105087,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::stPlaybackTimer Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105087,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::stPlaybackTimer Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105087,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus pause Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::stPlaybackTimer Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 109ms Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 107ms Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 105ms Oct 01 13:19:51 tom-juste-nielsen-rivo volumio[3515]: info: MCU Signalled Playback Inactive Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Playing Remote Device: http://192.168.0.41 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- Client requests Volumio play Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPlay Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::play index undefined Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::startPlaybackTimer Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: [1727781592668] ControllerTidal::resume Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::resume Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand play Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: aml_spdif_open Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: set normal 512 fs /4 fs Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: set spdifout clk:5644800, mpll:22579200 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: get spdifout clk:5644797, mpll:22579186 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: set normal 512 fs /4 fs Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: set spdifout clk:5644800, mpll:22579200 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: get spdifout clk:5644797, mpll:22579186 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Done playing: Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand play took 10 milliseconds Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: ---------------------------- MPD announces state update: player Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::getState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand status Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 9 milliseconds Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 6 milliseconds Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand status took 3 milliseconds Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: ControllerMpd::parseTrackInfo Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106322,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus pause Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106322,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus play Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Received an update from plugin. extracting info from payload Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ControllerMpd::pushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::servicePushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CorePlayQueue::getTrack 0 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106322,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"49651324&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/49651324&soundQuality=LOSSLESS","trackType":"tidal"} Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: verbose: CURRENT POSITION 0 Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState stateService play Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::syncState currentStatus play Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Received an update from plugin. extracting info from payload Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreStateMachine::pushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::volumioPushState Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output update for this device Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MRS: Pushing multiroomSync output Oct 01 13:19:52 tom-juste-nielsen-rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 155ms Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 153ms Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: ------------------------------ 151ms Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: Signalling Playback active due to playback status change Oct 01 13:19:52 tom-juste-nielsen-rivo volumio[3515]: info: MCU Signalled Playback Active Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: TypeError: Cannot read property 'then' of undefined Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2260:30) Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1442:26) Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: at Socket.emit (events.js:400:28) Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 01 13:19:54 tom-juste-nielsen-rivo volumio[3515]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 13:19:55 tom-juste-nielsen-rivo volumiologrotate[2818]: ls: cannot access '/var/log/samba/log.wb-TOM': No such file or directory Oct 01 13:19:55 tom-juste-nielsen-rivo volumiologrotate[2818]: ls: cannot access 'JUSTE': No such file or directory Oct 01 13:19:55 tom-juste-nielsen-rivo volumiologrotate[2818]: ls: cannot access 'NIELSEN': No such file or directory Oct 01 13:19:55 tom-juste-nielsen-rivo volumiologrotate[2818]: ls: cannot access 'RIVO': No such file or directory Oct 01 13:19:55 tom-juste-nielsen-rivo sudo[4399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-01 13:18 Oct 01 13:19:55 tom-juste-nielsen-rivo sudo[4399]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:47:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e08c3e210a64867536255c2503fb09"