-- Logs begin at Fri 2024-07-26 12:49:59 UTC, end at Fri 2024-07-26 13:14:33 UTC. -- Jul 26 13:13:01 motivo go-librespot[4371]: time="2024-07-26T13:13:01Z" level=debug msg="fetched chunk 4/12, size: 524288" Jul 26 13:13:14 motivo go-librespot[4371]: time="2024-07-26T13:13:14Z" level=debug msg="fetched chunk 5/12, size: 524288" Jul 26 13:13:26 motivo go-librespot[4371]: time="2024-07-26T13:13:26Z" level=debug msg="fetched chunk 6/12, size: 524288" Jul 26 13:13:27 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 26 13:13:27 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 26 13:13:27 motivo volumio[4129]: info: Discovery: Getting this device information Jul 26 13:13:27 motivo volumio[4129]: info: CoreCommandRouter::volumioGetState Jul 26 13:13:27 motivo volumio[4129]: info: CorePlayQueue::getTrack 1 Jul 26 13:13:27 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 26 13:13:38 motivo go-librespot[4371]: time="2024-07-26T13:13:38Z" level=debug msg="fetched chunk 7/12, size: 524288" Jul 26 13:13:50 motivo go-librespot[4371]: time="2024-07-26T13:13:50Z" level=debug msg="fetched chunk 8/12, size: 524288" Jul 26 13:13:57 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 26 13:13:57 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 26 13:13:57 motivo volumio[4129]: info: Discovery: Getting this device information Jul 26 13:13:57 motivo volumio[4129]: info: CoreCommandRouter::volumioGetState Jul 26 13:13:57 motivo volumio[4129]: info: CorePlayQueue::getTrack 1 Jul 26 13:13:57 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 26 13:14:02 motivo go-librespot[4371]: time="2024-07-26T13:14:02Z" level=debug msg="fetched chunk 9/12, size: 524288" Jul 26 13:14:12 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 26 13:14:12 motivo volumio[4129]: info: handleBrowseUri took 156 milliseconds Jul 26 13:14:12 motivo volumio[4129]: info: Preload queue cleared Jul 26 13:14:12 motivo volumio[4129]: info: Preload queue cleared Jul 26 13:14:13 motivo ntpd[816]: 95.230.240.5 local addr 192.168.1.71 -> Jul 26 13:14:13 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 26 13:14:13 motivo volumio[4129]: info: handleBrowseUri took 238 milliseconds Jul 26 13:14:13 motivo volumio[4129]: info: Preload queue cleared Jul 26 13:14:14 motivo go-librespot[4371]: time="2024-07-26T13:14:14Z" level=debug msg="fetched chunk 10/12, size: 524288" Jul 26 13:14:15 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 26 13:14:16 motivo volumio[4129]: info: handleBrowseUri took 1129 milliseconds Jul 26 13:14:16 motivo volumio[4129]: info: Preload queue cleared Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/2392409 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/23437171 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/3112508 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/52366503 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/31753046 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/140479144 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/90227180 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/69042777 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/2267355 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/2059392 Jul 26 13:14:16 motivo volumio[4129]: info: Preloading song: qobuz://song/13626279 Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/2392409 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/23437171 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/3112508 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/52366503 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/31753046 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/140479144 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/90227180 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:16 motivo volumio[4129]: info: explodeUri took 327 milliseconds Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/69042777 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:16 motivo volumio[4129]: info: explodeUri took 304 milliseconds Jul 26 13:14:16 motivo volumio[4129]: info: Exploding uri qobuz://song/2267355 in service qobuz Jul 26 13:14:16 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:17 motivo volumio[4129]: info: Exploding uri qobuz://song/2059392 in service qobuz Jul 26 13:14:17 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 364 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: Exploding uri qobuz://song/13626279 in service qobuz Jul 26 13:14:17 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 309 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 382 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 315 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 329 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 280 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: Preload queue cleared Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::ClearQueue Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::stop Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::updateTrackBlock Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::getTrackBlock Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::getTrack 1 Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:17 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:17 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::serviceStop Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::getTrack 1 Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::serviceStop Jul 26 13:14:17 motivo volumio[4129]: info: Spotify Stop Jul 26 13:14:17 motivo volumio[4129]: SPOTIFY: SPOTIFY STOP Jul 26 13:14:17 motivo volumio[4129]: SPOTIFY: {"status":"play","position":1,"title":"Paprika","artist":"Ghali","album":"Paprika","albumart":"https://i.scdn.co/image/ab67616d0000b27335542084789157d2c769aada","uri":"spotify:track:7kS2AVRk5Yj6BTj8PyJFfC","trackType":"spotify","codec":"ogg","seek":0,"duration":148,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":false,"repeatSingle":false,"consume":false,"volume":32,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jul 26 13:14:17 motivo volumio[4129]: info: Sending Spotify command to local API: /player/pause Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::clearPlayQueue Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::saveQueue Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::volumioPushQueue Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::addQueueItems Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::addQueueItems Jul 26 13:14:17 motivo volumio[4129]: info: Preload queue cleared Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/2392409 Jul 26 13:14:17 motivo volumio[4129]: info: Using cached record of: qobuz://song/2392409 Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/23437171 Jul 26 13:14:17 motivo volumio[4129]: info: Using cached record of: qobuz://song/23437171 Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/3112508 Jul 26 13:14:17 motivo volumio[4129]: info: Using cached record of: qobuz://song/3112508 Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/52366503 Jul 26 13:14:17 motivo volumio[4129]: info: Using cached record of: qobuz://song/52366503 Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/31753046 Jul 26 13:14:17 motivo volumio[4129]: info: Using cached record of: qobuz://song/31753046 Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/140479144 Jul 26 13:14:17 motivo volumio[4129]: info: Using cached record of: qobuz://song/140479144 Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/90227180 Jul 26 13:14:17 motivo volumio[4129]: info: Using cached record of: qobuz://song/90227180 Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/69042777 Jul 26 13:14:17 motivo volumio[4129]: info: Using cached record of: qobuz://song/69042777 Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/2267355 Jul 26 13:14:17 motivo volumio[4129]: info: Exploding uri qobuz://song/2267355 in service qobuz Jul 26 13:14:17 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/2059392 Jul 26 13:14:17 motivo volumio[4129]: info: Exploding uri qobuz://song/2059392 in service qobuz Jul 26 13:14:17 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:17 motivo volumio[4129]: info: Adding Item to queue: qobuz://song/13626279 Jul 26 13:14:17 motivo volumio[4129]: info: Exploding uri qobuz://song/13626279 in service qobuz Jul 26 13:14:17 motivo volumio[4129]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Jul 26 13:14:17 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:17 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 289 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 337 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 352 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: MCU Signalled Playback Inactive Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 213 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 246 milliseconds Jul 26 13:14:17 motivo go-librespot[4371]: time="2024-07-26T13:14:17Z" level=debug msg="pause track at 85187ms" Jul 26 13:14:17 motivo volumio[4129]: info: explodeUri took 277 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::volumioPushQueue Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::saveQueue Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::updateTrackBlock Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::getTrackBlock Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::volumioPlay Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::play index 0 Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::stop Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::play index undefined Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::startPlaybackTimer Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:17 motivo volumio[4129]: info: [1721999657555] ControllerQobuz::clearAddPlayTrack Jul 26 13:14:17 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 13:14:17 motivo volumio[4129]: info: sendMpdCommand stop took 6 milliseconds Jul 26 13:14:17 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand clear Jul 26 13:14:17 motivo volumio[4129]: info: Jul 26 13:14:17 motivo volumio[4129]: ---------------------------- MPD announces system playlist update Jul 26 13:14:17 motivo volumio[4129]: info: Ignoring MPD Status Update Jul 26 13:14:17 motivo volumio[4129]: info: sendMpdCommand clear took 1 milliseconds Jul 26 13:14:17 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/2392409" Jul 26 13:14:17 motivo volumio[4129]: info: Jul 26 13:14:17 motivo volumio[4129]: ---------------------------- MPD announces system playlist update Jul 26 13:14:17 motivo volumio[4129]: STREAMING PROXY: Handling url /?data=qobuz://song/2392409 Jul 26 13:14:17 motivo volumio[4129]: info: Ignoring MPD Status Update Jul 26 13:14:17 motivo volumio[4129]: info: Jul 26 13:14:17 motivo volumio[4129]: ---------------------------- MPD announces system playlist update Jul 26 13:14:17 motivo volumio[4129]: info: Ignoring MPD Status Update Jul 26 13:14:17 motivo volumio[4129]: error: updateQueue error: null Jul 26 13:14:17 motivo volumio[4129]: info: ------------------------------ 4ms Jul 26 13:14:17 motivo volumio[4129]: info: Executing endpoint getStreamUrlqobuz Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 26 13:14:17 motivo go-librespot[4371]: time="2024-07-26T13:14:17Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 26 13:14:17 motivo go-librespot[4371]: time="2024-07-26T13:14:17Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 26 13:14:17 motivo go-librespot[4371]: time="2024-07-26T13:14:17Z" level=trace msg="emitting websocket event: paused" Jul 26 13:14:17 motivo volumio[4129]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7kS2AVRk5Yj6BTj8PyJFfC","play_origin":"go-librespot"}} Jul 26 13:14:17 motivo volumio[4129]: SPOTIFY: PUSH STATE SPOTIFY Jul 26 13:14:17 motivo volumio[4129]: SPOTIFY: {"status":"pause","service":"spop","title":"Paprika","artist":"Ghali","album":"Paprika","albumart":"https://i.scdn.co/image/ab67616d00001e0235542084789157d2c769aada","uri":"spotify:track:7kS2AVRk5Yj6BTj8PyJFfC","trackType":"spotify","seek":85000,"duration":148,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:17 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:17 motivo volumio[4129]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received spop Jul 26 13:14:17 motivo volumio[4129]: info: getStreamUrl took 239 milliseconds Jul 26 13:14:17 motivo volumio[4129]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=3072185&eid=2392409&fmt=6&profile=raw&app_id=539451548&cid=2189532&etsp=1722003257&hmac=aHua1toEx505KZR433Rfm_5vyYg Jul 26 13:14:17 motivo volumio[4129]: STREAMING PROXY: Response: 200, length: 34463039 Jul 26 13:14:17 motivo volumio[4129]: STREAMING PROXY: Client dropped request, destroying Jul 26 13:14:17 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2392409" Jul 26 13:14:17 motivo volumio[4129]: error: updateQueue error: null Jul 26 13:14:17 motivo volumio[4129]: error: updateQueue error: null Jul 26 13:14:17 motivo volumio[4129]: info: Jul 26 13:14:17 motivo volumio[4129]: ---------------------------- MPD announces system playlist update Jul 26 13:14:17 motivo volumio[4129]: info: Ignoring MPD Status Update Jul 26 13:14:17 motivo volumio[4129]: info: ------------------------------ 366ms Jul 26 13:14:17 motivo volumio[4129]: info: ------------------------------ 366ms Jul 26 13:14:17 motivo volumio[4129]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2392409" took 2 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 13:14:17 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand play Jul 26 13:14:17 motivo volumio[4129]: info: Jul 26 13:14:17 motivo volumio[4129]: ---------------------------- MPD announces system playlist update Jul 26 13:14:17 motivo volumio[4129]: info: Ignoring MPD Status Update Jul 26 13:14:17 motivo volumio[4129]: info: Jul 26 13:14:17 motivo volumio[4129]: ---------------------------- MPD announces system playlist update Jul 26 13:14:17 motivo volumio[4129]: info: Ignoring MPD Status Update Jul 26 13:14:17 motivo volumio[4129]: STREAMING PROXY: Handling url /?data=qobuz://song/2392409 Jul 26 13:14:17 motivo volumio[4129]: info: ------------------------------ 3ms Jul 26 13:14:17 motivo volumio[4129]: info: sendMpdCommand play took 3 milliseconds Jul 26 13:14:17 motivo volumio[4129]: info: ------------------------------ 2ms Jul 26 13:14:17 motivo volumio[4129]: info: Executing endpoint getStreamUrlqobuz Jul 26 13:14:17 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 26 13:14:18 motivo volumio[4129]: info: getStreamUrl took 197 milliseconds Jul 26 13:14:18 motivo volumio[4129]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=3072185&eid=2392409&fmt=6&profile=raw&app_id=539451548&cid=2189532&etsp=1722003258&hmac=r_3vEMCevrMBSfh8wg_wAC-Cf8I Jul 26 13:14:18 motivo volumio[4129]: STREAMING PROXY: Response: 200, length: 34463039 Jul 26 13:14:18 motivo volumio[4129]: info: Jul 26 13:14:18 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:18 motivo volumio[4129]: info: Jul 26 13:14:18 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:18 motivo volumio[4129]: info: Jul 26 13:14:18 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:18 motivo volumio[4129]: info: Jul 26 13:14:18 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand status took 13 milliseconds Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:18 motivo volumio[4129]: info: Jul 26 13:14:18 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:18 motivo volumio[4129]: info: Jul 26 13:14:18 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand status took 18 milliseconds Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand status took 17 milliseconds Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand status took 8 milliseconds Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand status took 4 milliseconds Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:18 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:18 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:18 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus stop Jul 26 13:14:18 motivo volumio[4129]: info: ------------------------------ 25ms Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand status took 7 milliseconds Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:18 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:18 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:18 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:18 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:18 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:18 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:18 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:18 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:18 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:18 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"868 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:18 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:18 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:18 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:18 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"868 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:18 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:18 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: ------------------------------ 88ms Jul 26 13:14:18 motivo volumio[4129]: info: ------------------------------ 88ms Jul 26 13:14:18 motivo volumio[4129]: info: ------------------------------ 79ms Jul 26 13:14:18 motivo volumio[4129]: info: ------------------------------ 75ms Jul 26 13:14:18 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 67 milliseconds Jul 26 13:14:18 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:18 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:18 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:18 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"868 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:18 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:18 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:18 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:18 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:18 motivo volumio[4129]: info: ------------------------------ 98ms Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:18 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:18 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:18 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:18 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:18 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:18 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:18 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:18 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:18 motivo volumio[4129]: info: MCU Signalled Playback Active Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::volumioPause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::pause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::servicePause Jul 26 13:14:21 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::servicePause Jul 26 13:14:21 motivo volumio[4129]: info: [1721999661260] ControllerQobuz::pause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 13:14:21 motivo volumio[4129]: info: ControllerMpd::pause Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand pause Jul 26 13:14:21 motivo volumio[4129]: info: sendMpdCommand pause took 1 milliseconds Jul 26 13:14:21 motivo volumio[4129]: info: Jul 26 13:14:21 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:21 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:21 motivo volumio[4129]: info: Jul 26 13:14:21 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:21 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:21 motivo volumio[4129]: info: Jul 26 13:14:21 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:21 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:21 motivo volumio[4129]: info: sendMpdCommand status took 3 milliseconds Jul 26 13:14:21 motivo volumio[4129]: info: sendMpdCommand status took 2 milliseconds Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:21 motivo volumio[4129]: info: sendMpdCommand status took 3 milliseconds Jul 26 13:14:21 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 26 13:14:21 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:21 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:21 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:21 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4461,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"873 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:21 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:21 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:21 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:21 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:21 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:21 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4461,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"873 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:21 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:21 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:21 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:21 motivo volumio[4129]: info: ------------------------------ 25ms Jul 26 13:14:21 motivo volumio[4129]: info: ------------------------------ 25ms Jul 26 13:14:21 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 26 13:14:21 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:21 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:21 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:21 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4461,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"873 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:21 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:21 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:21 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:21 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:21 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:21 motivo volumio[4129]: info: ------------------------------ 38ms Jul 26 13:14:21 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:21 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:21 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:21 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:21 motivo volumio[4129]: info: MCU Signalled Playback Inactive Jul 26 13:14:21 motivo volumio[4129]: STREAMING PROXY: Client dropped request, destroying Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::volumioSeek Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::seek Jul 26 13:14:22 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:22 motivo volumio[4129]: info: TRACKBLOCK {"uri":"qobuz://song/2392409","service":"qobuz","name":"MMMBop","title":"MMMBop","album":"MmmBop : The Collection","type":"track","tracknumber":1,"volumeNumber":1,"albumart":"https://static.qobuz.com/images/covers/63/96/0060249829663_600.jpg","duration":267,"explicit":false,"artist":"Hanson","artistUri":"qobuz://artist/60509","albumUri":"qobuz://album/0060249829663","audioQuality":"","tagImage":""} Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::startPlaybackTimer Jul 26 13:14:22 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:22 motivo volumio[4129]: info: [1721999662347] ControllerQobuz::seek Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 13:14:22 motivo volumio[4129]: info: ControllerMpd::seek Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:22 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:22 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:22 motivo volumio[4129]: error: null Jul 26 13:14:22 motivo volumio[4129]: info: Jul 26 13:14:22 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:22 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:22 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:22 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:22 motivo volumio[4129]: info: Jul 26 13:14:22 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:22 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:22 motivo volumio[4129]: info: Jul 26 13:14:22 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:22 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:22 motivo volumio[4129]: info: sendMpdCommand status took 17 milliseconds Jul 26 13:14:22 motivo volumio[4129]: info: sendMpdCommand status took 3 milliseconds Jul 26 13:14:22 motivo volumio[4129]: info: sendMpdCommand status took 2 milliseconds Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:22 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 26 13:14:22 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 26 13:14:22 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:22 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:22 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:22 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:22 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":247000,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"873 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:22 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:22 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:22 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:22 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:22 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:22 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":247000,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"873 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:22 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:22 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:22 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:22 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:22 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:22 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":247000,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"873 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:22 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:22 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:22 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:22 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:22 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:22 motivo volumio[4129]: info: ------------------------------ 70ms Jul 26 13:14:22 motivo volumio[4129]: info: ------------------------------ 57ms Jul 26 13:14:22 motivo volumio[4129]: info: ------------------------------ 57ms Jul 26 13:14:22 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:22 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:22 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:22 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:22 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:22 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::volumioPlay Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::play index undefined Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:14:23 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::startPlaybackTimer Jul 26 13:14:23 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:23 motivo volumio[4129]: info: [1721999663456] ControllerQobuz::resume Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 13:14:23 motivo volumio[4129]: info: ControllerMpd::resume Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand play Jul 26 13:14:23 motivo volumio[4129]: info: sendMpdCommand play took 24 milliseconds Jul 26 13:14:23 motivo volumio[4129]: info: Jul 26 13:14:23 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:23 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:23 motivo volumio[4129]: info: Jul 26 13:14:23 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:23 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:23 motivo volumio[4129]: info: Jul 26 13:14:23 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:23 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:23 motivo volumio[4129]: info: sendMpdCommand status took 4 milliseconds Jul 26 13:14:23 motivo volumio[4129]: info: sendMpdCommand status took 3 milliseconds Jul 26 13:14:23 motivo volumio[4129]: info: sendMpdCommand status took 1 milliseconds Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:23 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 26 13:14:23 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 26 13:14:23 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:23 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:23 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:23 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:23 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":248212,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1066 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:23 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:23 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:23 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:23 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":248212,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1113 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:23 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:23 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:23 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:23 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:23 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":248212,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1113 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:23 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:23 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:23 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:23 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:23 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:23 motivo volumio[4129]: info: ------------------------------ 67ms Jul 26 13:14:23 motivo volumio[4129]: info: ------------------------------ 66ms Jul 26 13:14:23 motivo volumio[4129]: info: ------------------------------ 67ms Jul 26 13:14:23 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:23 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:23 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:23 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:23 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:23 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:23 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:23 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:23 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:23 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:23 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:23 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:23 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:23 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:23 motivo volumio[4129]: info: MCU Signalled Playback Active Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::volumioSeek Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::seek Jul 26 13:14:26 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:26 motivo volumio[4129]: info: TRACKBLOCK {"uri":"qobuz://song/2392409","service":"qobuz","name":"MMMBop","title":"MMMBop","album":"MmmBop : The Collection","type":"track","tracknumber":1,"volumeNumber":1,"albumart":"https://static.qobuz.com/images/covers/63/96/0060249829663_600.jpg","duration":267,"explicit":false,"artist":"Hanson","artistUri":"qobuz://artist/60509","albumUri":"qobuz://album/0060249829663","audioQuality":"","tagImage":""} Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::startPlaybackTimer Jul 26 13:14:26 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:26 motivo volumio[4129]: info: [1721999666311] ControllerQobuz::seek Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 13:14:26 motivo volumio[4129]: info: ControllerMpd::seek Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:26 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:26 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:26 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:26 motivo volumio[4129]: error: null Jul 26 13:14:26 motivo volumio[4129]: info: Jul 26 13:14:26 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:26 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:26 motivo volumio[4129]: info: Jul 26 13:14:26 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:26 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:26 motivo volumio[4129]: info: Jul 26 13:14:26 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:26 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:26 motivo volumio[4129]: info: sendMpdCommand status took 6 milliseconds Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:26 motivo volumio[4129]: info: sendMpdCommand status took 6 milliseconds Jul 26 13:14:26 motivo volumio[4129]: info: sendMpdCommand status took 6 milliseconds Jul 26 13:14:26 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:26 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:26 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:26 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":258212,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"761 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:26 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:26 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:26 motivo volumio[4129]: info: ------------------------------ 33ms Jul 26 13:14:26 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 26 13:14:26 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:26 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:26 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:26 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:26 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":258212,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"761 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:26 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:26 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:26 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:26 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:26 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":258212,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"761 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:26 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:26 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:26 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:26 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:26 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:26 motivo volumio[4129]: info: ------------------------------ 66ms Jul 26 13:14:26 motivo volumio[4129]: info: ------------------------------ 65ms Jul 26 13:14:26 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:26 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:26 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:26 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:26 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:26 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:26 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:26 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:26 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:26 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:26 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:26 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:26 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:26 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:26 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:26 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:26 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:26 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::volumioPause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::pause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::servicePause Jul 26 13:14:27 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::servicePause Jul 26 13:14:27 motivo volumio[4129]: info: [1721999667261] ControllerQobuz::pause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 13:14:27 motivo volumio[4129]: info: ControllerMpd::pause Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand pause Jul 26 13:14:27 motivo volumio[4129]: info: Jul 26 13:14:27 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:27 motivo volumio[4129]: info: sendMpdCommand pause took 3 milliseconds Jul 26 13:14:27 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:27 motivo volumio[4129]: info: sendMpdCommand status took 1 milliseconds Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:27 motivo volumio[4129]: info: Jul 26 13:14:27 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:27 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:27 motivo volumio[4129]: info: Jul 26 13:14:27 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:27 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:27 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 26 13:14:27 motivo volumio[4129]: info: sendMpdCommand status took 3 milliseconds Jul 26 13:14:27 motivo volumio[4129]: info: sendMpdCommand status took 2 milliseconds Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:27 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:27 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:27 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":259333,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"761 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:27 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:27 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:27 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:27 motivo volumio[4129]: info: ------------------------------ 24ms Jul 26 13:14:27 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 26 13:14:27 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:27 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:27 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:27 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":259333,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"761 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:27 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:27 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:27 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:27 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:27 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:27 motivo volumio[4129]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":259333,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"761 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:27 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::syncState stateService pause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:27 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:27 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:27 motivo volumio[4129]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:14:27 motivo volumio[4129]: info: ------------------------------ 66ms Jul 26 13:14:27 motivo volumio[4129]: info: ------------------------------ 65ms Jul 26 13:14:27 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:27 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:27 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:27 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:27 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:27 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:27 motivo volumio[4129]: info: MCU Signalled Playback Inactive Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 26 13:14:27 motivo volumio[4129]: info: Discovery: Getting this device information Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::volumioGetState Jul 26 13:14:27 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::volumioPlay Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::play index undefined Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:14:29 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::startPlaybackTimer Jul 26 13:14:29 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:29 motivo volumio[4129]: info: [1721999669587] ControllerQobuz::resume Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 13:14:29 motivo volumio[4129]: info: ControllerMpd::resume Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand play Jul 26 13:14:29 motivo volumio[4129]: info: Jul 26 13:14:29 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:29 motivo volumio[4129]: info: sendMpdCommand play took 37 milliseconds Jul 26 13:14:29 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:29 motivo volumio[4129]: info: Jul 26 13:14:29 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:29 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:29 motivo volumio[4129]: info: Jul 26 13:14:29 motivo volumio[4129]: ---------------------------- MPD announces state update: player Jul 26 13:14:29 motivo volumio[4129]: info: ControllerMpd::getState Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:14:29 motivo volumio[4129]: info: sendMpdCommand status took 6 milliseconds Jul 26 13:14:29 motivo volumio[4129]: info: sendMpdCommand status took 4 milliseconds Jul 26 13:14:29 motivo volumio[4129]: info: sendMpdCommand status took 3 milliseconds Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::parseState Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:14:29 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 26 13:14:29 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 26 13:14:29 motivo volumio[4129]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:29 motivo volumio[4129]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:14:29 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:29 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:29 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260569,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"831 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:29 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus pause Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:29 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:29 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:29 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260569,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"831 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:29 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:29 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:29 motivo volumio[4129]: info: ControllerMpd::pushState Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::servicePushState Jul 26 13:14:29 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:29 motivo volumio[4129]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260569,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"831 Kbps","isStreaming":false,"title":"2392409","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2392409","trackType":"qobuz"} Jul 26 13:14:29 motivo volumio[4129]: verbose: CURRENT POSITION 0 Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::syncState stateService play Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:14:29 motivo volumio[4129]: info: Received an update from plugin. extracting info from payload Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:29 motivo volumio[4129]: info: CoreStateMachine::pushState Jul 26 13:14:29 motivo volumio[4129]: info: CoreCommandRouter::volumioPushState Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output update for this device Jul 26 13:14:29 motivo volumio[4129]: info: MRS: Pushing multiroomSync output Jul 26 13:14:29 motivo volumio[4129]: info: ------------------------------ 67ms Jul 26 13:14:29 motivo volumio[4129]: info: ------------------------------ 66ms Jul 26 13:14:29 motivo volumio[4129]: info: ------------------------------ 65ms Jul 26 13:14:29 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:29 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:29 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:29 motivo volumio[4129]: info: Signalling Playback active due to playback status change Jul 26 13:14:29 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:29 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:29 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:29 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:29 motivo volumio[4129]: info: Updating RAAT Signal Path Jul 26 13:14:29 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:29 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:29 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:29 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:29 motivo volumio[4129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Jul 26 13:14:29 motivo volumio[4129]: info: MCU Signalled Playback Active Jul 26 13:14:33 motivo volumio[4129]: info: CorePlayQueue::getTrack 0 Jul 26 13:14:33 motivo volumio[4129]: info: CorePlayQueue::getTrack 1 Jul 26 13:14:33 motivo volumio[4129]: info: Prefetching next song Jul 26 13:14:33 motivo volumio[4129]: info: [1721999673343] ControllerQobuz::prefetch Jul 26 13:14:33 motivo volumio[4129]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:14:33 motivo volumio[4129]: TypeError: Cannot read property 'then' of undefined Jul 26 13:14:33 motivo volumio[4129]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:460:11) Jul 26 13:14:33 motivo volumio[4129]: at listOnTimeout (internal/timers.js:554:17) Jul 26 13:14:33 motivo volumio[4129]: at processTimers (internal/timers.js:497:7) Jul 26 13:14:33 motivo volumio[4129]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:14:33 motivo sudo[5327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 13:13 Jul 26 13:14:33 motivo sudo[5327]: 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="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 05:28:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="7c8d25c95f72295d622ec3282883fbc6"