[Android Auto General] rinaldimj:
Same issue still

I have the same issue since the beginning of the year. I've even wiped everything and re-installed the official Google image. Any suggestions?

EDIT 1: I'm going to copy and paste the description of the bug as I detailed at the Android Auto Help forum, because there the question was blocked (?)

I'll need some help interpreting the logs, I'll edit this post later.

EDIT 2: There's something that happens that is very strange. If I ask assistant to just "play music" it works, but if I ask assistant for a specific artist or song, it fails. I repeat: this happens only when using Android Auto.

I'm adding two logs, one after a successful voice command saying "play music", and another after a failed voice command saying "play <an artist>"

Successful voice command ("play music")

GH.AssistantUtils: Session Event: {
GH.AssistantUtils: Time Since Assistant Session Start Millis: 7039
GH.AssistantUtils: Event: VOICE_SESSION_END_PERFORMED
GH.AssistantUtils: }
GH.AssistantUtils: }
GH.AssistantUtils: }
GH.DefaultAppStorage: readDefaultApp com.google.android.apps.maps/com.google.android.maps.MapsActivity (Legacy=false)
GH.AvailableAppFinder: For AvailableAppFinderQuery[carDisplayType=MAIN,facetType=NAVIGATION,androidAutoUiMode=VANAGON,isPackageNameSet=true,packageName=com.google.android.apps.maps,filters=[ENABLED_BY_USER, COMPATIBLE_WITH_VEHICLE]] found available apps: [ComponentInfo{com.google.android.apps.maps/com.google.android.maps.MapsActivity}]
GH.DemandController: New demand space state 1
GH.VnAppBar: onDemandSpaceStateChanged 1
GH.MediaModel: onMetadataChanged(null, null, null)
GH.MediaPlaybackMonitor: onMetadataChanged called with null, null, null
GH.MediaPlaybackMonitor: Invalid metadata, no title and subtitle.
GH.MediaSIM: received null, null, null
GH.MediaSIM: Invalid metadata, no title or subtitle, canceling stream item
GH.StreamItemManager: cancel() dii{type=MEDIA, id=-4151775864118476492, contentId=-145261751}
GH.StreamItemManager: cancel() type=<pfg@26c716c number=2 name=MEDIA>,id=-4151775864118476492
GH.SimToNSAdapter: processStreamItemRemoved dii{type=MEDIA, id=-4151775864118476492, contentId=-145261751}
GH.MPAdapter: getAsNotificationWorker for dii{type=MEDIA, id=-4151775864118476492, contentId=-145261751} component=ComponentInfo{com.google.android.apps.youtube.music/com.google.android.apps.youtube.music.mediabrowser.MusicBrowserService}
GH.MPAdapter: getLeftAction forceDefault=true prevIsAvailable=true customActions=[]
GH.MPAdapter: getRightAction forceDefault=true prevIsAvailable=false customActions=[]
GH.NotificationStore: on cancel: t=MEDIA, id=-4151775864118476492, pkg=com.google.android.apps.youtube.music
GH.UnOverviewActivity: onChange 1 -> 0
GH.UnOverviewActivity: showClockScreen
GH.NotificationSim: cancel() dii{type=MEDIA, id=-4151775864118476492, contentId=-145261751}
GH.NotificationSim: cancel() type=<pfg@26c716c number=2 name=MEDIA>,id=-4151775864118476492
GH.NotificationSim: Canceling a non-existent stream item
GH.MediaModel: onMetadataChanged(null, null, null)
GH.MediaModel: onMetadataChanged(null, null, null)
GH.DemandController: setDemandSpaceView view=null
GH.AssistantVoicePlate: setVoicePlateView null
VideoCapabilities: Unrecognized profile 2130706433 for video/avc
VideoCapabilities: Unrecognized profile 2130706434 for video/avc
YT.mlplayer: MedialibPlayer.loadVideo(cpn=C51PbjH0hQmYPWf- playWhenReady=true positionMs=0 playerEvents=[41] videoId=scrubbed mediaView=null volume=1.0)
CCodec : allocate(c2.android.aac.decoder)
Codec2Client: Available Codec2 services: "software"
Codec2Client: Creating a Codec2 client to service "software"
Codec2Client: Client to Codec2 service "software" created
C2Store : in init
C2Store : loading dll
CCodec : Created component [c2.android.aac.decoder]
CCodecConfig: read media type: audio/mp4a-latm
ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.max-count.values
ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values
ReflectedParamUpdater: extent() != 1 for single value type: input.buffers.allocator-ids.values
ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.allocator-ids.values
ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.allocator-ids.values
ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values
ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.pool-ids.values
CCodecConfig: query failed after returning 16 values (BAD_INDEX)
CCodecConfig: c2 config is Dict {
CCodecConfig: c2::u32 coded.aac-packaging.value = 0
CCodecConfig: c2::u32 coded.bitrate.value = 64000
CCodecConfig: c2::u32 coded.pl.level = 0
CCodecConfig: c2::u32 coded.pl.profile = 8192
CCodecConfig: c2::float coding.drc.attenuation-factor.value = 1
CCodecConfig: c2::float coding.drc.boost-factor.value = 1
CCodecConfig: c2::i32 coding.drc.compression-mode.value = 3
CCodecConfig: c2::i32 coding.drc.effect-type.value = 3
CCodecConfig: c2::float coding.drc.encoded-level.value = 0.25
CCodecConfig: c2::float coding.drc.reference-level.value = -16
CCodecConfig: c2::u32 input.buffers.max-size.value = 8192
CCodecConfig: c2::u32 input.delay.value = 0
CCodecConfig: string input.media-type.value = "audio/mp4a-latm"
CCodecConfig: c2::u32 output.delay.value = 2
CCodecConfig: string output.media-type.value = "audio/raw"
CCodecConfig: c2::u32 raw.channel-count.value = 1
CCodecConfig: c2::u32 raw.sample-rate.value = 44100
CCodecConfig: }
CCodecConfig: c2 config is Dict {
CCodecConfig: c2::u32 coded.aac-packaging.value = 0
CCodecConfig: c2::u32 coded.bitrate.value = 64000
CCodecConfig: c2::u32 coded.pl.level = 0
CCodecConfig: c2::u32 coded.pl.profile = 8192
CCodecConfig: c2::float coding.drc.attenuation-factor.value = 1
CCodecConfig: c2::float coding.drc.boost-factor.value = 1
CCodecConfig: c2::i32 coding.drc.compression-mode.value = 3
CCodecConfig: c2::i32 coding.drc.effect-type.value = 3
CCodecConfig: c2::float coding.drc.encoded-level.value = 0.25
CCodecConfig: c2::float coding.drc.reference-level.value = -16
CCodecConfig: c2::u32 input.buffers.max-size.value = 8192
CCodecConfig: c2::u32 input.delay.value = 0
CCodecConfig: string input.media-type.value = "audio/mp4a-latm"
CCodecConfig: c2::u32 output.delay.value = 2
CCodecConfig: string output.media-type.value = "audio/raw"
CCodecConfig: c2::u32 raw.channel-count.value = 2
CCodecConfig: c2::u32 raw.sample-rate.value = 44100
CCodecConfig: }
Codec2Client: query -- param skipped: index = 1107298332.
CCodec : setup formats input: AMessage(what = 0x00000000) = {
CCodec : int32_t channel-count = 2
CCodec : int32_t level = 0
CCodec : int32_t max-input-size = 8192
CCodec : string mime = "audio/mp4a-latm"
CCodec : int32_t profile = 2
CCodec : int32_t sample-rate = 44100
CCodec : } and output: AMessage(what = 0x00000000) = {
CCodec : int32_t channel-count = 2
CCodec : string mime = "audio/raw"
CCodec : int32_t sample-rate = 44100
CCodec : }
Codec2Client: query -- param skipped: index = 1342179345.
Codec2Client: query -- param skipped: index = 2415921170.
Codec2Client: query -- param skipped: index = 1610614798.
CCodecBufferChannel: [c2.android.aac.decoder#660] Created input block pool with allocatorID 16 => poolID 17 - OK (0)
ashmem : Failed to dlopen() libashmemd_client.so: dlopen failed: library "libashmemd_client.so" not found
ashmem : Failed to dlopen() libashmemd_client.so: dlopen failed: library "libashmemd_client.so" not found
CCodecBufferChannel: [c2.android.aac.decoder#660] Created output block pool with allocatorID 16 => poolID 2710 - OK
CCodecBufferChannel: [c2.android.aac.decoder#660] Configured output block pool ids 2710 => OK
ion : ioctl c0044901 failed with code -1: Invalid argument
SimpleC2Component: Using output block pool with poolID 2710 => got 2710 - 0
C2SoftAacDec: Reconfiguring decoder: 0->44100 Hz, 0->2 channels
ashmem : Failed to dlopen() libashmemd_client.so: dlopen failed: library "libashmemd_client.so" not found
AudioFlinger: Client defaulted notificationFrames to 5512 for frameCount 11025
AF::TrackHandle: OpPlayAudio: track:3991 usage:1 not muted
CCodecBuffers: [c2.android.aac.decoder#660:1D-Input.Impl[N]] codec released a buffer owned by client (index 1)
CCodecBuffers: [c2.android.aac.decoder#660:1D-Input.Impl[N]] codec released a buffer owned by client (index 3)
CCodecBuffers: [c2.android.aac.decoder#660:1D-Input.Impl[N]] codec released a buffer owned by client (index 0)
CCodecBuffers: [c2.android.aac.decoder#660:1D-Input.Impl[N]] codec released a buffer owned by client (index 2)
CCodecBuffers: [c2.android.aac.decoder#660:1D-Input.Impl[N]] codec released a buffer owned by client (index 3)
CCodecBuffers: [c2.android.aac.decoder#660:1D-Output.Impl[N]] Client returned a buffer it does not own according to our record: 0
CCodecBuffers: [c2.android.aac.decoder#660:1D-Output.Impl[N]] Client returned a buffer it does not own according to our record: 1
CCodecBuffers: [c2.android.aac.decoder#660:1D-Output.Impl[N]] Client returned a buffer it does not own according to our record: 2
CCodecBuffers: [c2.android.aac.decoder#660:1D-Output.Impl[N]] Client returned a buffer it does not own according to our record: 3
Codec2Client: query -- param skipped: index = 1342179345.
Codec2Client: query -- param skipped: index = 2415921170.
Codec2Client: query -- param skipped: index = 1610614798.
Codec2Client: query -- param skipped: index = 1342179345.
Codec2Client: query -- param skipped: index = 2415921170.
Codec2Client: query -- param skipped: index = 1610614798.
GH.MediaModel: onPlaybackStateChanged state=PAUSED (PlaybackState {state=2, position=0, buffered position=0, speed=1.0, updated=547504009, actions=12183, error code=0, error message=null, custom actions=[], active item id=-1})
GH.MediaModel: onPlaybackStateChanged state=PAUSED (PlaybackState {state=2, position=0, buffered position=0, speed=1.0, updated=547504009, actions=12183, error code=0, error message=null, custom actions=[], active item id=-1})
GH.MediaPlaybackMonitor: onPlaybackStateChanged 2
GH.MediaModel: onPlaybackStateChanged state=PAUSED (PlaybackState {state=2, position=0, buffered position=0, speed=1.0, updated=547504009, actions=12183, error code=0, error message=null, custom actions=[], active item id=-1})
GH.AvailableAppFinder: For AvailableAppFinderQuery[carDisplayType=MAIN,facetType=MUSIC,androidAutoUiMode=VANAGON,isPackageNameSet=false,filters=[ENABLED_BY_USER, COMPATIBLE_WITH_VEHICLE]] found available apps: [ComponentInfo{com.google.android.googlequicksearchbox/com.google.android.apps.gsa.staticplugins.mediabrowser.PodcastsMediaBrowserService}, ComponentInfo{com.google.android.apps.youtube.music/com.google.android.apps.youtube.music.mediabrowser.MusicBrowserService}]
GH.AvailableAppFinder: For AvailableAppFinderQuery[carDisplayType=MAIN,facetType=MUSIC,androidAutoUiMode=VANAGON,isPackageNameSet=false,filters=[ENABLED_BY_USER, COMPATIBLE_WITH_VEHICLE]] found available apps: [ComponentInfo{com.google.android.googlequicksearchbox/com.google.android.apps.gsa.staticplugins.mediabrowser.PodcastsMediaBrowserService}, ComponentInfo{com.google.android.apps.youtube.music/com.google.android.apps.youtube.music.mediabrowser.MusicBrowserService}]
WificondControl: Scan result ready event
GH.MediaManager: onPlaybackStateChanged
GH.MediaManager: onPlaybackStateChanged. New playing state: 2
WificondScannerImpl: Filtering out 5 scan results.
AudioFlinger: Client defaulted notificationFrames to 5512 for frameCount 11025
AF::TrackHandle: OpPlayAudio: track:3992 usage:1 not muted
MediaSessionService: Media button session is changed to com.google.android.apps.youtube.music/YouTube playerlib (userId=0)
AvrcpMediaPlayerList: onAddressedPlayerChanged: token=com.google.android.apps.youtube.music
AudioFlinger: moveEffectChain_l() effect chain for session 0 not on source thread 0x7ecf0e45c0
volume_listener: check_and_set_gain_dep_cal: Failed to set gain dep cal level
SettingsProvider: Notifying for 0: content://settings/secure/media_button_receiver
ACDB-LOADER: ACDB AFE returned = -19
audio_hw_primary: enable_audio_route: usecase(0) apply and update mixer path: deep-buffer-playback speaker
audio_route: Apply path: deep-buffer-playback speaker
ANDR-PERF-OPTSHANDLER: Warning: Resource [2, 0] not supported for core 1. Instead use resource for core 0
ANDR-PERF-RESOURCEQS: Failed to apply optimization [2, 2, 0]
GH.AvailableAppFinder: For AvailableAppFinderQuery[carDisplayType=MAIN,facetType=MUSIC,androidAutoUiMode=VANAGON,isPackageNameSet=false,filters=[ENABLED_BY_USER, COMPATIBLE_WITH_VEHICLE]] found available apps: [ComponentInfo{com.google.android.googlequicksearchbox/com.google.android.apps.gsa.staticplugins.mediabrowser.PodcastsMediaBrowserService}, ComponentInfo{com.google.android.apps.youtube.music/com.google.android.apps.youtube.music.mediabrowser.MusicBrowserService}]
AvrcpMediaPlayerList: setActivePlayer(): setting player to com.google.android.apps.youtube.music
AvrcpMediaPlayerList: sendMediaUpdate
AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue
AvrcpTargetService: onMediaUpdated: track_changed=false state=true queue=false
AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=true queue=false
AvrcpTargetJni: sendMediaUpdateNative
bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=1 : queue=0
audio_hw_primary: out_write: retry previous failed cal level set
volume_listener: check_and_set_gain_dep_cal: Failed to set gain dep cal level
AvrcpMediaPlayerList: onActiveSessionsChanged: number of controllers: 1
AvrcpMediaPlayerList: onActiveSessionsChanged: controller: com.google.android.apps.youtube.music
AvrcpMediaPlayerList: Already have a controller for the player: com.google.android.apps.youtube.music, updating instead
GH.MediaModel: onMetadataChanged(Street Spirit (Fade Out), null, null)
AudioTrack: getTimestamp_l(5676): device stall time corrected using current time 233077766301191
GH.MediaPlaybackMonitor: onMetadataChanged called with Street Spirit (Fade Out), null, null
GH.MediaSIM: received Street Spirit (Fade Out), null, null
GH.MediaSIM: Update notification

Failed voice command ("play <artist>")

GH.AssistantUtils: Session Event: {
GH.AssistantUtils: Time Since Assistant Session Start Millis: 9013
GH.AssistantUtils: Event: VOICE_SESSION_END_PERFORMED
GH.AssistantUtils: }
GH.AssistantUtils: }
GH.AssistantUtils: }
GH.DefaultAppStorage: readDefaultApp com.google.android.apps.maps/com.google.android.maps.MapsActivity (Legacy=false)
GH.AvailableAppFinder: For AvailableAppFinderQuery[carDisplayType=MAIN,facetType=NAVIGATION,androidAutoUiMode=VANAGON,isPackageNameSet=true,packageName=com.google.android.apps.maps,filters=[ENABLED_BY_USER, COMPATIBLE_WITH_VEHICLE]] found available apps: [ComponentInfo{com.google.android.apps.maps/com.google.android.maps.MapsActivity}]
GH.DemandController: New demand space state 1
GH.VnAppBar: onDemandSpaceStateChanged 1
AppOps : Noting op not finished: uid 10114 pkg com.google.android.gms code 79 time=1601076852137 duration=0
GH.DemandController: setDemandSpaceView view=null
GH.AssistantVoicePlate: setVoicePlateView null
nanohub : osLog: [AR_CHRE] still: 100
YT.MBS.Callback: MBS client com.google.android.projection.gearhead result: onPlayFromSearch() : 1
GH.MediaModel: onPlaybackStateChanged state=ERROR (PlaybackState {state=7, position=0, buffered position=0, speed=1.0, updated=547528018, actions=12215, error code=1, error message=Cannot play song., custom actions=[], active item id=-1})
GH.MediaPlaybackMonitor: onPlaybackStateChanged 7
GH.StreamItemManager: postInternal: Posting dii{type=MEDIA, id=-4151775864118476492, contentId=-145261751}. #items 5 -> 5
GH.SimToNSAdapter: processing SI: dii{type=MEDIA, id=-4151775864118476492, contentId=-145261751}
GH.MPAdapter: getAsNotificationWorker for dii{type=MEDIA, id=-4151775864118476492, contentId=-145261751} component=ComponentInfo{com.google.android.apps.youtube.music/com.google.android.apps.youtube.music.mediabrowser.MusicBrowserService}
AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): com.google.android.apps.youtube.music : PlaybackState {state=7, position=0, buffered position=0, speed=1.0, updated=547528018, actions=12215, custom actions=[], active item id=-1, error=Cannot play song.}
GH.MPAdapter: media playback state is ERROR
GH.NotificationStore: on notify: t=MEDIA id=-4151775864118476492 n=efl{package=com.google.android.apps.youtube.music, category=MEDIA, priority=LOW, alertOnlyOnce=false, isOngoing=true, smallIcon=Icon(typ=APP_ICON componentName=ComponentInfo{com.google.android.apps.youtube.music/com.google.android.apps.youtube.music.mediabrowser.MusicBrowserService}), contentIntent=PendingIntent{2b41ebc: android.os.BinderProxy@9768e45}, largeIcon=null, action1=null, action2=null, action3=null, statusBarNotificationKey=null, isLegacyDndSuppressedMessagingNotification=false}
GH.AvailableAppFinder: For AvailableAppFinderQuery[carDisplayType=MAIN,facetType=MUSIC,androidAutoUiMode=VANAGON,isPackageNameSet=false,filters=[ENABLED_BY_USER, COMPATIBLE_WITH_VEHICLE]] found available apps: [ComponentInfo{com.google.android.googlequicksearchbox/com.google.android.apps.gsa.staticplugins.mediabrowser.PodcastsMediaBrowserService}, ComponentInfo{com.google.android.apps.youtube.music/com.google.android.apps.youtube.music.mediabrowser.MusicBrowserService}]
AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for com.google.android.apps.youtube.music
AvrcpMediaPlayerList: sendMediaUpdate
AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue
AvrcpTargetService: onMediaUpdated: track_changed=false state=true queue=false
AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=true queue=false
GH.AvailableAppFinder: For AvailableAppFinderQuery[carDisplayType=MAIN,facetType=MUSIC,androidAutoUiMode=VANAGON,isPackageNameSet=false,filters=[ENABLED_BY_USER, COMPATIBLE_WITH_VEHICLE]] found available apps: [ComponentInfo{com.google.android.googlequicksearchbox/com.google.android.apps.gsa.staticplugins.mediabrowser.PodcastsMediaBrowserService}, ComponentInfo{com.google.android.apps.youtube.music/com.google.android.apps.youtube.music.mediabrowser.MusicBrowserService}]
AvrcpTargetJni: sendMediaUpdateNative
bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=1 : queue=0
GH.MediaManager: onPlaybackStateChanged
GH.MediaManager: onPlaybackStateChanged. New playing state: 7
GH.CrossfadeImageView: setImageBitmapColorScrim(bitmap=android.graphics.Bitmap@70d1f87 saturationLevel=0.000000 color=-14273992 alpha=0.900000 showAnimation=true)
ClearcutMetricTransmitt: Sending Primes timer metric
GH.MediaModel: onPlaybackStateChanged state=ERROR (PlaybackState {state=7, position=0, buffered position=0, speed=1.0, updated=547528018, actions=12215, error code=1, error message=Cannot play song., custom actions=[], active item id=-1})
GH.MediaModel: onPlaybackStateChanged state=ERROR (PlaybackState {state=7, position=0, buffered position=0, speed=1.0, updated=547528018, actions=12215, error code=1, error message=Cannot play song., custom actions=[], active item id=-1})

Probably it will be better to filter this results somehow, but this is my first experience with this and I'm trying to figure out what is important and what not.