-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Thu 2025-10-30 16:00:41 CST. -- Oct 30 15:59:01 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:05 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 15:59:09 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:13 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 15:59:16 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 15:59:16 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 15:59:16 margules volumio[1016]: info: Discovery: Getting this device information Oct 30 15:59:16 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 15:59:16 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 15:59:16 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 15:59:18 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:24 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 15:59:26 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:33 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 15:59:34 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:36 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 15:59:37 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:38 margules volumio[1016]: info: Executing endpoint metavolumio Oct 30 15:59:38 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 30 15:59:38 margules volumio[1016]: info: Executing endpoint metavolumio Oct 30 15:59:38 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 30 15:59:38 margules volumio[1016]: info: Executing endpoint metavolumio Oct 30 15:59:38 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 30 15:59:39 margules volumio[1016]: info: Executing endpoint metavolumio Oct 30 15:59:39 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 30 15:59:40 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:41 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 15:59:43 margules volumio[1016]: Searching all installed plugins Oct 30 15:59:43 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 15:59:43 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: , search Oct 30 15:59:43 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 30 15:59:43 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 30 15:59:43 margules volumio[1016]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 30 15:59:43 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 30 15:59:44 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 30 15:59:44 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: tidal , search Oct 30 15:59:44 margules volumio[1016]: info: searchTIDALUri took 324 milliseconds Oct 30 15:59:44 margules volumio[1016]: info: searchQOBUZUri took 665 milliseconds Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 15:59:46 margules volumio[1016]: info: Discovery: Getting this device information Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 15:59:46 margules volumio[1016]: Searching all installed plugins Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: , search Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 30 15:59:46 margules volumio[1016]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 30 15:59:46 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: tidal , search Oct 30 15:59:46 margules volumio[1016]: info: searchTIDALUri took 296 milliseconds Oct 30 15:59:47 margules volumio[1016]: info: searchQOBUZUri took 491 milliseconds Oct 30 15:59:48 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:49 margules volumio[1016]: error: Search in plugin tidal timed out Oct 30 15:59:49 margules volumio[1016]: info: All search sources collected, pushing search results Oct 30 15:59:49 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 15:59:51 margules volumio[1016]: error: Search in plugin tidal timed out Oct 30 15:59:51 margules volumio[1016]: info: All search sources collected, pushing search results Oct 30 15:59:51 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 15:59:52 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 15:59:53 margules volumio[1016]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Oct 30 15:59:53 margules volumio[1016]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Oct 30 15:59:53 margules volumio[1016]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Oct 30 15:59:53 margules volumio[1016]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Oct 30 15:59:53 margules volumio[1016]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Oct 30 15:59:53 margules volumio[1016]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Oct 30 15:59:53 margules volumio[1016]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 30 15:59:53 margules volumio[1016]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 30 15:59:55 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:00 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 16:00:03 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 16:00:03 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:06 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 16:00:06 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:09 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 16:00:10 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:12 margules volumio[1016]: info: Preload queue cleared Oct 30 16:00:12 margules volumio[1016]: info: CoreCommandRouter::volumioAddQueueItems Oct 30 16:00:12 margules volumio[1016]: info: CoreStateMachine::addQueueItems Oct 30 16:00:12 margules volumio[1016]: info: CorePlayQueue::addQueueItems Oct 30 16:00:12 margules volumio[1016]: info: Preload queue cleared Oct 30 16:00:12 margules volumio[1016]: info: Adding Item to queue: qobuz://song/198686381 Oct 30 16:00:12 margules volumio[1016]: info: Exploding uri qobuz://song/198686381 in service qobuz Oct 30 16:00:12 margules volumio[1016]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 30 16:00:13 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:13 margules volumio[1016]: info: explodeUri took 471 milliseconds Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioPushQueue Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::saveQueue Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::updateTrackBlock Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrackBlock Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioPlay Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::play index 81 Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::stop Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::stPlaybackTimer Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::updateTrackBlock Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrackBlock Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 80 Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 80 Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::serviceStop Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 80 Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::serviceStop Oct 30 16:00:13 margules volumio[1016]: info: [1761861613351] ControllerQobuz::stop Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 16:00:13 margules volumio[1016]: info: ControllerMpd::stop Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 16:00:13 margules volumio[1016]: info: Oct 30 16:00:13 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:13 margules volumio[1016]: info: sendMpdCommand stop took 17 milliseconds Oct 30 16:00:13 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::play index undefined Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::startPlaybackTimer Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 30 16:00:13 margules volumio[1016]: info: [1761861613371] ControllerQobuz::clearAddPlayTrack Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 16:00:13 margules volumio[1016]: info: sendMpdCommand status took 3 milliseconds Oct 30 16:00:13 margules volumio[1016]: info: sendMpdCommand stop took 2 milliseconds Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 16:00:13 margules volumio[1016]: info: Oct 30 16:00:13 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:13 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:13 margules volumio[1016]: info: Oct 30 16:00:13 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:13 margules 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 Oct 30 16:00:13 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:13 margules volumio[1016]: info: Oct 30 16:00:13 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:13 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:13 margules volumio[1016]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 16:00:13 margules volumio[1016]: info: sendMpdCommand clear took 3 milliseconds Oct 30 16:00:13 margules volumio[1016]: info: sendMpdCommand status took 3 milliseconds Oct 30 16:00:13 margules volumio[1016]: info: sendMpdCommand status took 2 milliseconds Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/198686381" Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:13 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:13 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 30 16:00:13 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:13 margules volumio[1016]: STREAMING PROXY: Handling url /?data=qobuz://song/198686381 Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 30 16:00:13 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:13 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:13 margules volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 30 16:00:13 margules volumio[1016]: info: ------------------------------ 23ms Oct 30 16:00:13 margules volumio[1016]: info: ------------------------------ 18ms Oct 30 16:00:13 margules volumio[1016]: info: ------------------------------ 17ms Oct 30 16:00:13 margules volumio[1016]: info: Oct 30 16:00:13 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:13 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:13 margules volumio[1016]: info: Oct 30 16:00:13 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:13 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:13 margules volumio[1016]: error: updateQueue error: null Oct 30 16:00:13 margules volumio[1016]: info: ------------------------------ 18ms Oct 30 16:00:13 margules volumio[1016]: info: Executing endpoint getStreamUrlqobuz Oct 30 16:00:13 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 30 16:00:13 margules volumio[1016]: info: getStreamUrl took 427 milliseconds Oct 30 16:00:13 margules volumio[1016]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2287576&eid=198686381&fmt=6&profile=raw&app_id=539451548&cid=2177195&etsp=1761865213&hmac=WmdRtBEt95KRnI-aLPmUV1FG_nU Oct 30 16:00:15 margules volumio[1016]: STREAMING PROXY: Response: 200, length: 15154737 Oct 30 16:00:15 margules volumio[1016]: STREAMING PROXY: Client dropped request, destroying Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/198686381" Oct 30 16:00:15 margules volumio[1016]: error: updateQueue error: null Oct 30 16:00:15 margules volumio[1016]: error: updateQueue error: null Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:15 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 1779ms Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 1779ms Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/198686381" took 2 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand play Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:15 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:15 margules volumio[1016]: STREAMING PROXY: Handling url /?data=qobuz://song/198686381 Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:15 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 9ms Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand play took 8 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 8ms Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 7ms Oct 30 16:00:15 margules volumio[1016]: info: Executing endpoint getStreamUrlqobuz Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 30 16:00:15 margules volumio[1016]: info: getStreamUrl took 571 milliseconds Oct 30 16:00:15 margules volumio[1016]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2287576&eid=198686381&fmt=6&profile=raw&app_id=539451548&cid=2177195&etsp=1761865215&hmac=sEXYZlYdAvD9fBc_oDXlXs0V4DU Oct 30 16:00:15 margules volumio[1016]: STREAMING PROXY: Response: 200, length: 15154737 Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand status took 15 milliseconds Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand status took 17 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand status took 15 milliseconds Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:15 margules volumio[1016]: info: Oct 30 16:00:15 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand status took 4 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand status took 3 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand status took 2 milliseconds Oct 30 16:00:15 margules 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 Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:15 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:15 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198686381","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/198686381","trackType":"qobuz"} Oct 30 16:00:15 margules volumio[1016]: verbose: CURRENT POSITION 81 Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus stop Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:15 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:15 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198686381","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/198686381","trackType":"qobuz"} Oct 30 16:00:15 margules volumio[1016]: verbose: CURRENT POSITION 81 Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:15 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:15 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:15 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198686381","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/198686381","trackType":"qobuz"} Oct 30 16:00:15 margules volumio[1016]: verbose: CURRENT POSITION 81 Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:15 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 34ms Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 37ms Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 36ms Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 30 16:00:15 margules volumio[1016]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:15 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:15 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:15 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198686381","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/198686381","trackType":"qobuz"} Oct 30 16:00:15 margules volumio[1016]: verbose: CURRENT POSITION 81 Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:15 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:15 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:15 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198686381","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/198686381","trackType":"qobuz"} Oct 30 16:00:15 margules volumio[1016]: verbose: CURRENT POSITION 81 Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:15 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:15 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:15 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198686381","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/198686381","trackType":"qobuz"} Oct 30 16:00:15 margules volumio[1016]: verbose: CURRENT POSITION 81 Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:15 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:15 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 39ms Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 37ms Oct 30 16:00:15 margules volumio[1016]: info: ------------------------------ 37ms Oct 30 16:00:16 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:16 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 16:00:16 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 16:00:16 margules volumio[1016]: info: Discovery: Getting this device information Oct 30 16:00:16 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:16 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 16:00:17 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 16:00:19 margules volumio[1016]: STREAMING PROXY: Client dropped request, destroying Oct 30 16:00:19 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:21 margules volumio[1016]: info: Preload queue cleared Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioAddQueueItems Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::addQueueItems Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::addQueueItems Oct 30 16:00:21 margules volumio[1016]: info: Preload queue cleared Oct 30 16:00:21 margules volumio[1016]: info: Adding Item to queue: qobuz://song/4920498 Oct 30 16:00:21 margules volumio[1016]: info: Using cached record of: qobuz://song/4920498 Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioPushQueue Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::saveQueue Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::updateTrackBlock Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrackBlock Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioPlay Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::play index 82 Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::stop Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::stPlaybackTimer Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::updateTrackBlock Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrackBlock Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::serviceStop Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 81 Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::serviceStop Oct 30 16:00:21 margules volumio[1016]: info: [1761861621378] ControllerQobuz::stop Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 16:00:21 margules volumio[1016]: info: ControllerMpd::stop Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 16:00:21 margules 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 Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand stop took 12 milliseconds Oct 30 16:00:21 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::play index undefined Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::startPlaybackTimer Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 30 16:00:21 margules volumio[1016]: info: [1761861621392] ControllerQobuz::clearAddPlayTrack Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand status took 3 milliseconds Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand stop took 1 milliseconds Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:21 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:21 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:21 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand clear took 3 milliseconds Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand status took 2 milliseconds Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand status took 2 milliseconds Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/4920498" Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:21 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 30 16:00:21 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: STREAMING PROXY: Handling url /?data=qobuz://song/4920498 Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 30 16:00:21 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:21 margules volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 30 16:00:21 margules volumio[1016]: info: ------------------------------ 22ms Oct 30 16:00:21 margules volumio[1016]: info: ------------------------------ 17ms Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:21 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:21 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:21 margules volumio[1016]: error: updateQueue error: null Oct 30 16:00:21 margules volumio[1016]: info: ------------------------------ 18ms Oct 30 16:00:21 margules volumio[1016]: info: Executing endpoint getStreamUrlqobuz Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 30 16:00:21 margules volumio[1016]: info: getStreamUrl took 448 milliseconds Oct 30 16:00:21 margules volumio[1016]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2287576&eid=4920498&fmt=6&profile=raw&app_id=539451548&cid=2177195&etsp=1761865221&hmac=g2Zapx-p6iPzZOsW1CY1aM3nUXo Oct 30 16:00:21 margules volumio[1016]: STREAMING PROXY: Response: 200, length: 49816398 Oct 30 16:00:21 margules volumio[1016]: STREAMING PROXY: Client dropped request, destroying Oct 30 16:00:21 margules volumio[1016]: error: updateQueue error: null Oct 30 16:00:21 margules volumio[1016]: error: updateQueue error: null Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4920498" Oct 30 16:00:21 margules volumio[1016]: info: ------------------------------ 495ms Oct 30 16:00:21 margules volumio[1016]: info: ------------------------------ 494ms Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:21 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4920498" took 1 milliseconds Oct 30 16:00:21 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 16:00:21 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand play Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:21 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:21 margules volumio[1016]: info: Oct 30 16:00:21 margules volumio[1016]: ---------------------------- MPD announces system playlist update Oct 30 16:00:21 margules volumio[1016]: info: Ignoring MPD Status Update Oct 30 16:00:21 margules volumio[1016]: STREAMING PROXY: Handling url /?data=qobuz://song/4920498 Oct 30 16:00:21 margules volumio[1016]: info: ------------------------------ 3ms Oct 30 16:00:21 margules volumio[1016]: info: sendMpdCommand play took 2 milliseconds Oct 30 16:00:21 margules volumio[1016]: info: ------------------------------ 2ms Oct 30 16:00:21 margules volumio[1016]: info: ------------------------------ 2ms Oct 30 16:00:21 margules volumio[1016]: info: Executing endpoint getStreamUrlqobuz Oct 30 16:00:21 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 30 16:00:22 margules volumio[1016]: info: getStreamUrl took 458 milliseconds Oct 30 16:00:22 margules volumio[1016]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2287576&eid=4920498&fmt=6&profile=raw&app_id=539451548&cid=2177195&etsp=1761865222&hmac=QHxTlYa1qWsgQevuEHMk1Uya8NY Oct 30 16:00:22 margules volumio[1016]: STREAMING PROXY: Response: 200, length: 49816398 Oct 30 16:00:22 margules volumio[1016]: info: Oct 30 16:00:22 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:22 margules volumio[1016]: info: Oct 30 16:00:22 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:22 margules volumio[1016]: info: Oct 30 16:00:22 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:22 margules volumio[1016]: info: Oct 30 16:00:22 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand status took 15 milliseconds Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand status took 16 milliseconds Oct 30 16:00:22 margules 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 Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand status took 15 milliseconds Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand status took 2 milliseconds Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:22 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:22 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:22 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus stop Oct 30 16:00:22 margules volumio[1016]: info: ------------------------------ 21ms Oct 30 16:00:22 margules volumio[1016]: info: Oct 30 16:00:22 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:22 margules volumio[1016]: info: Oct 30 16:00:22 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand status took 3 milliseconds Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand status took 2 milliseconds Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:22 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:22 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:22 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:22 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:22 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:22 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:22 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:22 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:22 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:22 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:22 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:22 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: ------------------------------ 51ms Oct 30 16:00:22 margules volumio[1016]: info: ------------------------------ 50ms Oct 30 16:00:22 margules volumio[1016]: info: ------------------------------ 37ms Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 30 16:00:22 margules volumio[1016]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:22 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:22 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:22 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:22 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:22 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:22 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:22 margules volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:22 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 30 16:00:22 margules volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:22 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:22 margules volumio[1016]: info: ------------------------------ 45ms Oct 30 16:00:22 margules volumio[1016]: info: ------------------------------ 45ms Oct 30 16:00:25 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::volumioPause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::pause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::stPlaybackTimer Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::servicePause Oct 30 16:00:27 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::servicePause Oct 30 16:00:27 margules volumio[1016]: info: [1761861627419] ControllerQobuz::pause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 16:00:27 margules volumio[1016]: info: ControllerMpd::pause Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand pause Oct 30 16:00:27 margules volumio[1016]: info: Oct 30 16:00:27 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:27 margules volumio[1016]: info: sendMpdCommand pause took 1 milliseconds Oct 30 16:00:27 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:27 margules volumio[1016]: info: Oct 30 16:00:27 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:27 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:27 margules volumio[1016]: info: Oct 30 16:00:27 margules volumio[1016]: ---------------------------- MPD announces state update: player Oct 30 16:00:27 margules volumio[1016]: info: ControllerMpd::getState Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 30 16:00:27 margules 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 Oct 30 16:00:27 margules volumio[1016]: info: sendMpdCommand status took 4 milliseconds Oct 30 16:00:27 margules volumio[1016]: info: sendMpdCommand status took 3 milliseconds Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:27 margules volumio[1016]: info: sendMpdCommand status took 3 milliseconds Oct 30 16:00:27 margules volumio[1016]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 16:00:27 margules volumio[1016]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::parseState Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:27 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:27 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:27 margules volumio[1016]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5765,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1068 Kbps","isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:27 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::syncState stateService pause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus pause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::stPlaybackTimer Oct 30 16:00:27 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:27 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:27 margules volumio[1016]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5765,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1068 Kbps","isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:27 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::syncState stateService pause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus pause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::stPlaybackTimer Oct 30 16:00:27 margules volumio[1016]: info: ------------------------------ 17ms Oct 30 16:00:27 margules volumio[1016]: info: ------------------------------ 16ms Oct 30 16:00:27 margules volumio[1016]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 30 16:00:27 margules volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 30 16:00:27 margules volumio[1016]: info: ControllerMpd::pushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::servicePushState Oct 30 16:00:27 margules volumio[1016]: info: CorePlayQueue::getTrack 82 Oct 30 16:00:27 margules volumio[1016]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5765,"duration":387,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1068 Kbps","isStreaming":false,"title":"4920498","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4920498","trackType":"qobuz"} Oct 30 16:00:27 margules volumio[1016]: verbose: CURRENT POSITION 82 Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::syncState stateService pause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::syncState currentStatus pause Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::pushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:27 margules volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:27 margules volumio[1016]: info: CoreStateMachine::stPlaybackTimer Oct 30 16:00:27 margules volumio[1016]: info: ------------------------------ 21ms Oct 30 16:00:27 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:28 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 16:00:31 margules volumio[1016]: STREAMING PROXY: Client dropped request, destroying Oct 30 16:00:35 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:36 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.197 Oct 30 16:00:38 margules volumio[1016]: info: Discovery: Remote connection error: 192.168.1.194 Oct 30 16:00:41 margules volumio[1016]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 16:00:41 margules volumio[1016]: Error: connect ETIMEDOUT 158.69.210.98:443 Oct 30 16:00:41 margules volumio[1016]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 30 16:00:41 margules volumio[1016]: errno: -110, Oct 30 16:00:41 margules volumio[1016]: code: 'ETIMEDOUT', Oct 30 16:00:41 margules volumio[1016]: syscall: 'connect', Oct 30 16:00:41 margules volumio[1016]: address: '158.69.210.98', Oct 30 16:00:41 margules volumio[1016]: port: 443 Oct 30 16:00:41 margules volumio[1016]: } Oct 30 16:00:41 margules volumio[1016]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 16:00:41 margules sudo[4343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-30 15:59 Oct 30 16:00:41 margules sudo[4343]: 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="5d50cd80bebce60dddccd7f5e813d656e28ca4cd" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="margules" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 23 Oct 2025 12:47:45 PM CEST" VOLUMIO_VERSION="3.837" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Margules Magenta" VOLUMIO_VENDOR="Margules" VOLUMIO_MODEL="Margules Magenta" VOLUMIO_HASH="11127cd628c21711c77013d9b79f356e"