Page 1 of 1

Play status not updating

PostPosted: Wed Mar 25 2020 14:07
by oisteink
Every now and then on the last few versions of Volumio my UI don't reflect that I play songs.
I mostly play Tidal songs. I'm guessing theres some serious bugs deep down in how playstat is handled, as I have mentioned before. There's so many playstates beeing sent when you just change one song, some that are accurate and some that are empty/previous song.
Here it does a play like it should then issues a STOP. The timestamp is the same so they should probably the other way arround. Issue with promise handling maybe, or just no handling of async at all?

Here's logs from today + output from mpc and volumio regarding state.

volumio@volumio:~/js/packages$ mpc
http://sp-pr-fa.audio.tidal.com/mediatr ... RhNDk1Zg==
[playing] #1/1 15:10/45:31 (33%)
volume:100% repeat: off random: off single: off consume: on
volumio@volumio:~/js/packages$ volumio status
{
"status": "stop",
"position": 0,
"title": "Destination K.C.",
"artist": "Kansas City Seven",
"album": "Count Basie - Best Small Groups (1936-1944)",
"albumart": "https://resources.tidal.com/images/e5969053/b0a5/45ca/b54a/d9d2c7c4b4f8/640x640.jpg",
"uri": "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInODU0ZDBiZWJiMmJhZDFlMDVhODFmODg1ZTcwYjY5N2ZfNjEubXA0/0.flac?token=1585095117~OTBiMTg4OTJiYTM5MWUzOTMwNzAzNjYzOGJhZTE2NmQ1YjRlYTRmMg==",
"trackType": "tidal",
"seek": 918403,
"duration": 288,
"samplerate": "44.1 kHz",
"bitdepth": "16 bit",
"channels": 2,
"bitrate": "350 Kbps",
"random": false,
"repeat": false,
"repeatSingle": false,
"consume": true,
"volume": 100,
"mute": false,
"disableVolumeControl": true,
"stream": false,
"updatedb": false,
"volatile": false,
"service": "mpd"
}

2020-03-25T13:38:03.170Z - info: CoreCommandRouter::executeOnPlugin: streaming_services , handleBrowseUri
2020-03-25T13:38:05.120Z - info: CoreCommandRouter::executeOnPlugin: streaming_services , handleBrowseUri
2020-03-25T13:38:08.033Z - info: CoreCommandRouter::executeOnPlugin: streaming_services , handleBrowseUri
2020-03-25T13:38:09.289Z - info: CoreCommandRouter::executeOnPlugin: streaming_services , handleBrowseUri
2020-03-25T13:38:12.803Z - info: CoreCommandRouter::executeOnPlugin: streaming_services , handleBrowseUri
2020-03-25T13:38:18.994Z - info: CoreCommandRouter::volumioReplaceandPlayItems
2020-03-25T13:38:18.994Z - info: CoreStateMachine::ClearQueue
2020-03-25T13:38:18.994Z - info: CoreStateMachine::stop
2020-03-25T13:38:18.995Z - info: CoreStateMachine::setConsumeUpdateService undefined
2020-03-25T13:38:18.995Z - info: CorePlayQueue::clearPlayQueue
2020-03-25T13:38:18.995Z - info: CorePlayQueue::saveQueue
2020-03-25T13:38:18.996Z - info: CoreStateMachine::pushEmptyState
2020-03-25T13:38:18.996Z - info: CoreCommandRouter::volumioPushState
2020-03-25T13:38:18.996Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2020-03-25T13:38:19.016Z - info: CoreCommandRouter::volumioPushQueue
2020-03-25T13:38:19.018Z - info: CoreStateMachine::addQueueItems
2020-03-25T13:38:19.018Z - info: CorePlayQueue::addQueueItems
2020-03-25T13:38:19.019Z - info: Adding Item to queue: tidal://mymusic/artists/az/8847/2878851
2020-03-25T13:38:19.019Z - info: Exploding uri tidal://mymusic/artists/az/8847/2878851 in service streaming_services
2020-03-25T13:38:20.605Z - info: CorePlayQueue::saveQueue
2020-03-25T13:38:20.606Z - info: CoreCommandRouter::volumioPushQueue
2020-03-25T13:38:20.609Z - info: CoreStateMachine::updateTrackBlock
2020-03-25T13:38:20.609Z - info: CorePlayQueue::getTrackBlock
2020-03-25T13:38:20.610Z - info: CoreCommandRouter::volumioPlay
2020-03-25T13:38:20.610Z - info: CoreStateMachine::play index 0
2020-03-25T13:38:20.610Z - info: CoreStateMachine::setConsumeUpdateService undefined
2020-03-25T13:38:20.610Z - info: CoreStateMachine::stop
2020-03-25T13:38:20.611Z - info: CoreStateMachine::setConsumeUpdateService undefined
2020-03-25T13:38:20.611Z - info: CoreStateMachine::play index undefined
2020-03-25T13:38:20.611Z - info: CoreStateMachine::setConsumeUpdateService undefined
2020-03-25T13:38:20.612Z - info: CorePlayQueue::getTrack 0
2020-03-25T13:38:20.612Z - info: CoreStateMachine::startPlaybackTimer
2020-03-25T13:38:20.612Z - info: CorePlayQueue::getTrack 0
2020-03-25T13:38:20.612Z - info: [1585143500612] streamingServices::clearAddPlayTrack
2020-03-25T13:38:20.930Z - info: ControllerMpd::sendMpdCommand stop
2020-03-25T13:38:20.931Z - info: sending command...
2020-03-25T13:38:20.933Z - info: parsing response...
2020-03-25T13:38:20.933Z - info: ControllerMpd::sendMpdCommand clear
2020-03-25T13:38:20.933Z - info: sending command...
2020-03-25T13:38:20.934Z - info:
---------------------------- MPD announces system playlist update
2020-03-25T13:38:20.935Z - info: Ignoring MPD Status Update
2020-03-25T13:38:20.935Z - info: parsing response...
2020-03-25T13:38:20.936Z - info: ControllerMpd::sendMpdCommand load "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjZkYmU3NDljNzAzNTQxOWU2NjdmY2IxMTQ4MWU2YzVfNjEubXA0/0.flac?token=1585148962~YzIwMGRmOWU0ZjAyNmYyZDUyMjBiZjc3YWI5M2QzOWIyN2RhNDk1Zg=="
2020-03-25T13:38:20.936Z - info: sending command...
2020-03-25T13:38:20.937Z - info:
2020-03-25T13:38:20.938Z - info: ------------------------------ 4ms
2020-03-25T13:38:21.172Z - info: ControllerMpd::sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjZkYmU3NDljNzAzNTQxOWU2NjdmY2IxMTQ4MWU2YzVfNjEubXA0/0.flac?token=1585148962~YzIwMGRmOWU0ZjAyNmYyZDUyMjBiZjc3YWI5M2QzOWIyN2RhNDk1Zg=="
2020-03-25T13:38:21.172Z - info: sending command...
2020-03-25T13:38:21.173Z - info:
---------------------------- MPD announces system playlist update
2020-03-25T13:38:21.174Z - info: Ignoring MPD Status Update
2020-03-25T13:38:21.174Z - info: parsing response...
2020-03-25T13:38:21.175Z - info: CoreStateMachine::setConsumeUpdateService mpd
2020-03-25T13:38:21.175Z - info: ControllerMpd::sendMpdCommand play
2020-03-25T13:38:21.175Z - info: sending command...
2020-03-25T13:38:21.177Z - info: ------------------------------ 3ms
2020-03-25T13:38:21.177Z - info: parsing response...
2020-03-25T13:38:21.361Z - info:
---------------------------- MPD announces state update: player
2020-03-25T13:38:21.362Z - info: ControllerMpd::getState
2020-03-25T13:38:21.362Z - info: ControllerMpd::sendMpdCommand status
2020-03-25T13:38:21.362Z - info: sending command...
2020-03-25T13:38:21.364Z - info: parsing response...
2020-03-25T13:38:21.365Z - info: ControllerMpd::parseState
2020-03-25T13:38:21.365Z - info: ControllerMpd::sendMpdCommand playlistinfo
2020-03-25T13:38:21.366Z - info: sending command...
2020-03-25T13:38:21.367Z - info: parsing response...
2020-03-25T13:38:21.368Z - info: ControllerMpd::parseTrackInfo
2020-03-25T13:38:21.369Z - info: ControllerMpd::pushState
2020-03-25T13:38:21.369Z - info: CoreCommandRouter::servicePushState
2020-03-25T13:38:21.369Z - info: CoreStateMachine::syncState
2020-03-25T13:38:21.369Z - info: CorePlayQueue::getTrack 0
2020-03-25T13:38:21.369Z - info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":2731,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1090 Kbps","isStreaming":false,"title":"0.flac?token=1585148962~YzIwMGRmOWU0ZjAyNmYyZDUyMjBiZjc3YWI5M2QzOWIyN2RhNDk1Zg==","artist":null,"album":null,"uri":"http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjZkYmU3NDljNzAzNTQxOWU2NjdmY2IxMTQ4MWU2YzVfNjEubXA0/0.flac?token=1585148962~YzIwMGRmOWU0ZjAyNmYyZDUyMjBiZjc3YWI5M2QzOWIyN2RhNDk1Zg==","trackType":"tidal"}
2020-03-25T13:38:21.370Z - info: CURRENT POSITION 0
2020-03-25T13:38:21.370Z - info: CoreStateMachine::syncState stateService play
2020-03-25T13:38:21.370Z - info: CoreStateMachine::syncState currentStatus stop
2020-03-25T13:38:21.370Z - info: CoreStateMachine::pushState
2020-03-25T13:38:21.370Z - info: CoreStateMachine::getState
2020-03-25T13:38:21.371Z - info: CoreCommandRouter::volumioPushState
2020-03-25T13:38:21.371Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2020-03-25T13:38:21.396Z - info: ------------------------------ 35ms
2020-03-25T13:51:59.028Z - info: CoreCommandRouter::volumioGetVisibleSources
2020-03-25T13:51:59.029Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2020-03-25T13:51:59.049Z - info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
2020-03-25T13:51:59.052Z - info: CoreCommandRouter::volumioGetState
2020-03-25T13:51:59.053Z - info: CoreStateMachine::getState
2020-03-25T13:51:59.088Z - info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
2020-03-25T13:51:59.090Z - info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
2020-03-25T13:51:59.103Z - info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
2020-03-25T13:51:59.105Z - info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
2020-03-25T13:51:59.114Z - info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
2020-03-25T13:51:59.135Z - info: Listing playlists
2020-03-25T13:52:01.189Z - info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
2020-03-25T13:52:01.194Z - info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
2020-03-25T13:53:39.350Z - info: CoreCommandRouter::volumioGetState
2020-03-25T13:53:39.350Z - info: CoreStateMachine::getState
2020-03-25T13:53:57.504Z - info: CoreCommandRouter::volumioGetVisibleSources
2020-03-25T13:53:57.505Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2020-03-25T13:53:57.529Z - info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
2020-03-25T13:53:57.533Z - info: CoreCommandRouter::volumioGetState
2020-03-25T13:53:57.533Z - info: CoreStateMachine::getState
2020-03-25T13:53:57.562Z - info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
2020-03-25T13:53:57.563Z - info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
2020-03-25T13:53:57.581Z - info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
2020-03-25T13:53:57.582Z - info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
2020-03-25T13:53:57.589Z - info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
2020-03-25T13:53:57.610Z - info: Listing playlists
2020-03-25T13:53:59.663Z - info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
2020-03-25T13:53:59.667Z - info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
2020-03-25T13:54:01.070Z - info: CoreCommandRouter::volumioGetVisibleSources
2020-03-25T13:54:01.070Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2020-03-25T13:54:01.091Z - info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
2020-03-25T13:54:01.094Z - info: CoreCommandRouter::volumioGetState
2020-03-25T13:54:01.094Z - info: CoreStateMachine::getState
2020-03-25T13:54:01.132Z - info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
2020-03-25T13:54:01.133Z - info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
2020-03-25T13:54:01.139Z - info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
2020-03-25T13:54:01.140Z - info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
2020-03-25T13:54:01.142Z - info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
2020-03-25T13:54:01.166Z - info: Listing playlists
2020-03-25T13:54:03.228Z - info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
2020-03-25T13:54:03.231Z - info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken

Play status not updating

PostPosted: Fri Mar 27 2020 07:19
by alexvs
I asked a similar question, but this forum is deaf and dead, to this problem :roll:

Play status not updating

PostPosted: Fri Mar 27 2020 09:37
by michelangelo
Do you have other plugins active?