-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Tue 2026-01-27 23:31:07 CST. -- Jan 27 23:30:03 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Client dropped request, destroying Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPlay Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::play index 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::stop Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::stPlaybackTimer Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::updateTrackBlock Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrackBlock Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::serviceStop Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::serviceStop Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: [1769578209458] ControllerQobuz::stop Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ControllerMpd::stop Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: sendMpdCommand stop took 12 milliseconds Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:09 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::play index undefined Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::startPlaybackTimer Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: [1769578209472] ControllerQobuz::clearAddPlayTrack Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 3 milliseconds Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: sendMpdCommand stop took 1 milliseconds Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: sendMpdCommand clear took 3 milliseconds Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/54979356" Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Handling url /?data=qobuz://song/54979356 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ------------------------------ 39ms Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ------------------------------ 36ms Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ------------------------------ 35ms Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: error: updateQueue error: null Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: ------------------------------ 36ms Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: Executing endpoint getStreamUrlqobuz Jan 27 23:30:09 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 27 23:30:10 music-server-margules-magenta volumio[1015]: info: getStreamUrl took 767 milliseconds Jan 27 23:30:10 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=9789768&eid=54979356&fmt=6&profile=raw&app_id=539451548&cid=3729377&etsp=1769581810&hmac=1pLva-U7glD4oPM_pEdP0u0pHgM Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Response: 200, length: 18540171 Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Client dropped request, destroying Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/54979356" Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: error: updateQueue error: null Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: error: updateQueue error: null Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: ------------------------------ 2103ms Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: ------------------------------ 2102ms Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/54979356" took 3 milliseconds Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: Upnp client error: Error: This socket has been ended by the other party Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Handling url /?data=qobuz://song/54979356 Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: ------------------------------ 5ms Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: sendMpdCommand play took 4 milliseconds Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: ------------------------------ 2ms Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: ------------------------------ 2ms Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: Executing endpoint getStreamUrlqobuz Jan 27 23:30:11 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: getStreamUrl took 552 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=9789768&eid=54979356&fmt=6&profile=raw&app_id=539451548&cid=3729377&etsp=1769581812&hmac=jnlwwXEgM_AWEaOkC-YonZC0zsA Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Response: 200, length: 18540171 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 10 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 11 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 10 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:12 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:12 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ------------------------------ 15ms Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 5 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ------------------------------ 57ms Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ------------------------------ 57ms Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 43 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ------------------------------ 104ms Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ------------------------------ 101ms Jan 27 23:30:12 music-server-margules-magenta volumio[1015]: info: ------------------------------ 100ms Jan 27 23:30:15 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Client dropped request, destroying Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPlay Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::play index 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::stop Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::stPlaybackTimer Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::updateTrackBlock Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrackBlock Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::serviceStop Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::serviceStop Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: [1769578225057] ControllerQobuz::stop Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ControllerMpd::stop Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: sendMpdCommand stop took 11 milliseconds Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::play index undefined Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::startPlaybackTimer Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: [1769578225070] ControllerQobuz::clearAddPlayTrack Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 23:30:25 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: sendMpdCommand stop took 1 milliseconds Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 4 milliseconds Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: sendMpdCommand clear took 3 milliseconds Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 3 milliseconds Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/54979356" Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Handling url /?data=qobuz://song/54979356 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ------------------------------ 24ms Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ------------------------------ 23ms Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: error: updateQueue error: null Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ------------------------------ 25ms Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: ------------------------------ 29ms Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: Executing endpoint getStreamUrlqobuz Jan 27 23:30:25 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: getStreamUrl took 2028 milliseconds Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=9789768&eid=54979356&fmt=6&profile=raw&app_id=539451548&cid=3729377&etsp=1769581826&hmac=_TO0FbTq5KBscx1ebgBFvJljpLg Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Response: 200, length: 18540171 Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Client dropped request, destroying Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/54979356" Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: Upnp client error: Error: This socket has been ended by the other party Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: error: updateQueue error: null Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: error: updateQueue error: null Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: ------------------------------ 2236ms Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: ------------------------------ 2236ms Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/54979356" took 2 milliseconds Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces system playlist update Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Handling url /?data=qobuz://song/54979356 Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: Ignoring MPD Status Update Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: ------------------------------ 4ms Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: sendMpdCommand play took 3 milliseconds Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: ------------------------------ 3ms Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: ------------------------------ 2ms Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: Executing endpoint getStreamUrlqobuz Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: info: getStreamUrl took 523 milliseconds Jan 27 23:30:27 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=9789768&eid=54979356&fmt=6&profile=raw&app_id=539451548&cid=3729377&etsp=1769581827&hmac=2b-nPcyxB1tSAoXf9EC6YosJeBw Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Response: 200, length: 18540171 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 10 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:28 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:28 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: ---------------------------- MPD announces state update: player Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::getState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 13 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 12 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 3 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ------------------------------ 18ms Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand status took 4 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ------------------------------ 106ms Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ------------------------------ 105ms Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ------------------------------ 97ms Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ------------------------------ 96ms Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: sendMpdCommand playlistinfo took 91 milliseconds Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ControllerMpd::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::servicePushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CorePlayQueue::getTrack 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"54979356","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/54979356","trackType":"qobuz"} Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: verbose: CURRENT POSITION 6 Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:28 music-server-margules-magenta volumio[1015]: info: ------------------------------ 119ms Jan 27 23:30:31 music-server-margules-magenta volumio[1015]: STREAMING PROXY: Client dropped request, destroying Jan 27 23:30:32 music-server-margules-magenta ntpd[815]: Soliciting pool server 240b:4013:6:1500:4424:e971:3cba:f1d3 Jan 27 23:30:34 music-server-margules-magenta volumio[1015]: info: VolumeController::SetAlsaVolume100 Jan 27 23:30:34 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:34 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:34 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:34 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:34 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:34 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:34 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:34 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:34 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jan 27 23:30:34 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:34 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jan 27 23:30:37 music-server-margules-magenta volumio[1015]: info: VolumeController::SetAlsaVolume90 Jan 27 23:30:38 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:38 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:38 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:38 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:38 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:38 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:38 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:38 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:38 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jan 27 23:30:39 music-server-margules-magenta volumio[1015]: info: VolumeController::SetAlsaVolume100 Jan 27 23:30:39 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:39 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:39 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:39 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:39 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:39 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:39 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:39 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:39 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jan 27 23:30:40 music-server-margules-magenta volumio[1015]: info: VolumeController::SetAlsaVolume90 Jan 27 23:30:40 music-server-margules-magenta volumio[1015]: info: CoreStateMachine::pushState Jan 27 23:30:40 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:30:40 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 27 23:30:40 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::volumioGetState Jan 27 23:30:40 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output update for this device Jan 27 23:30:40 music-server-margules-magenta volumio[1015]: info: MRS: Pushing multiroomSync output Jan 27 23:30:40 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:40 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 27 23:30:40 music-server-margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jan 27 23:30:51 music-server-margules-magenta volumiologrotate[590]: ls: cannot access '/var/log/samba/log.wb-MUSIC': No such file or directory Jan 27 23:30:51 music-server-margules-magenta volumiologrotate[590]: ls: cannot access 'SERVER': No such file or directory Jan 27 23:30:51 music-server-margules-magenta volumiologrotate[590]: ls: cannot access 'MARGULES': No such file or directory Jan 27 23:30:51 music-server-margules-magenta volumiologrotate[590]: ls: cannot access 'MAGENTA': No such file or directory Jan 27 23:30:55 music-server-margules-magenta volumio[1015]: Searching plugin music_service/qobuz Jan 27 23:30:55 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jan 27 23:30:55 music-server-margules-magenta volumio[1015]: Searching plugin music_service/qobuz Jan 27 23:30:55 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jan 27 23:30:56 music-server-margules-magenta volumio[1015]: info: searchQOBUZUri took 1341 milliseconds Jan 27 23:30:56 music-server-margules-magenta volumio[1015]: info: All search sources collected, pushing search results Jan 27 23:30:57 music-server-margules-magenta volumio[1015]: info: searchQOBUZUri took 1855 milliseconds Jan 27 23:30:57 music-server-margules-magenta volumio[1015]: info: All search sources collected, pushing search results Jan 27 23:31:02 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: handleBrowseUri took 2116 milliseconds Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: Preload queue cleared Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: Executing endpoint getSimilarArtists Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: Executing endpoint getSimilarArtists Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: Executing endpoint metavolumio Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: Executing endpoint metavolumio Jan 27 23:31:05 music-server-margules-magenta volumio[1015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: errno: -111, Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: code: 'ECONNREFUSED', Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: syscall: 'connect', Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: address: '158.69.209.125', Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: port: 443 Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: } Jan 27 23:31:07 music-server-margules-magenta volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 23:31:07 music-server-margules-magenta sudo[3696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-27 23:30 Jan 27 23:31:07 music-server-margules-magenta sudo[3696]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="margules" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 17 Nov 2025 05:18:59 PM CET" VOLUMIO_VERSION="3.876" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Margules Magenta" VOLUMIO_VENDOR="Margules" VOLUMIO_MODEL="Margules Magenta" VOLUMIO_HASH="140a52f07d503434e97bb461dfd1f0fd"