Android Pie Bluetooth Auto: Tag display started working again after downgrade to v828

[Lox68]

Tag display via Bluetooth in my car started working again after downgrade to v828 without any tweaks or other manipulations.  It looks like as from v829 a regression crept in.

[pamplina]

It works!

Thank you very much, Lox68.

[Lox68]

You're welcome ☺️

[maxmp]

It should be fixed in 839 as well, but there is another issue with Auto (unrelated to tags, related to song selection), so I wouldn't advise that. I recommend updating to 840 when it's out. Note that this fixes Android Auto issue. Other non-"Android Auto" BT issues were never reproduced locally, unfortunately, but could be affected by Android media libs updates.

[Lox68]

Upgraded to v840 and the same problem reappears: "No Track Info Available" on my Car Audio System.  Downgraded again to v828 and the tags reappeared again...

[maxmp]

@Lox68 something in recent Media support libs is affecting your device/bt track info (it’s also used in many places). Unfortunately this is not reproduceable with my available bt devices. Poweramp uses the latest available libraries with each build. Also, after 828 support for additional queue was added (this can be disabled in Settings / Misc / Nowplaying List For Connected Devices/Apps (can be disabled). Thanks!

[Lox68]

Max I tried your advice but to no avail, impossible to make it work.  Downgraded back to v828 and everything ok again.  Strange though, other music players like Google Music Player and ddb2 have no such issues on Android 9.

 

 

[maxmp]

@Lox68 if you can capture logcat from the phone around the moment you change tracks and the issue reproduces, that can be really helpful in debugging this issue. Thanks!

[Lox68]

@Max, captured two logcats with v828 and v841 while changing tracks (see enclosed)

What I observe is that with v841 there is an exception getting the Attribute Values of a song due to the fact that the music file index is repeated twice, see uid: "00 00 00 00 01 46 01 46" and "0x00 00 00 00 01 46 01 46" : (0x0146 is repeated twice)

08-15 18:58:31.845 16291 16331 D bt_btif : btif_av_state_started_handler event:BTA_AV_META_MSG_EVT flags 0  index =0
08-15 18:58:31.845 16291 16331 D bt_btif : btif_rc_handler: event: BTA_AV_META_MSG_EVT
08-15 18:58:31.845 16291 16331 D bt_btif : btif_rc_upstreams_evt: pdu: AVRC_PDU_GET_ITEM_ATTRIBUTES handle: 0x0 ctype: 0 label: d event ID: 3
08-15 18:58:31.845 16291 16331 W bt_device_profile: profile_feature_fetch:profile 1
08-15 18:58:31.846 16291 16331 W bt_device_profile: profile_feature_fetch:AVRCP_0103_SUPPORT found 0
08-15 18:58:31.846 16291 16331 V Avrcp_ext: Enter getItemAttrRequestFromNative
08-15 18:58:31.846 16291 16331 V Avrcp_ext: Exit getItemAttrRequestFromNative
08-15 18:58:31.846 16291 20710 V Avrcp_ext: AvrcpMessageHandler: received message=11
08-15 18:58:31.847 16291 20710 V Avrcp_ext: MSG_NATIVE_REQ_GET_ITEM_ATTR [ItemAttrCmd: scope 3 uid 00 00 00 00 01 46 01 46 numAttr 7 attrs: 1 2 3 4 5 6 7 
08-15 18:58:31.848 16291 20710 D AddressedMediaPlayer: getItemAttr-UID: 0x00 00 00 00 01 46 01 46
08-15 18:58:31.848 16291 20710 E AvrcpMediaRsp: getItemAttrRsp: rspObj is null or rspStatus is error:9
08-15 18:58:31.848 16291 20710 V Avrcp_ext: Exit handleMessage

No such exception with v828 because file index is correct:

08-15 17:14:43.456 16291 16331 D bt_btif : btif_rc_handler: event: BTA_AV_META_MSG_EVT
08-15 17:14:43.456 16291 16331 D bt_btif : btif_rc_upstreams_evt: pdu: AVRC_PDU_GET_ITEM_ATTRIBUTES handle: 0x0 ctype: 0 label: 8 event ID: 3
08-15 17:14:43.456 16291 16331 W bt_device_profile: profile_feature_fetch:profile 1
08-15 17:14:43.456 16291 16331 W bt_device_profile: profile_feature_fetch:AVRCP_0103_SUPPORT found 0
08-15 17:14:43.456 16291 16331 V Avrcp_ext: Enter getItemAttrRequestFromNative
08-15 17:14:43.457 16291 16331 V Avrcp_ext: Exit getItemAttrRequestFromNative
08-15 17:14:43.457 16291 20710 V Avrcp_ext: AvrcpMessageHandler: received message=11
08-15 17:14:43.458 16291 20710 V Avrcp_ext: MSG_NATIVE_REQ_GET_ITEM_ATTR [ItemAttrCmd: scope 3 uid 00 00 00 00 00 00 01 43 numAttr 7 attrs: 1 2 3 4 5 6 7 
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttr-UID: 0x00 00 00 00 00 00 01 43
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttrFilterAttr: attr id[0] :1
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttrFilterAttr: attr id[1] :2
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttrFilterAttr: attr id[2] :3
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttrFilterAttr: attr id[3] :4
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttrFilterAttr: attr id[4] :5
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttrFilterAttr: attr id[5] :6
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttrFilterAttr: attr id[6] :7
08-15 17:14:43.462 16291 20710 D AddressedMediaPlayer: getItemAttrFilterAttr: attr id list size:7
08-15 17:14:43.462 16291 20717 W bt_btif : btif_a2dp_ctrl_cb: A2DP-CTRL-CHANNEL EVENT UIPC_RX_DATA_READY_EVT
08-15 17:14:43.462 16291 20717 W bt_btif : btif_a2dp_command_ack: ## a2dp ack : A2DP_CTRL_GET_PRESENTATION_POSITION, queued : A2DP_CTRL_CMD_NONE,  status 0 ##
08-15 17:14:43.463 16291 20710 D AddressedMediaPlayer: getAttrValue: item is active, using current data
08-15 17:14:43.465 16291 20710 D AddressedMediaPlayer: getAttrValue: item MediaSession.QueueItem {Description=Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a, Id=323 } : Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a
08-15 17:14:43.465 16291 20710 D AddressedMediaPlayer: getAttrValue: attrvalue = Skin & Bones (Radio Edit), attr id: 1
08-15 17:14:43.466 16291 20710 D AddressedMediaPlayer: getAttrValue: item is active, using current data
08-15 17:14:43.467 16291 20710 D AddressedMediaPlayer: getAttrValue: item MediaSession.QueueItem {Description=Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a, Id=323 } : Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a
08-15 17:14:43.467 16291 20710 D AddressedMediaPlayer: getAttrValue: attrvalue = Terranova Feat. Lydmor & Bon Homme, attr id: 2
08-15 17:14:43.467 16291 20710 D AddressedMediaPlayer: getAttrValue: item is active, using current data
08-15 17:14:43.468 16291 20710 D AddressedMediaPlayer: getAttrValue: item MediaSession.QueueItem {Description=Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a, Id=323 } : Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a
08-15 17:14:43.468 16291 20710 D AddressedMediaPlayer: getAttrValue: attrvalue = City Lounge - The Deep Session 02, attr id: 3
08-15 17:14:43.469 16291 20710 D AddressedMediaPlayer: getAttrValue: item is active, using current data
08-15 17:14:43.470 16291 20710 D AddressedMediaPlayer: getAttrValue: item MediaSession.QueueItem {Description=Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a, Id=323 } : Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a
08-15 17:14:43.470 16291 20710 D AddressedMediaPlayer: getAttrValue: attrvalue = 323, attr id: 4
08-15 17:14:43.471 16291 20710 D AddressedMediaPlayer: getAttrValue: item is active, using current data
08-15 17:14:43.471 16291 20710 D AddressedMediaPlayer: getAttrValue: item MediaSession.QueueItem {Description=Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a, Id=323 } : Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a
08-15 17:14:43.472 16291 20710 D AddressedMediaPlayer: getAttrValue: attrvalue = 795, attr id: 5
08-15 17:14:43.472 16291 20710 D AddressedMediaPlayer: getAttrValue: item is active, using current data
08-15 17:14:43.473 16291 20710 D AddressedMediaPlayer: getAttrValue: item MediaSession.QueueItem {Description=Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a, Id=323 } : Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a
08-15 17:14:43.473 16291 20710 D AddressedMediaPlayer: getAttrValue: attrvalue = null, attr id: 6
08-15 17:14:43.473 16291 20710 D AddressedMediaPlayer: getAttrValue: item is active, using current data
08-15 17:14:43.474 16291 20710 D AddressedMediaPlayer: getAttrValue: item MediaSession.QueueItem {Description=Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a, Id=323 } : Skin & Bones (Radio Edit), Terranova Feat. Lydmor & Bon Homme - City Lounge - The Deep Session 02, 7:00 | m4a
08-15 17:14:43.474 16291 20710 D AddressedMediaPlayer: getAttrValue: attrvalue = 420142, attr id: 7
08-15 17:14:43.479 16291 20710 V Avrcp_ext: Exit handleMessage

 

 

logcat_v828.log

logcat_v841.log

[ptzink]

Tag info is not displaying on my car (2016 Toyota Prius) either on V3 build 841. I am on Android 10. Enabling avrcp 1.3 displays tags on the head unit again, but removes my ability to browse my phone's music library from the head unit, which is not ideal. Google Play Music, Rocket Player, Spotify, and Podcast Addict all display media info properly when using avrcp 1.4. I have also enabled metachanged intent in PA settings.  

[Lox68]

Verified against v842 but issue still not fixed ... Back to v828 again 😰

[Lox68]

@Max, out of curiosity, did you have a look at both logcats that you asked me to provide ?  See my post of August 19th.

[Lox68]

A couple of weeks ago my Nokia 6 with Android 9 got an MR (Maintenance Release) upgrade of 1.6GB.  So as a test I decided to upgrade PA to the latest version.  Result: Tag Display started to work! Bug Report can be closed.