-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-07-07 11:39:04 CEST. -- Jul 07 11:38:35 volumiotop volumio[845]: info: CoreCommandRouter::volumioPlay Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::play index 5 Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::stop Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::stPlaybackTimer Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::updateTrackBlock Jul 07 11:38:35 volumiotop volumio[845]: info: CorePlayQueue::getTrackBlock Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::pushState Jul 07 11:38:35 volumiotop volumio[845]: info: CorePlayQueue::getTrack 1 Jul 07 11:38:35 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 11:38:35 volumiotop volumio[845]: info: CoreCommandRouter::volumioPushState Jul 07 11:38:35 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output update for this device Jul 07 11:38:35 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::serviceStop Jul 07 11:38:35 volumiotop volumio[845]: info: CorePlayQueue::getTrack 1 Jul 07 11:38:35 volumiotop volumio[845]: info: CoreCommandRouter::serviceStop Jul 07 11:38:35 volumiotop volumio[845]: info: [1720345115738] ControllerQobuz::stop Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 07 11:38:35 volumiotop volumio[845]: info: ControllerMpd::stop Jul 07 11:38:35 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Jul 07 11:38:35 volumiotop volumio[845]: STREAMING PROXY: Client dropped request, destroying Jul 07 11:38:35 volumiotop volumio[845]: info: sendMpdCommand stop took 41 milliseconds Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::play index undefined Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 11:38:35 volumiotop volumio[845]: info: CorePlayQueue::getTrack 5 Jul 07 11:38:35 volumiotop volumio[845]: info: CoreStateMachine::startPlaybackTimer Jul 07 11:38:35 volumiotop volumio[845]: info: CorePlayQueue::getTrack 5 Jul 07 11:38:35 volumiotop volumio[845]: info: [1720345115783] ControllerQobuz::clearAddPlayTrack Jul 07 11:38:35 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Jul 07 11:38:35 volumiotop volumio[845]: info: Jul 07 11:38:35 volumiotop volumio[845]: ---------------------------- MPD announces state update: player Jul 07 11:38:35 volumiotop volumio[845]: info: ControllerMpd::getState Jul 07 11:38:35 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 07 11:38:35 volumiotop volumio[845]: info: sendMpdCommand stop took 3 milliseconds Jul 07 11:38:35 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Jul 07 11:38:35 volumiotop volumio[845]: info: Jul 07 11:38:35 volumiotop volumio[845]: ---------------------------- MPD announces system playlist update Jul 07 11:38:35 volumiotop volumio[845]: info: Ignoring MPD Status Update Jul 07 11:38:35 volumiotop volumio[845]: info: sendMpdCommand status took 4 milliseconds Jul 07 11:38:35 volumiotop volumio[845]: info: sendMpdCommand clear took 3 milliseconds Jul 07 11:38:35 volumiotop volumio[845]: verbose: ControllerMpd::parseState Jul 07 11:38:35 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 11:38:35 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/29582546" Jul 07 11:38:35 volumiotop volumio[845]: error: updateQueue error: null Jul 07 11:38:35 volumiotop volumio[845]: info: ------------------------------ 6ms Jul 07 11:38:35 volumiotop volumio[845]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 07 11:38:35 volumiotop volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/29582546 Jul 07 11:38:35 volumiotop volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 07 11:38:35 volumiotop volumio[845]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 07 11:38:35 volumiotop volumio[845]: info: ------------------------------ 14ms Jul 07 11:38:35 volumiotop volumio[845]: info: Executing endpoint getStreamUrlqobuz Jul 07 11:38:35 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 07 11:38:36 volumiotop volumio[845]: info: getStreamUrl took 324 milliseconds Jul 07 11:38:36 volumiotop volumio[845]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=171027&eid=29582546&fmt=7&profile=raw&app_id=539451548&cid=1410007&etsp=1720348716&hmac=zQ_oIGdu7eZCRgEVilrIQFPFTBE Jul 07 11:38:36 volumiotop volumio[845]: STREAMING PROXY: Response: 200, length: 118385155 Jul 07 11:38:36 volumiotop volumio[845]: STREAMING PROXY: Client dropped request, destroying Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/29582546" Jul 07 11:38:36 volumiotop volumio[845]: info: Jul 07 11:38:36 volumiotop volumio[845]: ---------------------------- MPD announces system playlist update Jul 07 11:38:36 volumiotop volumio[845]: info: Ignoring MPD Status Update Jul 07 11:38:36 volumiotop volumio[845]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/29582546" took 2 milliseconds Jul 07 11:38:36 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand play Jul 07 11:38:36 volumiotop volumio[845]: info: ------------------------------ 4ms Jul 07 11:38:36 volumiotop volumio[845]: info: sendMpdCommand play took 3 milliseconds Jul 07 11:38:36 volumiotop volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/29582546 Jul 07 11:38:36 volumiotop volumio[845]: info: Executing endpoint getStreamUrlqobuz Jul 07 11:38:36 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 07 11:38:36 volumiotop volumio[845]: info: getStreamUrl took 252 milliseconds Jul 07 11:38:36 volumiotop volumio[845]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=171027&eid=29582546&fmt=7&profile=raw&app_id=539451548&cid=1410007&etsp=1720348716&hmac=zQ_oIGdu7eZCRgEVilrIQFPFTBE Jul 07 11:38:36 volumiotop volumio[845]: STREAMING PROXY: Response: 200, length: 118385155 Jul 07 11:38:36 volumiotop volumio[845]: info: Jul 07 11:38:36 volumiotop volumio[845]: ---------------------------- MPD announces state update: player Jul 07 11:38:36 volumiotop volumio[845]: info: ControllerMpd::getState Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 07 11:38:36 volumiotop volumio[845]: info: sendMpdCommand status took 2 milliseconds Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::parseState Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 11:38:36 volumiotop volumio[845]: info: Jul 07 11:38:36 volumiotop volumio[845]: ---------------------------- MPD announces state update: player Jul 07 11:38:36 volumiotop volumio[845]: info: ControllerMpd::getState Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 07 11:38:36 volumiotop volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 07 11:38:36 volumiotop volumio[845]: info: ControllerMpd::pushState Jul 07 11:38:36 volumiotop volumio[845]: info: CoreCommandRouter::servicePushState Jul 07 11:38:36 volumiotop volumio[845]: info: CorePlayQueue::getTrack 5 Jul 07 11:38:36 volumiotop volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":370,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"29582546","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/29582546","trackType":"qobuz"} Jul 07 11:38:36 volumiotop volumio[845]: verbose: CURRENT POSITION 5 Jul 07 11:38:36 volumiotop volumio[845]: info: CoreStateMachine::syncState stateService play Jul 07 11:38:36 volumiotop volumio[845]: info: CoreStateMachine::syncState currentStatus stop Jul 07 11:38:36 volumiotop volumio[845]: info: ------------------------------ 12ms Jul 07 11:38:36 volumiotop volumio[845]: info: sendMpdCommand status took 5 milliseconds Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::parseState Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 11:38:36 volumiotop volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 07 11:38:36 volumiotop volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 07 11:38:36 volumiotop volumio[845]: info: ControllerMpd::pushState Jul 07 11:38:36 volumiotop volumio[845]: info: CoreCommandRouter::servicePushState Jul 07 11:38:36 volumiotop volumio[845]: info: CorePlayQueue::getTrack 5 Jul 07 11:38:36 volumiotop volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":370,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"29582546","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/29582546","trackType":"qobuz"} Jul 07 11:38:36 volumiotop volumio[845]: verbose: CURRENT POSITION 5 Jul 07 11:38:36 volumiotop volumio[845]: info: CoreStateMachine::syncState stateService play Jul 07 11:38:36 volumiotop volumio[845]: info: CoreStateMachine::syncState currentStatus play Jul 07 11:38:36 volumiotop volumio[845]: info: Received an update from plugin. extracting info from payload Jul 07 11:38:36 volumiotop volumio[845]: info: CoreStateMachine::pushState Jul 07 11:38:36 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 11:38:36 volumiotop volumio[845]: info: CoreCommandRouter::volumioPushState Jul 07 11:38:36 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output update for this device Jul 07 11:38:36 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output Jul 07 11:38:36 volumiotop volumio[845]: info: CoreStateMachine::pushState Jul 07 11:38:36 volumiotop volumio[845]: info: CoreCommandRouter::volumioPushState Jul 07 11:38:36 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output update for this device Jul 07 11:38:36 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output Jul 07 11:38:36 volumiotop volumio[845]: info: ------------------------------ 51ms Jul 07 11:38:48 volumiotop volumio[845]: info: CoreCommandRouter::volumioPlay Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::play index 7 Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::stop Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::stPlaybackTimer Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::updateTrackBlock Jul 07 11:38:48 volumiotop volumio[845]: info: CorePlayQueue::getTrackBlock Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::pushState Jul 07 11:38:48 volumiotop volumio[845]: info: CorePlayQueue::getTrack 5 Jul 07 11:38:48 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 11:38:48 volumiotop volumio[845]: info: CoreCommandRouter::volumioPushState Jul 07 11:38:48 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output update for this device Jul 07 11:38:48 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::serviceStop Jul 07 11:38:48 volumiotop volumio[845]: info: CorePlayQueue::getTrack 5 Jul 07 11:38:48 volumiotop volumio[845]: info: CoreCommandRouter::serviceStop Jul 07 11:38:48 volumiotop volumio[845]: info: [1720345128078] ControllerQobuz::stop Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 07 11:38:48 volumiotop volumio[845]: info: ControllerMpd::stop Jul 07 11:38:48 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Jul 07 11:38:48 volumiotop volumio[845]: STREAMING PROXY: Client dropped request, destroying Jul 07 11:38:48 volumiotop volumio[845]: info: Jul 07 11:38:48 volumiotop volumio[845]: ---------------------------- MPD announces state update: player Jul 07 11:38:48 volumiotop volumio[845]: info: sendMpdCommand stop took 80 milliseconds Jul 07 11:38:48 volumiotop volumio[845]: info: ControllerMpd::getState Jul 07 11:38:48 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::play index undefined Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 11:38:48 volumiotop volumio[845]: info: CorePlayQueue::getTrack 7 Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::startPlaybackTimer Jul 07 11:38:48 volumiotop volumio[845]: info: CorePlayQueue::getTrack 7 Jul 07 11:38:48 volumiotop volumio[845]: info: [1720345128163] ControllerQobuz::clearAddPlayTrack Jul 07 11:38:48 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Jul 07 11:38:48 volumiotop volumio[845]: info: sendMpdCommand status took 5 milliseconds Jul 07 11:38:48 volumiotop volumio[845]: info: sendMpdCommand stop took 2 milliseconds Jul 07 11:38:48 volumiotop volumio[845]: verbose: ControllerMpd::parseState Jul 07 11:38:48 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 11:38:48 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Jul 07 11:38:48 volumiotop volumio[845]: info: Jul 07 11:38:48 volumiotop volumio[845]: ---------------------------- MPD announces system playlist update Jul 07 11:38:48 volumiotop volumio[845]: info: Ignoring MPD Status Update Jul 07 11:38:48 volumiotop volumio[845]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 07 11:38:48 volumiotop volumio[845]: info: sendMpdCommand clear took 3 milliseconds Jul 07 11:38:48 volumiotop volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 07 11:38:48 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/29582548" Jul 07 11:38:48 volumiotop volumio[845]: info: ControllerMpd::pushState Jul 07 11:38:48 volumiotop volumio[845]: info: CoreCommandRouter::servicePushState Jul 07 11:38:48 volumiotop volumio[845]: info: CoreStateMachine::pushState Jul 07 11:38:48 volumiotop volumio[845]: info: CorePlayQueue::getTrack 7 Jul 07 11:38:48 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 11:38:48 volumiotop volumio[845]: info: CoreCommandRouter::volumioPushState Jul 07 11:38:48 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output update for this device Jul 07 11:38:48 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output Jul 07 11:38:48 volumiotop volumio[845]: info: CorePlayQueue::getTrack 7 Jul 07 11:38:48 volumiotop volumio[845]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 07 11:38:48 volumiotop volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/29582548 Jul 07 11:38:48 volumiotop volumio[845]: info: ------------------------------ 42ms Jul 07 11:38:48 volumiotop volumio[845]: error: updateQueue error: null Jul 07 11:38:48 volumiotop volumio[845]: info: ------------------------------ 34ms Jul 07 11:38:48 volumiotop volumio[845]: info: Executing endpoint getStreamUrlqobuz Jul 07 11:38:48 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 07 11:38:48 volumiotop volumio[845]: info: getStreamUrl took 637 milliseconds Jul 07 11:38:48 volumiotop volumio[845]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=171027&eid=29582548&fmt=7&profile=raw&app_id=539451548&cid=1410007&etsp=1720348728&hmac=i3fl947Q8JIo22jVQQTeYpCv0ng Jul 07 11:38:49 volumiotop volumio[845]: STREAMING PROXY: Response: 200, length: 109017664 Jul 07 11:38:49 volumiotop volumio[845]: STREAMING PROXY: Client dropped request, destroying Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/29582548" Jul 07 11:38:49 volumiotop volumio[845]: info: Jul 07 11:38:49 volumiotop volumio[845]: ---------------------------- MPD announces system playlist update Jul 07 11:38:49 volumiotop volumio[845]: info: Ignoring MPD Status Update Jul 07 11:38:49 volumiotop volumio[845]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/29582548" took 3 milliseconds Jul 07 11:38:49 volumiotop volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand play Jul 07 11:38:49 volumiotop volumio[845]: info: ------------------------------ 4ms Jul 07 11:38:49 volumiotop volumio[845]: info: sendMpdCommand play took 3 milliseconds Jul 07 11:38:49 volumiotop volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/29582548 Jul 07 11:38:49 volumiotop volumio[845]: info: Executing endpoint getStreamUrlqobuz Jul 07 11:38:49 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 07 11:38:49 volumiotop volumio[845]: info: getStreamUrl took 273 milliseconds Jul 07 11:38:49 volumiotop volumio[845]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=171027&eid=29582548&fmt=7&profile=raw&app_id=539451548&cid=1410007&etsp=1720348729&hmac=PViRKziWtRx7LXHokdKYTyXvpy4 Jul 07 11:38:49 volumiotop volumio[845]: STREAMING PROXY: Response: 200, length: 109017664 Jul 07 11:38:49 volumiotop volumio[845]: info: Jul 07 11:38:49 volumiotop volumio[845]: ---------------------------- MPD announces state update: player Jul 07 11:38:49 volumiotop volumio[845]: info: ControllerMpd::getState Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 07 11:38:49 volumiotop volumio[845]: info: Jul 07 11:38:49 volumiotop volumio[845]: ---------------------------- MPD announces state update: player Jul 07 11:38:49 volumiotop volumio[845]: info: sendMpdCommand status took 4 milliseconds Jul 07 11:38:49 volumiotop volumio[845]: info: ControllerMpd::getState Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::parseState Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 11:38:49 volumiotop volumio[845]: info: sendMpdCommand status took 3 milliseconds Jul 07 11:38:49 volumiotop volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::parseState Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 07 11:38:49 volumiotop volumio[845]: info: ControllerMpd::pushState Jul 07 11:38:49 volumiotop volumio[845]: info: CoreCommandRouter::servicePushState Jul 07 11:38:49 volumiotop volumio[845]: info: CorePlayQueue::getTrack 7 Jul 07 11:38:49 volumiotop volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":350,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"29582548","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/29582548","trackType":"qobuz"} Jul 07 11:38:49 volumiotop volumio[845]: verbose: CURRENT POSITION 7 Jul 07 11:38:49 volumiotop volumio[845]: info: CoreStateMachine::syncState stateService play Jul 07 11:38:49 volumiotop volumio[845]: info: CoreStateMachine::syncState currentStatus stop Jul 07 11:38:49 volumiotop volumio[845]: info: ------------------------------ 14ms Jul 07 11:38:49 volumiotop volumio[845]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 07 11:38:49 volumiotop volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 07 11:38:49 volumiotop volumio[845]: info: ControllerMpd::pushState Jul 07 11:38:49 volumiotop volumio[845]: info: CoreCommandRouter::servicePushState Jul 07 11:38:49 volumiotop volumio[845]: info: CorePlayQueue::getTrack 7 Jul 07 11:38:49 volumiotop volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":350,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"29582548","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/29582548","trackType":"qobuz"} Jul 07 11:38:49 volumiotop volumio[845]: verbose: CURRENT POSITION 7 Jul 07 11:38:49 volumiotop volumio[845]: info: CoreStateMachine::syncState stateService play Jul 07 11:38:49 volumiotop volumio[845]: info: CoreStateMachine::syncState currentStatus play Jul 07 11:38:49 volumiotop volumio[845]: info: Received an update from plugin. extracting info from payload Jul 07 11:38:49 volumiotop volumio[845]: info: CoreStateMachine::pushState Jul 07 11:38:49 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 11:38:49 volumiotop volumio[845]: info: CoreCommandRouter::volumioPushState Jul 07 11:38:49 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output update for this device Jul 07 11:38:49 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output Jul 07 11:38:49 volumiotop volumio[845]: info: CoreStateMachine::pushState Jul 07 11:38:49 volumiotop volumio[845]: info: CoreCommandRouter::volumioPushState Jul 07 11:38:49 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output update for this device Jul 07 11:38:49 volumiotop volumio[845]: info: MRS: Pushing multiroomSync output Jul 07 11:38:49 volumiotop volumio[845]: info: ------------------------------ 48ms Jul 07 11:39:03 volumiotop volumio[845]: Searching all installed plugins Jul 07 11:39:03 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 11:39:03 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: , search Jul 07 11:39:03 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 07 11:39:03 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 07 11:39:03 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 07 11:39:03 volumiotop volumio[845]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 07 11:39:03 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 07 11:39:03 volumiotop volumio[845]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Jul 07 11:39:03 volumiotop volumio[845]: info: [1720345143974] ControllerCalmRadio::searchCategories Jul 07 11:39:03 volumiotop volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 11:39:03 volumiotop volumio[845]: TypeError: Cannot read property 'cats' of undefined Jul 07 11:39:03 volumiotop volumio[845]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Jul 07 11:39:03 volumiotop volumio[845]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Jul 07 11:39:03 volumiotop volumio[845]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1076:32) Jul 07 11:39:03 volumiotop volumio[845]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Jul 07 11:39:03 volumiotop volumio[845]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Jul 07 11:39:03 volumiotop volumio[845]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Jul 07 11:39:03 volumiotop volumio[845]: at Socket.emit (events.js:315:20) Jul 07 11:39:03 volumiotop volumio[845]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 07 11:39:03 volumiotop volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 07 11:39:03 volumiotop volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 11:39:04 volumiotop sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-07 11:38 Jul 07 11:39:04 volumiotop sudo[1551]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"