-- 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"