Oct 11 14:48:08 motivo volumio[1278]: Searching plugin music_service/qobuz Oct 11 14:48:08 motivo volumio[1278]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 11 14:48:09 motivo volumio[1278]: info: searchQOBUZUri took 1671 milliseconds Oct 11 14:48:09 motivo volumio[1278]: info: All search sources collected, pushing search results Oct 11 14:48:12 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 592. Oct 11 14:48:12 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:48:12 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:48:12 motivo upmpdcli[17429]: Could not open config: /tmp/upmpdcli.conf Oct 11 14:48:12 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 11 14:48:12 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 11 14:48:22 motivo volumio[1278]: Searching plugin music_service/qobuz Oct 11 14:48:22 motivo volumio[1278]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 11 14:48:23 motivo volumio[1278]: info: searchQOBUZUri took 727 milliseconds Oct 11 14:48:23 motivo volumio[1278]: info: All search sources collected, pushing search results Oct 11 14:48:24 motivo volumio[1278]: Searching plugin music_service/qobuz Oct 11 14:48:24 motivo volumio[1278]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 11 14:48:25 motivo volumio[1278]: info: searchQOBUZUri took 1120 milliseconds Oct 11 14:48:25 motivo volumio[1278]: info: All search sources collected, pushing search results Oct 11 14:48:27 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 593. Oct 11 14:48:27 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:48:27 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:48:27 motivo upmpdcli[17445]: Could not open config: /tmp/upmpdcli.conf Oct 11 14:48:27 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 11 14:48:27 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 11 14:48:42 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 594. Oct 11 14:48:42 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:48:42 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:48:42 motivo upmpdcli[17477]: Could not open config: /tmp/upmpdcli.conf Oct 11 14:48:42 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 11 14:48:42 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 11 14:48:50 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::ClearQueue Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::stPlaybackTimer Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::updateTrackBlock Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::getTrackBlock Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::serviceStop Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::serviceStop Oct 11 14:48:50 motivo volumio[1278]: info: [1791740930391] ControllerQobuz::stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::stop Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::clearPlayQueue Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::addQueueItems Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::addQueueItems Oct 11 14:48:50 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:48:50 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3960704 Oct 11 14:48:50 motivo volumio[1278]: info: Exploding uri qobuz://song/3960704 in service qobuz Oct 11 14:48:50 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is not playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipped samples monitor stopped Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: info: Oct 11 14:48:50 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand stop took 49 milliseconds Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand status took 3 milliseconds Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:48:50 motivo volumio[1278]: info: Oct 11 14:48:50 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:48:50 motivo volumio[1278]: info: Oct 11 14:48:50 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:48:50 motivo volumio[1278]: info: Oct 11 14:48:50 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand status took 10 milliseconds Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand status took 7 milliseconds Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand status took 5 milliseconds Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:48:50 motivo volumio[1278]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=54167465&fmt=6&profile=raw&app_id=539451548&cid=4319421&etsp=1791744457&hmac=OydnKCzJfMmMcBqii20ftPRL4mA","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=54167465&fmt=6&profile=raw&app_id=539451548&cid=4319421&etsp=1791744457&hmac=OydnKCzJfMmMcBqii20ftPRL4mA","trackType":"qobuz"} Oct 11 14:48:50 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::syncState stateService stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: No code Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: ------------------------------ 55ms Oct 11 14:48:50 motivo volumio[1278]: info: MCU Signalled Playback Inactive Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 11 14:48:50 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:48:50 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:48:50 motivo volumio[1278]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=54167465&fmt=6&profile=raw&app_id=539451548&cid=4319421&etsp=1791744457&hmac=OydnKCzJfMmMcBqii20ftPRL4mA","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=54167465&fmt=6&profile=raw&app_id=539451548&cid=4319421&etsp=1791744457&hmac=OydnKCzJfMmMcBqii20ftPRL4mA","trackType":"qobuz"} Oct 11 14:48:50 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::syncState stateService stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: No code Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:48:50 motivo volumio[1278]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=54167465&fmt=6&profile=raw&app_id=539451548&cid=4319421&etsp=1791744457&hmac=OydnKCzJfMmMcBqii20ftPRL4mA","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=54167465&fmt=6&profile=raw&app_id=539451548&cid=4319421&etsp=1791744457&hmac=OydnKCzJfMmMcBqii20ftPRL4mA","trackType":"qobuz"} Oct 11 14:48:50 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::syncState stateService stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: No code Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:48:50 motivo volumio[1278]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=54167465&fmt=6&profile=raw&app_id=539451548&cid=4319421&etsp=1791744457&hmac=OydnKCzJfMmMcBqii20ftPRL4mA","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=54167465&fmt=6&profile=raw&app_id=539451548&cid=4319421&etsp=1791744457&hmac=OydnKCzJfMmMcBqii20ftPRL4mA","trackType":"qobuz"} Oct 11 14:48:50 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::syncState stateService stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus stop Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: No code Oct 11 14:48:50 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:48:50 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:48:50 motivo volumio[1278]: info: ------------------------------ 75ms Oct 11 14:48:50 motivo volumio[1278]: info: ------------------------------ 75ms Oct 11 14:48:50 motivo volumio[1278]: info: ------------------------------ 71ms Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:48:50 motivo volumio[1278]: info: MCU Signalled Playback Active Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:50 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:51 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 11 14:48:51 motivo volumio[1278]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 11 14:48:51 motivo volumio[1278]: info: explodeUri took 827 milliseconds Oct 11 14:48:51 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:51 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3960704 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:51 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:48:51 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:48:51 motivo volumio[1278]: info: CoreStateMachine::updateTrackBlock Oct 11 14:48:51 motivo volumio[1278]: info: CorePlayQueue::getTrackBlock Oct 11 14:48:51 motivo volumio[1278]: info: CoreCommandRouter::volumioPlay Oct 11 14:48:51 motivo volumio[1278]: info: CoreStateMachine::play index 0 Oct 11 14:48:51 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:48:51 motivo volumio[1278]: info: CoreStateMachine::addQueueItems Oct 11 14:48:51 motivo volumio[1278]: info: CorePlayQueue::addQueueItems Oct 11 14:48:51 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427251705 Oct 11 14:48:51 motivo volumio[1278]: info: Using cached record of: qobuz://song/427251705 Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3960549 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/3960549 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/118827399 Oct 11 14:48:51 motivo volumio[1278]: info: Using cached record of: qobuz://song/118827399 Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3639275 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/3639275 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/11182340 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/11182340 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/54650668 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/54650668 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253249 Oct 11 14:48:51 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253249 Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253325 Oct 11 14:48:51 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253325 Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3033607 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/3033607 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427254643 Oct 11 14:48:51 motivo volumio[1278]: info: Using cached record of: qobuz://song/427254643 Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253995 Oct 11 14:48:51 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253995 Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/437212387 Oct 11 14:48:51 motivo volumio[1278]: info: Using cached record of: qobuz://song/437212387 Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/84453614 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/84453614 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/344276597 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/344276597 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3033109 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/3033109 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/52979745 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/52979745 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427254053 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/427254053 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/99603278 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/99603278 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/20711162 Oct 11 14:48:51 motivo volumio[1278]: info: Exploding uri qobuz://song/20711162 in service qobuz Oct 11 14:48:51 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:48:51 motivo volumio[1278]: info: CoreStateMachine::stop Oct 11 14:48:51 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:48:51 motivo volumio[1278]: info: CoreStateMachine::play index undefined Oct 11 14:48:51 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:48:51 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:48:51 motivo volumio[1278]: info: explodeUri took 484 milliseconds Oct 11 14:48:51 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:51 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3960549 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:51 motivo volumio[1278]: info: explodeUri took 719 milliseconds Oct 11 14:48:51 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:51 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3033607 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 767 milliseconds Oct 11 14:48:52 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/54650668 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 790 milliseconds Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 815 milliseconds Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 820 milliseconds Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 867 milliseconds Oct 11 14:48:52 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/11182340 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 878 milliseconds Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 927 milliseconds Oct 11 14:48:52 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3033109 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 958 milliseconds Oct 11 14:48:52 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3639275 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 996 milliseconds Oct 11 14:48:52 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/344276597 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: info: explodeUri took 1006 milliseconds Oct 11 14:48:52 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/84453614 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:48:52 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:48:52 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:48:52 motivo volumio[1278]: info: CoreStateMachine::updateTrackBlock Oct 11 14:48:52 motivo volumio[1278]: info: CorePlayQueue::getTrackBlock Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:53 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:53 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:55 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:55 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:57 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:57 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:48:57 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 595. Oct 11 14:48:57 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:48:57 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:48:57 motivo upmpdcli[17508]: Could not open config: /tmp/upmpdcli.conf Oct 11 14:48:57 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 11 14:48:57 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 11 14:48:59 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:48:59 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:00 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:49:00 motivo volumio[1278]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 14:49:00 motivo volumio[1278]: info: CoreStateMachine::ClearQueue Oct 11 14:49:00 motivo volumio[1278]: info: CoreStateMachine::stop Oct 11 14:49:00 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:00 motivo volumio[1278]: info: CorePlayQueue::clearPlayQueue Oct 11 14:49:00 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:49:00 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:49:00 motivo volumio[1278]: info: CoreStateMachine::addQueueItems Oct 11 14:49:00 motivo volumio[1278]: info: CorePlayQueue::addQueueItems Oct 11 14:49:00 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:49:00 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3960704 Oct 11 14:49:00 motivo volumio[1278]: info: Exploding uri qobuz://song/3960704 in service qobuz Oct 11 14:49:00 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:00 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427251705 Oct 11 14:49:00 motivo volumio[1278]: info: Using cached record of: qobuz://song/427251705 Oct 11 14:49:01 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:01 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:01 motivo volumio[1278]: info: explodeUri took 633 milliseconds Oct 11 14:49:01 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:01 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3960704 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:01 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:49:01 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:49:01 motivo volumio[1278]: info: CoreStateMachine::updateTrackBlock Oct 11 14:49:01 motivo volumio[1278]: info: CorePlayQueue::getTrackBlock Oct 11 14:49:01 motivo volumio[1278]: info: CoreCommandRouter::volumioPlay Oct 11 14:49:01 motivo volumio[1278]: info: CoreStateMachine::play index 1 Oct 11 14:49:01 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:01 motivo volumio[1278]: info: CoreStateMachine::addQueueItems Oct 11 14:49:01 motivo volumio[1278]: info: CorePlayQueue::addQueueItems Oct 11 14:49:01 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3960549 Oct 11 14:49:01 motivo volumio[1278]: info: Exploding uri qobuz://song/3960549 in service qobuz Oct 11 14:49:01 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/118827399 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/118827399 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3639275 Oct 11 14:49:01 motivo volumio[1278]: info: Exploding uri qobuz://song/3639275 in service qobuz Oct 11 14:49:01 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/11182340 Oct 11 14:49:01 motivo volumio[1278]: info: Exploding uri qobuz://song/11182340 in service qobuz Oct 11 14:49:01 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/54650668 Oct 11 14:49:01 motivo volumio[1278]: info: Exploding uri qobuz://song/54650668 in service qobuz Oct 11 14:49:01 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253249 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253249 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253325 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253325 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3033607 Oct 11 14:49:01 motivo volumio[1278]: info: Exploding uri qobuz://song/3033607 in service qobuz Oct 11 14:49:01 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427254643 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/427254643 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253995 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253995 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/437212387 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/437212387 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/84453614 Oct 11 14:49:01 motivo volumio[1278]: info: Exploding uri qobuz://song/84453614 in service qobuz Oct 11 14:49:01 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/344276597 Oct 11 14:49:01 motivo volumio[1278]: info: Exploding uri qobuz://song/344276597 in service qobuz Oct 11 14:49:01 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3033109 Oct 11 14:49:01 motivo volumio[1278]: info: Exploding uri qobuz://song/3033109 in service qobuz Oct 11 14:49:01 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/52979745 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/52979745 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427254053 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/427254053 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/99603278 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/99603278 Oct 11 14:49:01 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/20711162 Oct 11 14:49:01 motivo volumio[1278]: info: Using cached record of: qobuz://song/20711162 Oct 11 14:49:01 motivo volumio[1278]: info: CoreStateMachine::stop Oct 11 14:49:01 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:01 motivo volumio[1278]: info: CoreStateMachine::play index undefined Oct 11 14:49:01 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:01 motivo volumio[1278]: info: CorePlayQueue::getTrack 1 Oct 11 14:49:01 motivo volumio[1278]: info: explodeUri took 450 milliseconds Oct 11 14:49:01 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:01 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3960549 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:01 motivo volumio[1278]: info: explodeUri took 617 milliseconds Oct 11 14:49:01 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:01 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3639275 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: info: explodeUri took 654 milliseconds Oct 11 14:49:02 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3033607 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: info: explodeUri took 741 milliseconds Oct 11 14:49:02 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/344276597 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: info: explodeUri took 748 milliseconds Oct 11 14:49:02 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/54650668 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: info: explodeUri took 753 milliseconds Oct 11 14:49:02 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/84453614 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: info: explodeUri took 807 milliseconds Oct 11 14:49:02 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/11182340 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: info: explodeUri took 892 milliseconds Oct 11 14:49:02 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3033109 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::updateTrackBlock Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::getTrackBlock Oct 11 14:49:02 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:49:02 motivo volumio[1278]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::ClearQueue Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::stop Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::clearPlayQueue Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:49:02 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::addQueueItems Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::addQueueItems Oct 11 14:49:02 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3960704 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/3960704 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427251705 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/427251705 Oct 11 14:49:02 motivo volumio[1278]: info: explodeUri took 338 milliseconds Oct 11 14:49:02 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3960704 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:02 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::updateTrackBlock Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::getTrackBlock Oct 11 14:49:02 motivo volumio[1278]: info: CoreCommandRouter::volumioPlay Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::play index 1 Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::addQueueItems Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::addQueueItems Oct 11 14:49:02 motivo volumio[1278]: info: Preload queue cleared Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3960549 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/3960549 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/118827399 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/118827399 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3639275 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/3639275 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/11182340 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/11182340 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/54650668 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/54650668 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253249 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253249 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253325 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253325 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3033607 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/3033607 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427254643 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/427254643 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427253995 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/427253995 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/437212387 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/437212387 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/84453614 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/84453614 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/344276597 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/344276597 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/3033109 Oct 11 14:49:02 motivo volumio[1278]: info: Exploding uri qobuz://song/3033109 in service qobuz Oct 11 14:49:02 motivo volumio[1278]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/52979745 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/52979745 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/427254053 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/427254053 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/99603278 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/99603278 Oct 11 14:49:02 motivo volumio[1278]: info: Adding Item to queue: qobuz://song/20711162 Oct 11 14:49:02 motivo volumio[1278]: info: Using cached record of: qobuz://song/20711162 Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::stop Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::play index undefined Oct 11 14:49:02 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:02 motivo volumio[1278]: info: CorePlayQueue::getTrack 1 Oct 11 14:49:03 motivo volumio[1278]: info: explodeUri took 534 milliseconds Oct 11 14:49:03 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3960549 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: info: explodeUri took 569 milliseconds Oct 11 14:49:03 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3033109 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: info: explodeUri took 583 milliseconds Oct 11 14:49:03 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/11182340 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: info: explodeUri took 632 milliseconds Oct 11 14:49:03 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/84453614 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: info: explodeUri took 661 milliseconds Oct 11 14:49:03 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/54650668 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: info: explodeUri took 682 milliseconds Oct 11 14:49:03 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3033607 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: info: explodeUri took 725 milliseconds Oct 11 14:49:03 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/344276597 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: info: explodeUri took 843 milliseconds Oct 11 14:49:03 motivo volumio[1278]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: error: Commandrouter: Cannot explode uri qobuz://song/3639275 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 11 14:49:03 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:49:03 motivo volumio[1278]: info: CorePlayQueue::saveQueue Oct 11 14:49:03 motivo volumio[1278]: info: CoreStateMachine::updateTrackBlock Oct 11 14:49:03 motivo volumio[1278]: info: CorePlayQueue::getTrackBlock Oct 11 14:49:07 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:07 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:09 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:09 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:10 motivo volumio[1278]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 11 14:49:10 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:10 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:13 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 596. Oct 11 14:49:13 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:49:13 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:49:13 motivo upmpdcli[17538]: Could not open config: /tmp/upmpdcli.conf Oct 11 14:49:13 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 11 14:49:13 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 11 14:49:17 motivo volumio[1278]: info: CoreCommandRouter::volumioMoveQueue Oct 11 14:49:17 motivo volumio[1278]: info: CoreStateMachine::moveQueueItem 0 0 Oct 11 14:49:17 motivo volumio[1278]: info: CorePlayQueue::moveQueueItem 0 --> 0 Oct 11 14:49:17 motivo volumio[1278]: info: CoreCommandRouter::volumioPushQueue Oct 11 14:49:18 motivo volumio[1278]: info: CoreCommandRouter::volumioPlay Oct 11 14:49:18 motivo volumio[1278]: info: CoreStateMachine::play index 0 Oct 11 14:49:18 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:18 motivo volumio[1278]: info: CoreStateMachine::stop Oct 11 14:49:18 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:18 motivo volumio[1278]: info: CoreStateMachine::play index undefined Oct 11 14:49:18 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 14:49:18 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:18 motivo volumio[1278]: info: CoreStateMachine::startPlaybackTimer Oct 11 14:49:18 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:18 motivo volumio[1278]: info: [1791740958183] ControllerQobuz::clearAddPlayTrack Oct 11 14:49:18 motivo volumio[1278]: info: getStreamUrl took 618 milliseconds Oct 11 14:49:18 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 14:49:18 motivo volumio[1278]: info: sendMpdCommand stop took 1 milliseconds Oct 11 14:49:18 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 14:49:18 motivo volumio[1278]: info: Oct 11 14:49:18 motivo volumio[1278]: ---------------------------- MPD announces system playlist update Oct 11 14:49:18 motivo volumio[1278]: info: Ignoring MPD Status Update Oct 11 14:49:18 motivo volumio[1278]: info: sendMpdCommand clear took 2 milliseconds Oct 11 14:49:18 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M" Oct 11 14:49:18 motivo volumio[1278]: info: Oct 11 14:49:18 motivo volumio[1278]: ---------------------------- MPD announces system playlist update Oct 11 14:49:18 motivo volumio[1278]: info: Ignoring MPD Status Update Oct 11 14:49:18 motivo volumio[1278]: info: Oct 11 14:49:18 motivo volumio[1278]: ---------------------------- MPD announces system playlist update Oct 11 14:49:18 motivo volumio[1278]: info: Ignoring MPD Status Update Oct 11 14:49:18 motivo volumio[1278]: info: Oct 11 14:49:18 motivo volumio[1278]: ---------------------------- MPD announces system playlist update Oct 11 14:49:18 motivo volumio[1278]: info: Ignoring MPD Status Update Oct 11 14:49:18 motivo volumio[1278]: error: updateQueue error: null Oct 11 14:49:18 motivo volumio[1278]: info: ------------------------------ 5ms Oct 11 14:49:18 motivo volumio[1278]: error: updateQueue error: null Oct 11 14:49:18 motivo volumio[1278]: error: updateQueue error: null Oct 11 14:49:18 motivo volumio[1278]: error: updateQueue error: null Oct 11 14:49:18 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M" Oct 11 14:49:18 motivo volumio[1278]: info: ------------------------------ 38ms Oct 11 14:49:18 motivo volumio[1278]: info: ------------------------------ 37ms Oct 11 14:49:18 motivo volumio[1278]: info: ------------------------------ 36ms Oct 11 14:49:18 motivo volumio[1278]: info: Oct 11 14:49:18 motivo volumio[1278]: ---------------------------- MPD announces system playlist update Oct 11 14:49:18 motivo volumio[1278]: info: Ignoring MPD Status Update Oct 11 14:49:18 motivo volumio[1278]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M" took 2 milliseconds Oct 11 14:49:18 motivo volumio[1278]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 14:49:18 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand play Oct 11 14:49:18 motivo volumio[1278]: info: Oct 11 14:49:18 motivo volumio[1278]: ---------------------------- MPD announces system playlist update Oct 11 14:49:18 motivo volumio[1278]: info: Ignoring MPD Status Update Oct 11 14:49:18 motivo volumio[1278]: info: Oct 11 14:49:18 motivo volumio[1278]: ---------------------------- MPD announces system playlist update Oct 11 14:49:18 motivo volumio[1278]: info: Ignoring MPD Status Update Oct 11 14:49:18 motivo volumio[1278]: info: Oct 11 14:49:18 motivo volumio[1278]: ---------------------------- MPD announces system playlist update Oct 11 14:49:18 motivo volumio[1278]: info: Ignoring MPD Status Update Oct 11 14:49:18 motivo volumio[1278]: info: ------------------------------ 6ms Oct 11 14:49:18 motivo volumio[1278]: info: sendMpdCommand play took 6 milliseconds Oct 11 14:49:18 motivo volumio[1278]: info: ------------------------------ 5ms Oct 11 14:49:18 motivo volumio[1278]: info: ------------------------------ 3ms Oct 11 14:49:18 motivo volumio[1278]: info: ------------------------------ 2ms Oct 11 14:49:19 motivo volumio[1278]: info: Oct 11 14:49:19 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:49:19 motivo volumio[1278]: info: Oct 11 14:49:19 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:49:19 motivo volumio[1278]: info: Oct 11 14:49:19 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:49:19 motivo volumio[1278]: info: Oct 11 14:49:19 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:49:19 motivo kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Oct 11 14:49:19 motivo volumio[1278]: info: Oct 11 14:49:19 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand status took 10 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand status took 9 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand status took 10 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand status took 8 milliseconds Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:49:19 motivo volumio[1278]: info: Oct 11 14:49:19 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:49:19 motivo volumio[1278]: info: Oct 11 14:49:19 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:49:19 motivo volumio[1278]: info: Oct 11 14:49:19 motivo volumio[1278]: ---------------------------- MPD announces state update: player Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::getState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand status took 8 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand status took 5 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand status took 4 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand status took 2 milliseconds Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseState Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:49:19 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:19 motivo volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","trackType":"qobuz"} Oct 11 14:49:19 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState stateService play Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus stop Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:49:19 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:19 motivo volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","trackType":"qobuz"} Oct 11 14:49:19 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState stateService play Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus play Oct 11 14:49:19 motivo volumio[1278]: info: Received an update from plugin. extracting info from payload Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:49:19 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:19 motivo volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","trackType":"qobuz"} Oct 11 14:49:19 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState stateService play Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus play Oct 11 14:49:19 motivo volumio[1278]: info: Received an update from plugin. extracting info from payload Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:49:19 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:19 motivo volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","trackType":"qobuz"} Oct 11 14:49:19 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState stateService play Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus play Oct 11 14:49:19 motivo volumio[1278]: info: Received an update from plugin. extracting info from payload Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: ------------------------------ 52ms Oct 11 14:49:19 motivo volumio[1278]: info: ------------------------------ 71ms Oct 11 14:49:19 motivo volumio[1278]: info: ------------------------------ 72ms Oct 11 14:49:19 motivo volumio[1278]: info: ------------------------------ 72ms Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 56 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 11 14:49:19 motivo volumio[1278]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:49:19 motivo volumio[1278]: verbose: ControllerMpd::parseTrackInfo Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:49:19 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:19 motivo volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","trackType":"qobuz"} Oct 11 14:49:19 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState stateService play Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus play Oct 11 14:49:19 motivo volumio[1278]: info: Received an update from plugin. extracting info from payload Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:49:19 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:19 motivo volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","trackType":"qobuz"} Oct 11 14:49:19 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState stateService play Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus play Oct 11 14:49:19 motivo volumio[1278]: info: Received an update from plugin. extracting info from payload Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:49:19 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:19 motivo volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","trackType":"qobuz"} Oct 11 14:49:19 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState stateService play Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus play Oct 11 14:49:19 motivo volumio[1278]: info: Received an update from plugin. extracting info from payload Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: ControllerMpd::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::servicePushState Oct 11 14:49:19 motivo volumio[1278]: info: CorePlayQueue::getTrack 0 Oct 11 14:49:19 motivo volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=10124236&eid=427251705&fmt=7&profile=raw&app_id=539451548&cid=4319421&etsp=1791744558&hmac=kCWfE1BRsMDjT9k63dhtaMa-k0M","trackType":"qobuz"} Oct 11 14:49:19 motivo volumio[1278]: verbose: CURRENT POSITION 0 Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState stateService play Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::syncState currentStatus play Oct 11 14:49:19 motivo volumio[1278]: info: Received an update from plugin. extracting info from payload Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: CoreStateMachine::pushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioPushState Oct 11 14:49:19 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:19 motivo volumio[1278]: info: ------------------------------ 126ms Oct 11 14:49:19 motivo volumio[1278]: info: ------------------------------ 125ms Oct 11 14:49:19 motivo volumio[1278]: info: ------------------------------ 125ms Oct 11 14:49:19 motivo volumio[1278]: info: ------------------------------ 125ms Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - Volumio is playing Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: info: Signalling Playback active due to playback status change Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Oct 11 14:49:19 motivo volumio[1278]: info: FusionDsp - ---- read samplerate from file: 96000 Oct 11 14:49:19 motivo volumio[1278]: info: camilladsp stopping service pid 17505... Oct 11 14:49:20 motivo volumio[1278]: grep: /proc/17505/cmdline: binary file matches Oct 11 14:49:20 motivo volumio[1278]: grep: /proc/17505/cmdline: binary file matches Oct 11 14:49:20 motivo volumio[1278]: grep: /proc/17505/cmdline: binary file matches Oct 11 14:49:20 motivo volumio[1278]: grep: /proc/17505/cmdline: binary file matches Oct 11 14:49:20 motivo volumio[1278]: grep: /proc/17505/cmdline: binary file matches Oct 11 14:49:20 motivo volumio[1278]: info: camilladsp service terminated, instance 1 Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 11 14:49:20 motivo volumio[1278]: info: camilladsp service started and running in background, instance 1 Oct 11 14:49:20 motivo volumio[1278]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:20 motivo volumio[1278]: error: FusionDsp - Monitor WebSocket error: [object Object] Oct 11 14:49:20 motivo volumio[1278]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Oct 11 14:49:21 motivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 11 14:49:21 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:49:21 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:49:21 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:49:21 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:49:21 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:49:21 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:49:21 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:49:21 motivo volumio[1278]: info: FusionDsp - Clipping Monitor started Oct 11 14:49:22 motivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 11 14:49:22 motivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 11 14:49:25 motivo volumio[1278]: info: CoreCommandRouter::volumioGetState Oct 11 14:49:28 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 597. Oct 11 14:49:28 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:49:28 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:49:28 motivo upmpdcli[17618]: Could not open config: /tmp/upmpdcli.conf Oct 11 14:49:28 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 11 14:49:28 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 11 14:49:43 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 598. Oct 11 14:49:43 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:49:43 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 11 14:49:43 motivo upmpdcli[17648]: Could not open config: /tmp/upmpdcli.conf Oct 11 14:49:43 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 11 14:49:43 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 11 14:49:44 motivo volumio[1278]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Oct 11 14:49:44 motivo volumio[1278]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 14:49:44 motivo volumio[1278]: TypeError: Cannot read properties of undefined (reading 'then') Oct 11 14:49:44 motivo volumio[1278]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Oct 11 14:49:44 motivo volumio[1278]: at Socket.emit (node:events:514:28) Oct 11 14:49:44 motivo volumio[1278]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 11 14:49:44 motivo volumio[1278]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 11 14:49:44 motivo volumio[1278]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 14:49:45 motivo sudo[17663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 14:48' Oct 11 14:49:45 motivo sudo[17663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="9ccd1247f8cab3c5d64c23a96d243f6bfa34d032" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="e94c5b27ab2607d3918cd6a4973c240c1b2ce37e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 16 00:11:09 UTC 2026" VOLUMIO_VERSION="4.156" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="67ebfb15187d4de64266b21b13da5443"