-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2024-07-15 12:10:27 CEST. --
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::volumioPause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::pause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::stPlaybackTimer
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::servicePause
Jul 15 12:10:01 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::servicePause
Jul 15 12:10:01 motivo volumio[988]: info: [1721038201118] ControllerQobuz::pause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 15 12:10:01 motivo volumio[988]: info: ControllerMpd::pause
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand pause
Jul 15 12:10:01 motivo volumio[988]: info: sendMpdCommand pause took 7 milliseconds
Jul 15 12:10:01 motivo volumio[988]: info:
Jul 15 12:10:01 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:01 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:01 motivo volumio[988]: info:
Jul 15 12:10:01 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:01 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:01 motivo volumio[988]: info:
Jul 15 12:10:01 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:01 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:01 motivo volumio[988]: info: sendMpdCommand status took 3 milliseconds
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:01 motivo volumio[988]: info: sendMpdCommand status took 3 milliseconds
Jul 15 12:10:01 motivo volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:01 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:01 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:01 motivo volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":231493,"duration":409,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2599 Kbps","isStreaming":false,"title":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:01 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::syncState stateService pause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus pause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::stPlaybackTimer
Jul 15 12:10:01 motivo volumio[988]: info: ------------------------------ 18ms
Jul 15 12:10:01 motivo volumio[988]: info: sendMpdCommand playlistinfo took 12 milliseconds
Jul 15 12:10:01 motivo volumio[988]: info: sendMpdCommand playlistinfo took 12 milliseconds
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:01 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:01 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:01 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:01 motivo volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":231493,"duration":409,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2599 Kbps","isStreaming":false,"title":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:01 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::syncState stateService pause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus pause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::stPlaybackTimer
Jul 15 12:10:01 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:01 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:01 motivo volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":231493,"duration":409,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2599 Kbps","isStreaming":false,"title":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:01 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::syncState stateService pause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus pause
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:01 motivo volumio[988]: info: CoreStateMachine::stPlaybackTimer
Jul 15 12:10:01 motivo volumio[988]: info: ------------------------------ 34ms
Jul 15 12:10:01 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:01 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:01 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:01 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:01 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:01 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:01 motivo volumio[988]: info: MCU Signalled Playback Inactive
Jul 15 12:10:02 motivo volumio[988]: info: CoreCommandRouter::volumioGetState
Jul 15 12:10:04 motivo volumio[988]: info: Executing endpoint metavolumio
Jul 15 12:10:04 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 15 12:10:04 motivo volumio[988]: info: Executing endpoint metavolumio
Jul 15 12:10:04 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 15 12:10:05 motivo volumio[988]: info: Executing endpoint metavolumio
Jul 15 12:10:05 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 15 12:10:05 motivo volumio[988]: info: Executing endpoint metavolumio
Jul 15 12:10:05 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 15 12:10:10 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 15 12:10:10 motivo volumio[988]: info: handleBrowseUri took 207 milliseconds
Jul 15 12:10:10 motivo volumio[988]: info: Preload queue cleared
Jul 15 12:10:10 motivo volumio[988]: info: Preload queue cleared
Jul 15 12:10:12 motivo volumio[988]: error: Failed request for metavolumio API
Jul 15 12:10:14 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 15 12:10:15 motivo volumio[988]: info: handleBrowseUri took 1118 milliseconds
Jul 15 12:10:15 motivo volumio[988]: info: Preload queue cleared
Jul 15 12:10:20 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 15 12:10:20 motivo volumio[988]: info: handleBrowseUri took 522 milliseconds
Jul 15 12:10:20 motivo volumio[988]: info: Preload queue cleared
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198436
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198437
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198438
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198439
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198440
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198441
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198442
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198443
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198444
Jul 15 12:10:20 motivo volumio[988]: info: Preloading song: qobuz://song/164198445
Jul 15 12:10:20 motivo volumio[988]: info: Exploding uri qobuz://song/164198436 in service qobuz
Jul 15 12:10:20 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:20 motivo volumio[988]: info: Exploding uri qobuz://song/164198437 in service qobuz
Jul 15 12:10:20 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: Exploding uri qobuz://song/164198438 in service qobuz
Jul 15 12:10:21 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: Exploding uri qobuz://song/164198439 in service qobuz
Jul 15 12:10:21 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: Exploding uri qobuz://song/164198440 in service qobuz
Jul 15 12:10:21 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: Exploding uri qobuz://song/164198441 in service qobuz
Jul 15 12:10:21 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: Executing endpoint getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: Exploding uri qobuz://song/164198442 in service qobuz
Jul 15 12:10:21 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: Executing endpoint getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: Executing endpoint getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: Executing endpoint getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: Executing endpoint getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: Executing endpoint getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 15 12:10:21 motivo volumio[988]: info: Exploding uri qobuz://song/164198443 in service qobuz
Jul 15 12:10:21 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 403 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: Exploding uri qobuz://song/164198444 in service qobuz
Jul 15 12:10:21 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: Exploding uri qobuz://song/164198445 in service qobuz
Jul 15 12:10:21 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 413 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 471 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 484 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 419 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 492 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 429 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 409 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 503 milliseconds
Jul 15 12:10:21 motivo volumio[988]: info: explodeUri took 404 milliseconds
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::volumioSeek
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::seek
Jul 15 12:10:22 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:22 motivo volumio[988]: info: TRACKBLOCK {"uri":"qobuz://song/255505383","service":"qobuz","name":"Ave Maria","title":"Ave Maria","album":"History of Tango","type":"track","tracknumber":3,"volumeNumber":1,"albumart":"https://static.qobuz.com/images/covers/ec/sj/izfnybif9sjec_600.jpg","duration":408,"explicit":false,"artist":"Artyom Dervoed","artistUri":"qobuz://artist/41591","albumUri":"qobuz://album/izfnybif9sjec","audioQuality":"","tagImage":""}
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::startPlaybackTimer
Jul 15 12:10:22 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:22 motivo volumio[988]: info: [1721038222637] ControllerQobuz::seek
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 15 12:10:22 motivo volumio[988]: info: ControllerMpd::seek
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:22 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:22 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:22 motivo volumio[988]: error: null
Jul 15 12:10:22 motivo volumio[988]: info:
Jul 15 12:10:22 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:22 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:22 motivo volumio[988]: info:
Jul 15 12:10:22 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:22 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:22 motivo volumio[988]: info:
Jul 15 12:10:22 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:22 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:22 motivo volumio[988]: info: sendMpdCommand status took 5 milliseconds
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:22 motivo volumio[988]: info: sendMpdCommand status took 5 milliseconds
Jul 15 12:10:22 motivo volumio[988]: info: sendMpdCommand status took 5 milliseconds
Jul 15 12:10:22 motivo volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:22 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:22 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:22 motivo volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37000,"duration":409,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2599 Kbps","isStreaming":false,"title":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:22 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::syncState stateService pause
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus pause
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::stPlaybackTimer
Jul 15 12:10:22 motivo volumio[988]: info: ------------------------------ 21ms
Jul 15 12:10:22 motivo volumio[988]: info: sendMpdCommand playlistinfo took 12 milliseconds
Jul 15 12:10:22 motivo volumio[988]: info: sendMpdCommand playlistinfo took 11 milliseconds
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:22 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:22 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:22 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:22 motivo volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37000,"duration":409,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2599 Kbps","isStreaming":false,"title":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:22 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::syncState stateService pause
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus pause
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::stPlaybackTimer
Jul 15 12:10:22 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:22 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:22 motivo volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37000,"duration":409,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2599 Kbps","isStreaming":false,"title":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:22 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::syncState stateService pause
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus pause
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:22 motivo volumio[988]: info: CoreStateMachine::stPlaybackTimer
Jul 15 12:10:22 motivo volumio[988]: info: ------------------------------ 38ms
Jul 15 12:10:22 motivo volumio[988]: info: ------------------------------ 36ms
Jul 15 12:10:22 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:22 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:22 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:22 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:22 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:23 motivo volumio[988]: info: Preload queue cleared
Jul 15 12:10:23 motivo volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::ClearQueue
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::stop
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::updateTrackBlock
Jul 15 12:10:23 motivo volumio[988]: info: CorePlayQueue::getTrackBlock
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::stPlaybackTimer
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:23 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:23 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 15 12:10:23 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::serviceStop
Jul 15 12:10:23 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:23 motivo volumio[988]: info: CoreCommandRouter::serviceStop
Jul 15 12:10:23 motivo volumio[988]: info: [1721038223994] ControllerQobuz::stop
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 15 12:10:23 motivo volumio[988]: info: ControllerMpd::stop
Jul 15 12:10:23 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand stop
Jul 15 12:10:23 motivo volumio[988]: info: CorePlayQueue::clearPlayQueue
Jul 15 12:10:23 motivo volumio[988]: info: CorePlayQueue::saveQueue
Jul 15 12:10:23 motivo volumio[988]: info: CoreCommandRouter::volumioPushQueue
Jul 15 12:10:23 motivo volumio[988]: info: CoreStateMachine::addQueueItems
Jul 15 12:10:23 motivo volumio[988]: info: CorePlayQueue::addQueueItems
Jul 15 12:10:23 motivo volumio[988]: info: Preload queue cleared
Jul 15 12:10:23 motivo volumio[988]: info: Adding Item to queue: qobuz://album/yy6wv6qnrmonc
Jul 15 12:10:23 motivo volumio[988]: info: Exploding uri qobuz://album/yy6wv6qnrmonc in service qobuz
Jul 15 12:10:23 motivo volumio[988]: https://browsing-performer.dfs.volumio.org/volumio2/qobuz/explodeUri
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand stop took 45 milliseconds
Jul 15 12:10:24 motivo volumio[988]: info:
Jul 15 12:10:24 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:24 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:24 motivo volumio[988]: info:
Jul 15 12:10:24 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:24 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:24 motivo volumio[988]: info:
Jul 15 12:10:24 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:24 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand status took 4 milliseconds
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand status took 2 milliseconds
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand status took 3 milliseconds
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:24 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:24 motivo volumio[988]: 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":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:24 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::syncState stateService stop
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus stop
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: No code
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: ------------------------------ 23ms
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand playlistinfo took 18 milliseconds
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand playlistinfo took 18 milliseconds
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:24 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:24 motivo volumio[988]: 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":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:24 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::syncState stateService stop
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus stop
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: No code
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: CorePlayQueue::getTrack 3
Jul 15 12:10:24 motivo volumio[988]: 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":"255505383","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/255505383","trackType":"qobuz"}
Jul 15 12:10:24 motivo volumio[988]: verbose: CURRENT POSITION 3
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::syncState stateService stop
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus stop
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: No code
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:24 motivo volumio[988]: info: ------------------------------ 82ms
Jul 15 12:10:24 motivo volumio[988]: info: ------------------------------ 81ms
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:24 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:24 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:24 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:24 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:24 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:24 motivo volumio[988]: info: explodeUri took 358 milliseconds
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPushQueue
Jul 15 12:10:24 motivo volumio[988]: info: CorePlayQueue::saveQueue
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::updateTrackBlock
Jul 15 12:10:24 motivo volumio[988]: info: CorePlayQueue::getTrackBlock
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::volumioPlay
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::play index 0
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::stop
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::play index undefined
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 15 12:10:24 motivo volumio[988]: info: CorePlayQueue::getTrack 0
Jul 15 12:10:24 motivo volumio[988]: info: CoreStateMachine::startPlaybackTimer
Jul 15 12:10:24 motivo volumio[988]: info: CorePlayQueue::getTrack 0
Jul 15 12:10:24 motivo volumio[988]: info: [1721038224363] ControllerQobuz::clearAddPlayTrack
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand stop
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand stop took 5 milliseconds
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand clear
Jul 15 12:10:24 motivo volumio[988]: info:
Jul 15 12:10:24 motivo volumio[988]: ---------------------------- MPD announces system playlist update
Jul 15 12:10:24 motivo volumio[988]: info: Ignoring MPD Status Update
Jul 15 12:10:24 motivo volumio[988]: info: sendMpdCommand clear took 1 milliseconds
Jul 15 12:10:24 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/164198436"
Jul 15 12:10:24 motivo volumio[988]: info:
Jul 15 12:10:24 motivo volumio[988]: ---------------------------- MPD announces system playlist update
Jul 15 12:10:24 motivo volumio[988]: info: Ignoring MPD Status Update
Jul 15 12:10:24 motivo volumio[988]: info:
Jul 15 12:10:24 motivo volumio[988]: ---------------------------- MPD announces system playlist update
Jul 15 12:10:24 motivo volumio[988]: info: Ignoring MPD Status Update
Jul 15 12:10:24 motivo volumio[988]: error: updateQueue error: null
Jul 15 12:10:24 motivo volumio[988]: info: ------------------------------ 2ms
Jul 15 12:10:24 motivo volumio[988]: STREAMING PROXY: Handling url /?data=qobuz://song/164198436
Jul 15 12:10:24 motivo volumio[988]: info: Executing endpoint getStreamUrlqobuz
Jul 15 12:10:24 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 15 12:10:24 motivo volumio[988]: info: getStreamUrl took 375 milliseconds
Jul 15 12:10:24 motivo volumio[988]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=789598&eid=164198436&fmt=7&profile=raw&app_id=539451548&cid=1302541&etsp=1721041824&hmac=KVypt0R65xZ_xWQ848YEHXv56Zg
Jul 15 12:10:25 motivo volumio[988]: STREAMING PROXY: Response: 200, length: 32848557
Jul 15 12:10:25 motivo volumio[988]: STREAMING PROXY: Client dropped request, destroying
Jul 15 12:10:25 motivo volumio[988]: error: updateQueue error: null
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/164198436"
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 685ms
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 684ms
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces system playlist update
Jul 15 12:10:25 motivo volumio[988]: info: Ignoring MPD Status Update
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/164198436" took 2 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand play
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces system playlist update
Jul 15 12:10:25 motivo volumio[988]: info: Ignoring MPD Status Update
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces system playlist update
Jul 15 12:10:25 motivo volumio[988]: STREAMING PROXY: Handling url /?data=qobuz://song/164198436
Jul 15 12:10:25 motivo volumio[988]: info: Ignoring MPD Status Update
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 7ms
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand play took 6 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 4ms
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 3ms
Jul 15 12:10:25 motivo volumio[988]: info: Executing endpoint getStreamUrlqobuz
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 15 12:10:25 motivo volumio[988]: info: getStreamUrl took 336 milliseconds
Jul 15 12:10:25 motivo volumio[988]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=789598&eid=164198436&fmt=7&profile=raw&app_id=539451548&cid=1302541&etsp=1721041825&hmac=XWcn6G30H1ovZc1CUr_U0L5bE5c
Jul 15 12:10:25 motivo volumio[988]: STREAMING PROXY: Response: 200, length: 32848557
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand status took 16 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand status took 15 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand status took 17 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand status took 4 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:25 motivo volumio[988]: info: CorePlayQueue::getTrack 0
Jul 15 12:10:25 motivo volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"164198436","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/164198436","trackType":"qobuz"}
Jul 15 12:10:25 motivo volumio[988]: verbose: CURRENT POSITION 0
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState stateService play
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus stop
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:25 motivo volumio[988]: info: CorePlayQueue::getTrack 0
Jul 15 12:10:25 motivo volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"164198436","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/164198436","trackType":"qobuz"}
Jul 15 12:10:25 motivo volumio[988]: verbose: CURRENT POSITION 0
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState stateService play
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus play
Jul 15 12:10:25 motivo volumio[988]: info: Received an update from plugin. extracting info from payload
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 37ms
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 41ms
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:25 motivo volumio[988]: info:
Jul 15 12:10:25 motivo volumio[988]: ---------------------------- MPD announces state update: player
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::getState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand status
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand playlistinfo took 24 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand playlistinfo took 25 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand status took 4 milliseconds
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:25 motivo volumio[988]: info: CorePlayQueue::getTrack 0
Jul 15 12:10:25 motivo volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"164198436","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/164198436","trackType":"qobuz"}
Jul 15 12:10:25 motivo volumio[988]: verbose: CURRENT POSITION 0
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState stateService play
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus play
Jul 15 12:10:25 motivo volumio[988]: info: Received an update from plugin. extracting info from payload
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:25 motivo volumio[988]: info: CorePlayQueue::getTrack 0
Jul 15 12:10:25 motivo volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"164198436","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/164198436","trackType":"qobuz"}
Jul 15 12:10:25 motivo volumio[988]: verbose: CURRENT POSITION 0
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState stateService play
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus play
Jul 15 12:10:25 motivo volumio[988]: info: Received an update from plugin. extracting info from payload
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 77ms
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 65ms
Jul 15 12:10:25 motivo volumio[988]: info: Signalling Playback active due to playback status change
Jul 15 12:10:25 motivo volumio[988]: info: Signalling Playback active due to playback status change
Jul 15 12:10:25 motivo volumio[988]: info: Signalling Playback active due to playback status change
Jul 15 12:10:25 motivo volumio[988]: info: Signalling Playback active due to playback status change
Jul 15 12:10:25 motivo volumio[988]: info: Signalling Playback active due to playback status change
Jul 15 12:10:25 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:25 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:25 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:25 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:25 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:25 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:25 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:25 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand status took 93 milliseconds
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand playlistinfo took 87 milliseconds
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseState
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:25 motivo volumio[988]: info: CorePlayQueue::getTrack 0
Jul 15 12:10:25 motivo volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"164198436","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/164198436","trackType":"qobuz"}
Jul 15 12:10:25 motivo volumio[988]: verbose: CURRENT POSITION 0
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState stateService play
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus play
Jul 15 12:10:25 motivo volumio[988]: info: Received an update from plugin. extracting info from payload
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 119ms
Jul 15 12:10:25 motivo volumio[988]: info: sendMpdCommand playlistinfo took 24 milliseconds
Jul 15 12:10:25 motivo volumio[988]: verbose: ControllerMpd::parseTrackInfo
Jul 15 12:10:25 motivo volumio[988]: info: ControllerMpd::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::servicePushState
Jul 15 12:10:25 motivo volumio[988]: info: CorePlayQueue::getTrack 0
Jul 15 12:10:25 motivo volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"164198436","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/164198436","trackType":"qobuz"}
Jul 15 12:10:25 motivo volumio[988]: verbose: CURRENT POSITION 0
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState stateService play
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::syncState currentStatus play
Jul 15 12:10:25 motivo volumio[988]: info: Received an update from plugin. extracting info from payload
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreStateMachine::pushState
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::volumioPushState
Jul 15 12:10:25 motivo volumio[988]: info: ------------------------------ 138ms
Jul 15 12:10:25 motivo volumio[988]: info: Signalling Playback active due to playback status change
Jul 15 12:10:25 motivo volumio[988]: info: Signalling Playback active due to playback status change
Jul 15 12:10:25 motivo volumio[988]: info: Signalling Playback active due to playback status change
Jul 15 12:10:25 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:25 motivo volumio[988]: info: Updating RAAT Signal Path
Jul 15 12:10:25 motivo volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 15 12:10:25 motivo volumio[988]: info: MCU Signalled Playback Active
Jul 15 12:10:25 motivo volumio[988]: info: Executing endpoint getSimilarAlbums
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 15 12:10:25 motivo volumio[988]: info: Executing endpoint metavolumio
Jul 15 12:10:25 motivo volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 15 12:10:26 motivo volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 15 12:10:27 motivo volumio[988]: Error: read ECONNRESET
Jul 15 12:10:27 motivo volumio[988]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) {
Jul 15 12:10:27 motivo volumio[988]: errno: -104,
Jul 15 12:10:27 motivo volumio[988]: code: 'ECONNRESET',
Jul 15 12:10:27 motivo volumio[988]: syscall: 'read'
Jul 15 12:10:27 motivo volumio[988]: }
Jul 15 12:10:27 motivo volumio[988]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 15 12:10:27 motivo sudo[5776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-15 12:09
Jul 15 12:10:27 motivo sudo[5776]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="de36dcdb7799b932c717b0440e58d5fd01f99639"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="motivo"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 28 Jun 2024 02:52:26 PM CEST"
VOLUMIO_VERSION="3.734"
VOLUMIO_HARDWARE="cm4"
VOLUMIO_DEVICENAME="CM4"
VOLUMIO_VENDOR_MODEL="Volumio Motivo"
VOLUMIO_VENDOR="Volumio"
VOLUMIO_MODEL="Motivo"
VOLUMIO_HASH="86aad4292aa1f19e61fb8075c6303928"