Play status not updating

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

I asked a similar question, but this forum is deaf and dead, to this problem :unamused:

Do you have other plugins active?

Sorry to be the one to resurrect this old thread but I’m experiencing the same issue with the latest version where one song play status is not being updated but the next one will be correct. This issue seems to be intermittent and so far I’ve noticed occur with both Tidal and Airplay. Airplay sometimes doesn’t update the album art as well.

hello!
thanks, this is a reported issue. A work is in progress…

2 Likes

Hello, it seems that using the “Now playing” plugin reduce this anomaly. Worth trying !