-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Thu 2026-02-12 14:51:06 UTC. -- Feb 12 14:50:02 volumio volumio[1302]: info: Preload queue cleared Feb 12 14:50:02 volumio volumio[1302]: info: CoreCommandRouter::volumioAddQueueItems Feb 12 14:50:02 volumio volumio[1302]: info: CoreStateMachine::addQueueItems Feb 12 14:50:02 volumio volumio[1302]: info: CorePlayQueue::addQueueItems Feb 12 14:50:02 volumio volumio[1302]: info: Preload queue cleared Feb 12 14:50:02 volumio volumio[1302]: info: Adding Item to queue: qobuz://song/73670591 Feb 12 14:50:02 volumio volumio[1302]: info: Using cached record of: qobuz://song/73670591 Feb 12 14:50:02 volumio volumio[1302]: info: CoreCommandRouter::volumioPushQueue Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::saveQueue Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::updateTrackBlock Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrackBlock Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::volumioPlay Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::play index 3027 Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::stop Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::stPlaybackTimer Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::updateTrackBlock Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrackBlock Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::pushState Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrack 3010 Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::volumioPushState Feb 12 14:50:03 volumio volumio[1302]: info: MRS: Pushing multiroomSync output update for this device Feb 12 14:50:03 volumio volumio[1302]: info: MRS: Pushing multiroomSync output Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrack 3010 Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::serviceStop Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrack 3010 Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::serviceStop Feb 12 14:50:03 volumio volumio[1302]: info: [1770907803198] ControllerQobuz::stop Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 14:50:03 volumio volumio[1302]: info: ControllerMpd::stop Feb 12 14:50:03 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 14:50:03 volumio volumio[1302]: info: Feb 12 14:50:03 volumio volumio[1302]: ---------------------------- MPD announces state update: player Feb 12 14:50:03 volumio volumio[1302]: info: sendMpdCommand stop took 298 milliseconds Feb 12 14:50:03 volumio volumio[1302]: info: ControllerMpd::getState Feb 12 14:50:03 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::play index undefined Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::startPlaybackTimer Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 12 14:50:03 volumio volumio[1302]: info: [1770907803523] ControllerQobuz::clearAddPlayTrack Feb 12 14:50:03 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 14:50:03 volumio volumio[1302]: info: sendMpdCommand status took 85 milliseconds Feb 12 14:50:03 volumio volumio[1302]: info: sendMpdCommand stop took 60 milliseconds Feb 12 14:50:03 volumio volumio[1302]: verbose: ControllerMpd::parseState Feb 12 14:50:03 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 14:50:03 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 14:50:03 volumio volumio[1302]: info: Feb 12 14:50:03 volumio volumio[1302]: ---------------------------- MPD announces system playlist update Feb 12 14:50:03 volumio volumio[1302]: info: Ignoring MPD Status Update Feb 12 14:50:03 volumio volumio[1302]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 14:50:03 volumio volumio[1302]: info: sendMpdCommand clear took 5 milliseconds Feb 12 14:50:03 volumio volumio[1302]: verbose: ControllerMpd::parseTrackInfo Feb 12 14:50:03 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/73670591" Feb 12 14:50:03 volumio volumio[1302]: info: ControllerMpd::pushState Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::servicePushState Feb 12 14:50:03 volumio volumio[1302]: info: CoreStateMachine::pushState Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::volumioPushState Feb 12 14:50:03 volumio volumio[1302]: info: MRS: Pushing multiroomSync output update for this device Feb 12 14:50:03 volumio volumio[1302]: info: MRS: Pushing multiroomSync output Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:03 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:03 volumio volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Feb 12 14:50:03 volumio volumio[1302]: STREAMING PROXY: Handling url /?data=qobuz://song/73670591 Feb 12 14:50:03 volumio volumio[1302]: info: ------------------------------ 112ms Feb 12 14:50:03 volumio volumio[1302]: error: updateQueue error: null Feb 12 14:50:03 volumio volumio[1302]: info: ------------------------------ 26ms Feb 12 14:50:03 volumio volumio[1302]: info: Executing endpoint getStreamUrlqobuz Feb 12 14:50:03 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 12 14:50:03 volumio volumio[1302]: info: getStreamUrl took 259 milliseconds Feb 12 14:50:03 volumio volumio[1302]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1839081&eid=73670591&fmt=6&profile=raw&app_id=539451548&cid=1954306&etsp=1770911403&hmac=AqpkoLPlwXQHhO9s2mfaNgv1kAw Feb 12 14:50:04 volumio volumio[1302]: STREAMING PROXY: Response: 200, length: 11015349 Feb 12 14:50:04 volumio volumio[1302]: STREAMING PROXY: Client dropped request, destroying Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/73670591" Feb 12 14:50:04 volumio volumio[1302]: info: Feb 12 14:50:04 volumio volumio[1302]: ---------------------------- MPD announces system playlist update Feb 12 14:50:04 volumio volumio[1302]: info: Ignoring MPD Status Update Feb 12 14:50:04 volumio volumio[1302]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/73670591" took 1 milliseconds Feb 12 14:50:04 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand play Feb 12 14:50:04 volumio volumio[1302]: info: ------------------------------ 1ms Feb 12 14:50:04 volumio volumio[1302]: info: sendMpdCommand play took 1 milliseconds Feb 12 14:50:04 volumio volumio[1302]: STREAMING PROXY: Handling url /?data=qobuz://song/73670591 Feb 12 14:50:04 volumio volumio[1302]: info: Executing endpoint getStreamUrlqobuz Feb 12 14:50:04 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 12 14:50:04 volumio volumio[1302]: info: getStreamUrl took 256 milliseconds Feb 12 14:50:04 volumio volumio[1302]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1839081&eid=73670591&fmt=6&profile=raw&app_id=539451548&cid=1954306&etsp=1770911404&hmac=6jFnwZhxuqcs-_J5td39BWPOp6E Feb 12 14:50:04 volumio volumio[1302]: STREAMING PROXY: Response: 200, length: 11015349 Feb 12 14:50:04 volumio volumio[1302]: info: Feb 12 14:50:04 volumio volumio[1302]: ---------------------------- MPD announces state update: player Feb 12 14:50:04 volumio volumio[1302]: info: ControllerMpd::getState Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Feb 12 14:50:04 volumio volumio[1302]: info: sendMpdCommand status took 0 milliseconds Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::parseState Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 14:50:04 volumio volumio[1302]: info: Feb 12 14:50:04 volumio volumio[1302]: ---------------------------- MPD announces state update: player Feb 12 14:50:04 volumio volumio[1302]: info: ControllerMpd::getState Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Feb 12 14:50:04 volumio volumio[1302]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 14:50:04 volumio volumio[1302]: info: sendMpdCommand status took 1 milliseconds Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::parseTrackInfo Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::parseState Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 14:50:04 volumio volumio[1302]: info: ControllerMpd::pushState Feb 12 14:50:04 volumio volumio[1302]: info: CoreCommandRouter::servicePushState Feb 12 14:50:04 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:04 volumio volumio[1302]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":103,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"73670591","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/73670591","trackType":"qobuz"} Feb 12 14:50:04 volumio volumio[1302]: verbose: CURRENT POSITION 3027 Feb 12 14:50:04 volumio volumio[1302]: info: CoreStateMachine::syncState stateService play Feb 12 14:50:04 volumio volumio[1302]: info: CoreStateMachine::syncState currentStatus stop Feb 12 14:50:04 volumio volumio[1302]: info: ------------------------------ 11ms Feb 12 14:50:04 volumio volumio[1302]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 14:50:04 volumio volumio[1302]: verbose: ControllerMpd::parseTrackInfo Feb 12 14:50:04 volumio volumio[1302]: info: ControllerMpd::pushState Feb 12 14:50:04 volumio volumio[1302]: info: CoreCommandRouter::servicePushState Feb 12 14:50:04 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:04 volumio volumio[1302]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":103,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"73670591","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/73670591","trackType":"qobuz"} Feb 12 14:50:04 volumio volumio[1302]: verbose: CURRENT POSITION 3027 Feb 12 14:50:04 volumio volumio[1302]: info: CoreStateMachine::syncState stateService play Feb 12 14:50:04 volumio volumio[1302]: info: CoreStateMachine::syncState currentStatus play Feb 12 14:50:04 volumio volumio[1302]: info: Received an update from plugin. extracting info from payload Feb 12 14:50:04 volumio volumio[1302]: info: CoreStateMachine::pushState Feb 12 14:50:04 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:50:04 volumio volumio[1302]: info: CoreCommandRouter::volumioPushState Feb 12 14:50:04 volumio volumio[1302]: info: MRS: Pushing multiroomSync output update for this device Feb 12 14:50:04 volumio volumio[1302]: info: MRS: Pushing multiroomSync output Feb 12 14:50:04 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:04 volumio volumio[1302]: info: CoreStateMachine::pushState Feb 12 14:50:04 volumio volumio[1302]: info: CoreCommandRouter::volumioPushState Feb 12 14:50:04 volumio volumio[1302]: info: MRS: Pushing multiroomSync output update for this device Feb 12 14:50:04 volumio volumio[1302]: info: MRS: Pushing multiroomSync output Feb 12 14:50:04 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:04 volumio volumio[1302]: info: ------------------------------ 28ms Feb 12 14:50:05 volumio volumio[1302]: STREAMING PROXY: Client dropped request, destroying Feb 12 14:50:12 volumio volumio[1302]: info: Preload queue cleared Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::volumioAddQueueItems Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::addQueueItems Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::addQueueItems Feb 12 14:50:12 volumio volumio[1302]: info: Preload queue cleared Feb 12 14:50:12 volumio volumio[1302]: info: Adding Item to queue: qobuz://song/73670592 Feb 12 14:50:12 volumio volumio[1302]: info: Using cached record of: qobuz://song/73670592 Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::volumioPushQueue Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::saveQueue Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::updateTrackBlock Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrackBlock Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::volumioPlay Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::play index 3028 Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::stop Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::stPlaybackTimer Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::updateTrackBlock Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrackBlock Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::pushState Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::volumioPushState Feb 12 14:50:12 volumio volumio[1302]: info: MRS: Pushing multiroomSync output update for this device Feb 12 14:50:12 volumio volumio[1302]: info: MRS: Pushing multiroomSync output Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::serviceStop Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrack 3027 Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::serviceStop Feb 12 14:50:12 volumio volumio[1302]: info: [1770907812572] ControllerQobuz::stop Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 14:50:12 volumio volumio[1302]: info: ControllerMpd::stop Feb 12 14:50:12 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 14:50:12 volumio volumio[1302]: info: Feb 12 14:50:12 volumio volumio[1302]: ---------------------------- MPD announces state update: player Feb 12 14:50:12 volumio volumio[1302]: info: sendMpdCommand stop took 232 milliseconds Feb 12 14:50:12 volumio volumio[1302]: info: ControllerMpd::getState Feb 12 14:50:12 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::play index undefined Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrack 3028 Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::startPlaybackTimer Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrack 3028 Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 12 14:50:12 volumio volumio[1302]: info: [1770907812808] ControllerQobuz::clearAddPlayTrack Feb 12 14:50:12 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 14:50:12 volumio volumio[1302]: info: sendMpdCommand status took 68 milliseconds Feb 12 14:50:12 volumio volumio[1302]: info: sendMpdCommand stop took 65 milliseconds Feb 12 14:50:12 volumio volumio[1302]: verbose: ControllerMpd::parseState Feb 12 14:50:12 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 14:50:12 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 14:50:12 volumio volumio[1302]: info: Feb 12 14:50:12 volumio volumio[1302]: ---------------------------- MPD announces system playlist update Feb 12 14:50:12 volumio volumio[1302]: info: Ignoring MPD Status Update Feb 12 14:50:12 volumio volumio[1302]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 12 14:50:12 volumio volumio[1302]: info: sendMpdCommand clear took 14 milliseconds Feb 12 14:50:12 volumio volumio[1302]: verbose: ControllerMpd::parseTrackInfo Feb 12 14:50:12 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/73670592" Feb 12 14:50:12 volumio volumio[1302]: info: ControllerMpd::pushState Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::servicePushState Feb 12 14:50:12 volumio volumio[1302]: info: CoreStateMachine::pushState Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrack 3028 Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::volumioPushState Feb 12 14:50:12 volumio volumio[1302]: info: MRS: Pushing multiroomSync output update for this device Feb 12 14:50:12 volumio volumio[1302]: info: MRS: Pushing multiroomSync output Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrack 3028 Feb 12 14:50:12 volumio volumio[1302]: info: CorePlayQueue::getTrack 3028 Feb 12 14:50:12 volumio volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Feb 12 14:50:12 volumio volumio[1302]: STREAMING PROXY: Handling url /?data=qobuz://song/73670592 Feb 12 14:50:12 volumio volumio[1302]: info: ------------------------------ 97ms Feb 12 14:50:12 volumio volumio[1302]: error: updateQueue error: null Feb 12 14:50:12 volumio volumio[1302]: info: ------------------------------ 19ms Feb 12 14:50:12 volumio volumio[1302]: info: Executing endpoint getStreamUrlqobuz Feb 12 14:50:12 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 12 14:50:13 volumio volumio[1302]: info: getStreamUrl took 319 milliseconds Feb 12 14:50:13 volumio volumio[1302]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1839081&eid=73670592&fmt=6&profile=raw&app_id=539451548&cid=1954306&etsp=1770911413&hmac=-io04Np26_BtYGlMUm6VeAkybbM Feb 12 14:50:13 volumio volumio[1302]: STREAMING PROXY: Response: 200, length: 10151843 Feb 12 14:50:13 volumio volumio[1302]: STREAMING PROXY: Client dropped request, destroying Feb 12 14:50:13 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/73670592" Feb 12 14:50:13 volumio volumio[1302]: info: Feb 12 14:50:13 volumio volumio[1302]: ---------------------------- MPD announces system playlist update Feb 12 14:50:13 volumio volumio[1302]: info: Ignoring MPD Status Update Feb 12 14:50:13 volumio volumio[1302]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/73670592" took 2 milliseconds Feb 12 14:50:13 volumio volumio[1302]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 14:50:13 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand play Feb 12 14:50:13 volumio volumio[1302]: info: ------------------------------ 3ms Feb 12 14:50:13 volumio volumio[1302]: info: sendMpdCommand play took 1 milliseconds Feb 12 14:50:13 volumio volumio[1302]: STREAMING PROXY: Handling url /?data=qobuz://song/73670592 Feb 12 14:50:13 volumio volumio[1302]: info: Executing endpoint getStreamUrlqobuz Feb 12 14:50:13 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 12 14:50:14 volumio volumio[1302]: info: getStreamUrl took 300 milliseconds Feb 12 14:50:14 volumio volumio[1302]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1839081&eid=73670592&fmt=6&profile=raw&app_id=539451548&cid=1954306&etsp=1770911414&hmac=sDXqza-sOvNgGhgyiO7b4_l0kPY Feb 12 14:50:14 volumio volumio[1302]: STREAMING PROXY: Response: 200, length: 10151843 Feb 12 14:50:14 volumio volumio[1302]: info: Feb 12 14:50:14 volumio volumio[1302]: ---------------------------- MPD announces state update: player Feb 12 14:50:14 volumio volumio[1302]: info: ControllerMpd::getState Feb 12 14:50:14 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Feb 12 14:50:14 volumio volumio[1302]: info: Feb 12 14:50:14 volumio volumio[1302]: ---------------------------- MPD announces state update: player Feb 12 14:50:14 volumio volumio[1302]: info: ControllerMpd::getState Feb 12 14:50:14 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Feb 12 14:50:14 volumio volumio[1302]: info: sendMpdCommand status took 2 milliseconds Feb 12 14:50:14 volumio volumio[1302]: verbose: ControllerMpd::parseState Feb 12 14:50:14 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 14:50:14 volumio volumio[1302]: info: sendMpdCommand status took 3 milliseconds Feb 12 14:50:14 volumio volumio[1302]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 12 14:50:14 volumio volumio[1302]: verbose: ControllerMpd::parseState Feb 12 14:50:14 volumio volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 14:50:14 volumio volumio[1302]: verbose: ControllerMpd::parseTrackInfo Feb 12 14:50:14 volumio volumio[1302]: info: ControllerMpd::pushState Feb 12 14:50:14 volumio volumio[1302]: info: CoreCommandRouter::servicePushState Feb 12 14:50:14 volumio volumio[1302]: info: CorePlayQueue::getTrack 3028 Feb 12 14:50:14 volumio volumio[1302]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"73670592","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/73670592","trackType":"qobuz"} Feb 12 14:50:14 volumio volumio[1302]: verbose: CURRENT POSITION 3028 Feb 12 14:50:14 volumio volumio[1302]: info: CoreStateMachine::syncState stateService play Feb 12 14:50:14 volumio volumio[1302]: info: CoreStateMachine::syncState currentStatus stop Feb 12 14:50:14 volumio volumio[1302]: info: ------------------------------ 11ms Feb 12 14:50:14 volumio volumio[1302]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 14:50:14 volumio volumio[1302]: verbose: ControllerMpd::parseTrackInfo Feb 12 14:50:14 volumio volumio[1302]: info: ControllerMpd::pushState Feb 12 14:50:14 volumio volumio[1302]: info: CoreCommandRouter::servicePushState Feb 12 14:50:14 volumio volumio[1302]: info: CorePlayQueue::getTrack 3028 Feb 12 14:50:14 volumio volumio[1302]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"73670592","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/73670592","trackType":"qobuz"} Feb 12 14:50:14 volumio volumio[1302]: verbose: CURRENT POSITION 3028 Feb 12 14:50:14 volumio volumio[1302]: info: CoreStateMachine::syncState stateService play Feb 12 14:50:14 volumio volumio[1302]: info: CoreStateMachine::syncState currentStatus play Feb 12 14:50:14 volumio volumio[1302]: info: Received an update from plugin. extracting info from payload Feb 12 14:50:14 volumio volumio[1302]: info: CoreStateMachine::pushState Feb 12 14:50:14 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:50:14 volumio volumio[1302]: info: CoreCommandRouter::volumioPushState Feb 12 14:50:14 volumio volumio[1302]: info: MRS: Pushing multiroomSync output update for this device Feb 12 14:50:14 volumio volumio[1302]: info: MRS: Pushing multiroomSync output Feb 12 14:50:14 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:14 volumio volumio[1302]: info: CoreStateMachine::pushState Feb 12 14:50:14 volumio volumio[1302]: info: CoreCommandRouter::volumioPushState Feb 12 14:50:14 volumio volumio[1302]: info: MRS: Pushing multiroomSync output update for this device Feb 12 14:50:14 volumio volumio[1302]: info: MRS: Pushing multiroomSync output Feb 12 14:50:14 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:14 volumio volumio[1302]: info: ------------------------------ 31ms Feb 12 14:50:15 volumio volumio[1302]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.120 UA: Mozilla/5.0 (Linux; Android 16; SM-A536B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 12 14:50:15 volumio volumio[1302]: STREAMING PROXY: Client dropped request, destroying Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::volumioGetQueue Feb 12 14:50:15 volumio volumio[1302]: info: CoreStateMachine::getQueue Feb 12 14:50:15 volumio volumio[1302]: info: CorePlayQueue::getQueue Feb 12 14:50:15 volumio volumio[1302]: info: Listing playlists Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 12 14:50:15 volumio volumio[1302]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 12 14:50:15 volumio volumio[1302]: info: Received Get System Info Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 14:50:15 volumio volumio[1302]: info: Discovery: Getting this device information Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::volumioGetState Feb 12 14:50:15 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 12 14:50:58 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 12 14:50:59 volumio volumio[1302]: info: handleBrowseUri took 803 milliseconds Feb 12 14:50:59 volumio volumio[1302]: info: Preload queue cleared Feb 12 14:50:59 volumio volumio[1302]: info: Executing endpoint getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: Executing endpoint getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: Executing endpoint getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: Executing endpoint getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: Executing endpoint getSimilarArtists Feb 12 14:50:59 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 12 14:51:00 volumio volumio[1302]: info: Executing endpoint metavolumio Feb 12 14:51:00 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 14:51:00 volumio volumio[1302]: info: Executing endpoint metavolumio Feb 12 14:51:00 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 14:51:00 volumio volumio[1302]: info: Executing endpoint metavolumio Feb 12 14:51:00 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 14:51:00 volumio volumio[1302]: info: Executing endpoint metavolumio Feb 12 14:51:00 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 14:51:01 volumio volumio[1302]: info: Executing endpoint metavolumio Feb 12 14:51:01 volumio volumio[1302]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 14:51:06 volumio volumio[1302]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 14:51:06 volumio volumio[1302]: TypeError: Cannot read property 'slice' of null Feb 12 14:51:06 volumio volumio[1302]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 12 14:51:06 volumio volumio[1302]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 12 14:51:06 volumio volumio[1302]: at Object.onceWrapper (events.js:520:26) Feb 12 14:51:06 volumio volumio[1302]: at ClientRequest.emit (events.js:400:28) Feb 12 14:51:06 volumio volumio[1302]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Feb 12 14:51:06 volumio volumio[1302]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Feb 12 14:51:06 volumio volumio[1302]: at TLSSocket.socketOnData (_http_client.js:515:22) Feb 12 14:51:06 volumio volumio[1302]: at TLSSocket.emit (events.js:400:28) Feb 12 14:51:06 volumio volumio[1302]: at addChunk (internal/streams/readable.js:293:12) Feb 12 14:51:06 volumio volumio[1302]: at readableAddChunk (internal/streams/readable.js:267:9) Feb 12 14:51:06 volumio volumio[1302]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Feb 12 14:51:06 volumio volumio[1302]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 12 14:51:06 volumio volumio[1302]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 14:51:06 volumio sudo[1511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-12 14:50 Feb 12 14:51:06 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"