Related
I am trying to build a ROM from the AOSP, i am having a great deal of success but i am having issues with the camera app. When i run it i get the following logcat
Code:
V/RenderScript_jni( 1581): surfaceDestroyed
E/msm7x30.gralloc( 1497): cannot flush handle 0x2c4b80 (offs=a43000 len=32000)
E/msm7x30.gralloc( 1497): cannot flush handle 0x2f05e0 (offs=2ef000 len=145000)
E/msm7x30.gralloc( 1497): cannot flush handle 0x2aa668 (offs=434000 len=32000)
E/msm7x30.gralloc( 1497): cannot flush handle 0x2aa668 (offs=434000 len=32000)
V/CameraHolder( 1767): open camera 0
I/mm-camera( 1272): openCameraHardware: call createInstance
D/mm-camera( 1272): createInstance: E
D/mm-camera( 1272): Storing the current target type as 2
D/mm-camera( 1272): constructor EX
D/mm-camera( 1272): startCamera E
D/mm-camera( 1272): loading liboemcamera at 0xb000dc88
I/mm-camera( 1272): Set main cam
I/mm-camera( 1272): set sensor selection :0
I/mm-camera( 1272): Open Device node : /dev/msm_camera/control1
I/mm-camera( 1272): Open Device node : /dev/msm_camera/config1
I/mm-camera( 1272): [AWB Calibration] from kernel: fuse_id: 0x0, 0x0, 0x4, 0x216df4
I/mm-camera-s5k3h1gx_u( 1272): Inside s5k3h1gx_process_start
I/mm-camera( 1272): [LSC Calibration]Sensor_set_mesh
I/mm-camera( 1272): [LSC Calibration] : call awb_get_fuseid
I/mm-camera( 1272): Read /data/lsc_cal fail
I/mm-camera( 1272): Read from file OK: /data/lsc_cal or /sys/android_camera_awb_cal/awb_cal
I/mm-camera( 1272): [LSC Calibration]: lsc_checksum=d43f0087, lsc_verify=c1b
I/mm-camera( 1272): [LSC Calibration]: fuseid_1=0,fuseid_2=0,fuseid_3=4,fuseid_4=216df4, sfuse.fuse_id_word1=0,sfuse.fuse_id_word2=0,sfuse.fuse_id_wor
d3=4,sfuse.fuse_id_word4=216df4
I/mm-camera( 1272): LSC_Calibration: addr=a77b0d2c, & addr=a77b0d2c
I/mm-camera( 1272): LSC_Calibration: addr=a77b1b00, & addr=a77b1b00
I/mm-camera( 1272): LSC_Calibration: addr=a77b28d4, & addr=a77b28d4
I/mm-camera( 1272): LSC_Calibration: addr=a77b36a8, & addr=a77b36a8
I/mm-camera( 1272): LSC_Calibration: addr=a77b447c, & addr=a77b447c
I/mm-camera-s5k3h1gx_u( 1272): s5k3h1gx_process_start: [LSC Calibration] LSC is valid and set NEW mesh value
I/mm-camera( 1272): [AWB Calibration] fuse_id : 0x0, 0x0, 0x4, 0x216df4
I/mm-camera( 1272): [AWB Calibration] fail to read /data/awb_cal, try another file
I/mm-camera( 1272): [AWB Calibration] read from file OK: /sys/android_camera_awb_cal/awb_cal
I/mm-camera( 1272): [AWB Calibration] nandflash data: 0xc1b, 0x2d2, 0x2a5, 0x0, 0x0, 0x4, 0x216df4, 0xf8a3e887
I/mm-camera( 1272): [AWB Calibration] check sum=f8a3e887, OK
I/mm-camera( 1272): [AWB Calibration] valid bit and fuseID pass, use new white points
I/mm-camera( 1272): [AWB Calibration] use light version: v3
I/mm-camera-s5k3h1gx_u( 1272): s5k3h1gx_register: table = 0xa77df5f0
I/mm-camera-s5k3h1gx_u( 1272): get snapshot size pointer:0xa7725e39
D/mm-camera( 1272): sensor_led_ctrl_init: camfd = 22
D/mm-camera( 1272): af_init_process_exhaustive_search sensor->af_exhaust_downhill_allowance 2, sensor->af_exhaust_fv_drop_allowance 0.800000
I/mm-camera( 1272): startCamera: camsensor name s5k3h1gx, flash 1
I/mm-camera( 1272): sctrl:0xa77dea50
I/mm-camera( 1272): sctrl->fn_table:0xa7726991
I/mm-camera( 1272): sctrl->fn_table.get_snapshot_sizes:0xa7725e39
I/mm-camera-s5k3h1gx_u( 1272): get table
D/mm-camera( 1272): startCamera X
D/mm-camera( 1272): initDefaultParameters E
D/mm-camera( 1272): product 11
D/mm-camera( 1272): init thumbnail width: 640, height: 480
D/mm-camera( 1272): native_access_parm: fd 16, type 78, length 2
D/mm-camera( 1272): native_access_parm: fd 16, type 1, length 32
W/mm-camera( 1272): native_access_parm: error (No such file or directory): fd 16, type 1, length 32, status 0
D/mm-camera( 1272): native_access_parm: fd 16, type 68, length 4
D/mm-camera( 1272): native_access_parm: fd 16, type 69, length 4
D/mm-camera( 1272): native_access_parm: fd 16, type 70, length 4
V/mm-camera( 1272): setParameters: E params = 0x292f4
D/mm-camera( 1272): requested preview size 640 x 480
D/mm-camera( 1272): requested picture size 3264 x 2448
D/mm-camera( 1272): requested Fps Range min: 9000 x max: 28261
D/mm-camera( 1272): native_access_parm: fd 16, type 21, length 4
D/mm-camera( 1272): native_access_parm: fd 16, type 15, length 4
D/mm-camera( 1272): native_access_parm: fd 16, type 14, length 4
D/mm-camera( 1272): setFlash 0
D/mm-camera( 1272): native_access_parm: fd 16, type 49, length 4
D/mm-camera( 1272): In config_proc_CAMERA_SET_PARM_LED_MODE: 0
D/mm-camera( 1272): set_led_mode = 0, led_ctrl_p->led_mode = 0
D/mm-camera( 1272): flip-video = -1
V/mm-camera( 1272): Set zoom=0 40
D/mm-camera( 1272): native_access_parm: fd 16, type 2, length 4
I/mm-camera( 1272): Setting Contrast is 5
D/mm-camera( 1272): native_access_parm: fd 16, type 6, length 4
D/mm-camera( 1272): [ATS][adjust_contrast][complete]
I/mm-camera( 1272): Setting Brightness is 3
D/mm-camera( 1272): native_access_parm: fd 16, type 7, length 4
I/mm-camera( 1272): Setting Saturation is 5
D/mm-camera( 1272): native_access_parm: fd 16, type 11, length 4
D/mm-camera( 1272): [ATS][adjust_saturation][complete]
I/mm-camera( 1272): Setting Sharpness is 10
D/mm-camera( 1272): native_access_parm: fd 16, type 9, length 4
D/mm-camera( 1272): [ATS][adjust_sharpness][complete]
D/mm-camera( 1272): native_access_parm: fd 16, type 12, length 4
D/mm-camera( 1272): not defined touch-focus yet, or AP without touch-focus function
I/mm-camera( 1272): Before Setting coordinate x:320 y:240
I/mm-camera( 1272): Setting coordinate x:2 y:2
D/mm-camera( 1272): native_access_parm: fd 16, type 64, length 4
D/mm-camera( 1272): native_access_parm: fd 16, type 73, length 18
D/mm-camera( 1272): native_access_parm: fd 16, type 26, length 4
D/mm-camera( 1272): native_access_parm: fd 16, type 62, length 4
D/mm-camera( 1272): native_access_parm: fd 16, type 8, length 4
V/mm-camera( 1272): setThumbnailSize w=640 h=480
D/mm-camera( 1272): virtual android::status_t android::QualcommCameraHardware::setParameters(const android::CameraParameters&), final_rc=0
V/mm-camera( 1272): setParameters: X
D/mm-camera( 1272): initDefaultParameters X
D/mm-camera( 1272): createInstance: X created hardware=0x292f0
V/mm-camera( 1272): encodeData: E
D/mm-camera( 1272): encodeData: Do nothing
V/mm-camera( 1272): encodeData: X
D/dalvikvm( 1672): GC_EXTERNAL_ALLOC freed 506K, 56% free 2938K/6663K, external 1875K/2177K, paused 24ms
D/dalvikvm( 1672): GC_FOR_MALLOC freed 166K, 57% free 2898K/6663K, external 1731K/2177K, paused 16ms
I/dalvikvm-heap( 1672): Grow heap (frag case) to 8.269MB for 1440010-byte allocation
D/dalvikvm( 1672): GC_FOR_MALLOC freed 5K, 47% free 4299K/8071K, external 1635K/2147K, paused 15ms
D/dalvikvm( 1672): GC_CONCURRENT freed 0K, 47% free 4299K/8071K, external 1635K/2147K, paused 2ms+2ms
W/WindowManager( 1379): App freeze timeout expired.
W/WindowManager( 1379): Force clearing freeze: AppWindowToken{4056c198 token=HistoryRecord{4056b038 com.android.camera/.Camera}}
E/msm7x30.gralloc( 1379): cannot flush handle 0x3f2fa8 (offs=5de000 len=177000)
D/dalvikvm( 1672): GC_FOR_MALLOC freed 1418K, 65% free 2897K/8071K, external 1635K/2147K, paused 16ms
I/dalvikvm-heap( 1672): Grow heap (frag case) to 7.849MB for 1099569-byte allocation
D/dalvikvm( 1672): GC_FOR_MALLOC freed 3K, 51% free 3967K/8071K, external 1635K/2147K, paused 15ms
D/dalvikvm( 1672): GC_CONCURRENT freed 0K, 51% free 3967K/8071K, external 1704K/2147K, paused 3ms+2ms
D/dalvikvm( 1672): GC_FOR_MALLOC freed 1085K, 65% free 2897K/8071K, external 1635K/2147K, paused 17ms
I/dalvikvm-heap( 1672): Grow heap (frag case) to 8.878MB for 2178751-byte allocation
D/dalvikvm( 1672): GC_FOR_MALLOC freed 3K, 51% free 5021K/10247K, external 1635K/2147K, paused 15ms
D/dalvikvm( 1672): GC_CONCURRENT freed <1K, 51% free 5021K/10247K, external 1734K/2147K, paused 2ms+2ms
D/dalvikvm( 1672): GC_FOR_MALLOC freed 2138K, 72% free 2898K/10247K, external 1635K/2147K, paused 15ms
I/dalvikvm-heap( 1672): Grow heap (frag case) to 9.144MB for 2456026-byte allocation
D/dalvikvm( 1672): GC_FOR_MALLOC freed 3K, 49% free 5293K/10247K, external 1635K/2147K, paused 15ms
D/dalvikvm( 1672): GC_CONCURRENT freed 0K, 49% free 5293K/10247K, external 1635K/2147K, paused 2ms+2ms
D/dalvikvm( 1672): GC_FOR_MALLOC freed 2411K, 72% free 2898K/10247K, external 1635K/2147K, paused 15ms
I/dalvikvm-heap( 1672): Grow heap (frag case) to 8.813MB for 2109569-byte allocation
D/dalvikvm( 1672): GC_FOR_MALLOC freed 3K, 52% free 4954K/10247K, external 1635K/2147K, paused 15ms
D/dalvikvm( 1672): GC_CONCURRENT freed <1K, 52% free 4954K/10247K, external 1695K/2147K, paused 2ms+2ms
D/dalvikvm( 1672): GC_FOR_MALLOC freed 2071K, 72% free 2898K/10247K, external 1635K/2147K, paused 16ms
I/dalvikvm-heap( 1672): Grow heap (frag case) to 8.095MB for 1355975-byte allocation
D/dalvikvm( 1672): GC_FOR_MALLOC freed 3K, 59% free 4218K/10247K, external 1635K/2147K, paused 15ms
D/dalvikvm( 1379): GC_EXPLICIT freed 662K, 49% free 4268K/8327K, external 1625K/2137K, paused 59ms
D/dalvikvm( 1672): GC_CONCURRENT freed 0K, 59% free 4219K/10247K, external 1635K/2147K, paused 2ms+2ms
D/dalvikvm( 1672): GC_FOR_MALLOC freed 1738K, 72% free 2902K/10247K, external 1635K/2147K, paused 15ms
I/dalvikvm-heap( 1672): Grow heap (frag case) to 8.287MB for 1552568-byte allocation
D/dalvikvm( 1672): GC_CONCURRENT freed 3K, 57% free 4415K/10247K, external 1635K/2147K, paused 2ms+2ms
W/ActivityManager( 1379): Activity idle timeout for HistoryRecord{4056b038 com.android.camera/.Camera}
I/ActivityManager( 1379): Starting: Intent { act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10200000 cmp=com.android.launcher
/com.android.launcher2.Launcher } from pid 1379
W/ActivityManager( 1379): Activity pause timeout for HistoryRecord{4056b038 com.android.camera/.Camera}
I/WindowManager( 1379): Setting rotation to 0, animFlags=1
I/ActivityManager( 1379): Config changed: { scale=1.0 imsi=0/0 loc=en_US touch=3 keys=1/1/2 nav=1/1 orien=1 layout=34 uiMode=17 seq=5}
V/RenderScript_jni( 1581): surfaceCreated
V/RenderScript_jni( 1581): surfaceChanged
E/msm7x30.gralloc( 1497): cannot flush handle 0x2ff940 (offs=a75000 len=c3000)
V/RenderScript_jni( 1581): surfaceChanged
W/InputManagerService( 1379): Window already focused, ignoring focus gain of: [email protected]
Firstly i am not sure what this issue is
Code:
E/msm7x30.gralloc( 1379): cannot flush handle 0x40ce08 (offs=2ee000 len=168000)
But its not to with the camera as it happens a lot without the camera being used.
Code:
W/mm-camera( 1272): native_access_parm: error (No such file or directory): fd 16, type 1, length 32, status 0
Appears to be the only error i can see but i think it is part of libcamera.so that is proprietary.
Any got any clues to where i need to look at how to fix this issue?
I have managed to debug the Camera app and found the function call that is hanging.
Code:
frameworks/base/core/jni/android_hardware_Camera.java:android_hardware_Camera_getParameters()
return env->NewStringUTF(camera->getParameters().string());
camera->getParameters() never returns, no idea why as yet, but CM team have it working so if any of you are out there and know what i am missing please let me know.
Has anyone tried this? We have the hardware behind it (twl6040).
I compiled the driver for it, and found the firmware blobs it wanted to load.
Insmod dmesg logs:
Code:
<3>[ 3012.835601] init: no such service 'fm_dl'
<3>[ 3014.386718] init: untracked pid 2225 exited
<3>[ 3015.045623] init: untracked pid 2213 exited
<6>[ 3015.710144] (stc): st_register(8)
<6>[ 3015.710174] (stc): chnl_id list empty :8
<6>[ 3015.710205] (stk) : st_kim_start
<6>[ 3015.710205] Enable wl12xx power
<6>[ 3015.917175] (stk) :ldisc_install = 1
<6>[ 3015.968597] (stc): st_tty_open
<6>[ 3015.968627] (stk) :line discipline installed
<6>[ 3015.969879] (stk) :TIInit_7.6.15.bts
<6>[ 3016.060791] (stc): add_channel_to_table: id 8
<6>[ 3016.726562] (stc): remove_channel_from_table: id 8
<6>[ 3016.726623] (stc): all chnl_ids unregistered
<6>[ 3016.726684] (stk) :ldisc_install = 0
<6>[ 3016.727081] (stc): st_tty_close
<6>[ 3016.729278] Disable wl12xx power
<6>[ 3023.407836] (stc): st_register(8)
<6>[ 3023.407897] (stc): chnl_id list empty :8
<6>[ 3023.407928] (stk) : st_kim_start
<6>[ 3023.407958] Enable wl12xx power
<6>[ 3023.615295] (stk) :ldisc_install = 1
<6>[ 3023.666046] (stc): st_tty_open
<6>[ 3023.666107] (stk) :line discipline installed
<6>[ 3023.666595] (stk) :TIInit_7.6.15.bts
<6>[ 3023.755706] (stc): add_channel_to_table: id 8
The radio app, itself, seems to want to work.
logcat logs
Code:
D/fm_apln ( 2190): freq_set_dlg_shw: Frequency changing to : 106500 KHz
D/fm_srvc ( 2190): dhrdw_freq_set fd: 6 freq: 106500
D/fm_hrdw ( 2190): hrdw_so_freq_set api_hndl: 6 freq: 106500
D/fm_hrdw ( 2190): gen_freq_set freq: 106500 limited: 106500
D/fm_hrdw ( 2190): rds_reset
D/fm_hrdw ( 2190): v4_freq_set: 106500
E/fm_hrdw ( 2190): iris_buf_display error: 22
E/fm_hrdw ( 2190): iris_buf_display error: 22
D/fm_srvc ( 2190): evt_pi_chngd
D/fm_srvc ( 2190): dhrdw_pi_get fd: 6
D/fm_hrdw ( 2190): hrdw_so_pi_get api_hndl: 6 pi: 0x0
D/fm_apln ( 2190): on_pi_chngd
D/fm_hrdw ( 2190): v4_freq_set VIDIOC_S_FREQUENCY success
D/fm_sttn ( 2190): pt_parse: 0
D/fm_apln ( 2190): run_on_pi_chngd run
D/fm_apln ( 2190): run_on_pi_chngd run pi: 0 cs: null
D/fm_apln ( 2190): run_on_pi_chngd run cs: 0000 m_just_pi: true m_just_pi_time: 1395133231826
E/fm_hrdw ( 2190): iris_buf_display error: 22
E/fm_hrdw ( 2190): iris_buf_display error: 22
D/fm_srvc ( 2190): evt_freq_chngd: freq: 106500
D/fm_apln ( 2190): on_freq_chngd freq: 106500
D/fm_apln ( 2190): ui_sttn_info_reset m_freq: 106500 sttn_name: 106.5
D/fm_sttn ( 2190): pt_parse: 0
D/fm_apln ( 2190): sttn_id_tv_set m_station_id: 0 sttn_id: PI: 0
D/fm_apln ( 2190): prefs_set int: last_frequency = 106500
E/fm_hrdw ( 2190): iris_buf_display error: 22
E/fm_hrdw ( 2190): iris_buf_display error: 22
D/fm_srvc ( 2190): dhrdw_pt_get fd: 6
D/fm_hrdw ( 2190): hrdw_so_pt_get api_hndl: 6 pt: -1
D/fm_srvc ( 2190): evt_pt_chngd: -1
D/fm_apln ( 2190): cb_on_pt_chngd
E/fm_hrdw ( 2190): iris_buf_display error: 22
E/fm_hrdw ( 2190): iris_buf_display error: 22
D/fm_srvc ( 2190): evt_ps_chngd:
D/fm_apln ( 2190): run_on_pt_chngd: -1
D/fm_sttn ( 2190): pt_parse: -1
D/fm_sttn ( 2190): pt_parse: -1
E/fm_hrdw ( 2190): iris_buf_display error: 22
E/fm_hrdw ( 2190): iris_buf_display error: 22
D/fm_srvc ( 2190): evt_rt_chngd:
D/fm_hrdw ( 2190): v4_rssi_get VIDIOC_G_TUNER success name: FM type: 1 cap: 0x91 lo: 1400000 hi: 1728000 sc: 3 am: 1 sig: 31097 afc: 0
No audio. I'm not sure if I just need to use a mixer to map it or if I have further driver problems.
Sometimes I get these errors?
Code:
<3>[ 3081.753814] fmdrv: Response SKB ptr not NULL
<3>[ 3081.754577] fmdrv: Response SKB is missing
<3>[ 3082.530303] fmdrv: Response SKB ptr not NULL
<3>[ 3082.531036] fmdrv: Response SKB is missing
Anyway, I'm thinking it's possible with some work.
Well I definitely know that it's going to be possible with a little bit of work.
I managed to get a split second of audio from radio station while I was messing with spirit radio settings and unplugged the headset.
Don't know what I did because I haven't managed to recreate, but it was enough to let me know that it was there and just needs to be mapped.
If anyone else would like to work with me on this, here's the files:
http://www.mediafire.com/download/6296mev66ydlb2y/fm_radio_files.zip
the *.bts files go in /system/etc/firmware
fm_drv.ko goes in /system/lib/modules
omap audio-tool (which I just compiled) goes in /system/xbin
You have to procure spirit FM unlocked (from market or elsewhere).
I'm not going to get involved but that's cool and unexpected. Good stuff.
Well at least I found a practical use for audio-tool. Apparently our sound chipset is normally configured for low-power operation. You can use audio-tool to change this to high performance.
Code:
[email protected]:/ # audio-tool mix
Number of controls: 71
ctl type num name value
0 ENUM 1 DL1 Equalizer Flat response
1 ENUM 1 Sidetone Equalizer Flat response
2 ENUM 1 AMIC Equalizer High-pass 0dB
3 ENUM 1 DMIC Equalizer High-pass 0dB
4 INT 1 DL1 Media Playback Volume 132
5 INT 1 DL1 Tones Playback Volume 0
6 INT 1 DL1 Voice Playback Volume 130
7 INT 1 DL1 Capture Playback Volume 0
8 INT 1 VXREC Media Volume 0
9 INT 1 VXREC Tones Volume 0
10 INT 1 VXREC Voice DL Volume 0
11 INT 1 VXREC Voice UL Volume 0
12 INT 1 AUDUL Media Volume 0
13 INT 1 AUDUL Tones Volume 0
14 INT 1 AUDUL Voice UL Volume 120
15 INT 1 AUDUL Voice DL Volume 0
16 INT 1 SDT UL Volume 101
17 INT 1 SDT DL Volume 120
18 INT 2 DMIC1 UL Volume 120 120
19 INT 2 DMIC2 UL Volume 120 120
20 INT 2 DMIC3 UL Volume 120 120
21 INT 2 AMIC UL Volume 120 120
22 INT 2 BT UL Volume 126 126
23 BOOL 1 DL1 Mono Mixer Off
24 BOOL 1 AUDUL Mono Mixer Off
25 BOOL 1 DL1 MM_EXT Switch Off
26 BOOL 1 DL1 BT_VX Switch Off
27 BOOL 1 DL1 PDM_DL2 Switch On
28 BOOL 1 DL1 PDM Switch Off
29 BOOL 1 Sidetone Mixer Capture Off
30 BOOL 1 Sidetone Mixer Playback On
31 BOOL 1 Capture Mixer Tones Off
32 BOOL 1 Capture Mixer Voice Playback Off
33 BOOL 1 Capture Mixer Voice Capture Off
34 BOOL 1 Capture Mixer Media Playback Off
35 BOOL 1 Voice Capture Mixer Tones Playback Off
36 BOOL 1 Voice Capture Mixer Media Playback Off
37 BOOL 1 Voice Capture Mixer Capture Off
38 BOOL 1 DL1 Mixer Tones Off
39 BOOL 1 DL1 Mixer Voice Off
40 BOOL 1 DL1 Mixer Capture Off
41 BOOL 1 DL1 Mixer Multimedia On
42 ENUM 1 MUX_VX1 None
43 ENUM 1 MUX_VX0 None
44 ENUM 1 MUX_UL11 None
45 ENUM 1 MUX_UL10 None
46 ENUM 1 MUX_UL07 None
47 ENUM 1 MUX_UL06 None
48 ENUM 1 MUX_UL05 None
49 ENUM 1 MUX_UL04 None
50 ENUM 1 MUX_UL03 None
51 ENUM 1 MUX_UL02 None
52 ENUM 1 MUX_UL01 None
53 ENUM 1 MUX_UL00 None
54 INT 2 Capture Preamplifier Volume 1 1
55 INT 2 Capture Volume 4 4
56 INT 2 Aux FM Volume 3 3
57 INT 2 Headset Playback Volume 7 7
58 INT 2 Handsfree Playback Volume 26 26
59 INT 1 Earphone Playback Volume 15
60 ENUM 1 Headset Power Mode Low-Power
61 BOOL 1 Earphone Playback Switch Off
62 BOOL 1 Spk Right Playback Switch On
63 BOOL 1 Spk Left Playback Switch On
64 ENUM 1 Headset Right Playback Off
65 ENUM 1 Headset Left Playback Off
66 ENUM 1 Handsfree Right Playback HF DAC
67 ENUM 1 Handsfree Left Playback HF DAC
68 ENUM 1 Analog Right Capture Route Off
69 ENUM 1 Analog Left Capture Route Off
70 ENUM 1 TWL6040 Power Mode Low-Power
[email protected]:/ # audio-tool mix 70 1
[email protected]:/ # audio-tool mix
Number of controls: 71
ctl type num name value
0 ENUM 1 DL1 Equalizer Flat response
1 ENUM 1 Sidetone Equalizer Flat response
2 ENUM 1 AMIC Equalizer High-pass 0dB
3 ENUM 1 DMIC Equalizer High-pass 0dB
4 INT 1 DL1 Media Playback Volume 132
5 INT 1 DL1 Tones Playback Volume 0
6 INT 1 DL1 Voice Playback Volume 130
7 INT 1 DL1 Capture Playback Volume 0
8 INT 1 VXREC Media Volume 0
9 INT 1 VXREC Tones Volume 0
10 INT 1 VXREC Voice DL Volume 0
11 INT 1 VXREC Voice UL Volume 0
12 INT 1 AUDUL Media Volume 0
13 INT 1 AUDUL Tones Volume 0
14 INT 1 AUDUL Voice UL Volume 120
15 INT 1 AUDUL Voice DL Volume 0
16 INT 1 SDT UL Volume 101
17 INT 1 SDT DL Volume 120
18 INT 2 DMIC1 UL Volume 120 120
19 INT 2 DMIC2 UL Volume 120 120
20 INT 2 DMIC3 UL Volume 120 120
21 INT 2 AMIC UL Volume 120 120
22 INT 2 BT UL Volume 126 126
23 BOOL 1 DL1 Mono Mixer Off
24 BOOL 1 AUDUL Mono Mixer Off
25 BOOL 1 DL1 MM_EXT Switch Off
26 BOOL 1 DL1 BT_VX Switch Off
27 BOOL 1 DL1 PDM_DL2 Switch On
28 BOOL 1 DL1 PDM Switch Off
29 BOOL 1 Sidetone Mixer Capture Off
30 BOOL 1 Sidetone Mixer Playback On
31 BOOL 1 Capture Mixer Tones Off
32 BOOL 1 Capture Mixer Voice Playback Off
33 BOOL 1 Capture Mixer Voice Capture Off
34 BOOL 1 Capture Mixer Media Playback Off
35 BOOL 1 Voice Capture Mixer Tones Playback Off
36 BOOL 1 Voice Capture Mixer Media Playback Off
37 BOOL 1 Voice Capture Mixer Capture Off
38 BOOL 1 DL1 Mixer Tones Off
39 BOOL 1 DL1 Mixer Voice Off
40 BOOL 1 DL1 Mixer Capture Off
41 BOOL 1 DL1 Mixer Multimedia On
42 ENUM 1 MUX_VX1 None
43 ENUM 1 MUX_VX0 None
44 ENUM 1 MUX_UL11 None
45 ENUM 1 MUX_UL10 None
46 ENUM 1 MUX_UL07 None
47 ENUM 1 MUX_UL06 None
48 ENUM 1 MUX_UL05 None
49 ENUM 1 MUX_UL04 None
50 ENUM 1 MUX_UL03 None
51 ENUM 1 MUX_UL02 None
52 ENUM 1 MUX_UL01 None
53 ENUM 1 MUX_UL00 None
54 INT 2 Capture Preamplifier Volume 1 1
55 INT 2 Capture Volume 4 4
56 INT 2 Aux FM Volume 3 3
57 INT 2 Headset Playback Volume 7 7
58 INT 2 Handsfree Playback Volume 26 26
59 INT 1 Earphone Playback Volume 15
60 ENUM 1 Headset Power Mode Low-Power
61 BOOL 1 Earphone Playback Switch Off
62 BOOL 1 Spk Right Playback Switch On
63 BOOL 1 Spk Left Playback Switch On
64 ENUM 1 Headset Right Playback Off
65 ENUM 1 Headset Left Playback Off
66 ENUM 1 Handsfree Right Playback HF DAC
67 ENUM 1 Handsfree Left Playback HF DAC
68 ENUM 1 Analog Right Capture Route Off
69 ENUM 1 Analog Left Capture Route Off
70 ENUM 1 TWL6040 Power Mode High-Performance
[email protected]:/ #
I'm not real sure where to start as far as mapping.
Recently, whenever I turn on the WiFi on my phone, it immediately flips back off. This issue is intermittent and happens seemingly randomly, persisting for a day or two, after which WiFi will work for a few days and then break again.
I'm running 4.4.4, VZW, completely stock, and I've already tried a factory reset and "safe mode" (without any third-party apps). Rebooting will sometimes stop the problem, but then it will come back.
Here's the logcat from flipping on WiFi:
Code:
D/WifiService( 919): setWifiEnabled: true pid=1533, uid=1000
E/WifiService( 919): Invoking mWifiStateMachine.setWifiEnabled
D/Ulp_jni ( 919): JNI:In update_settings:currentContextType 4, currentGpsSetting 0, currentAgpsSetting 0,currentNetworkProvSetting 0,currentWifiSetting 1,currentBatteryCharging 0,currentEnhLocationServicesSetting 0 currentPipUserSetting 0
W/Settings( 1247): Setting wifi_on has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
W/ContextImpl( 1247): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1252 android.content.ContextWrapper.sendBroadcast:365 com.motorola.motocare.internal.settings.SettingsTrigger$1.onChange:395 android.database.ContentObserver.onChange:129 android.database.ContentObserver$NotificationRunnable.run:180
D/WifiStateMachine( 919): setting operational mode to 1
D/WifiStateMachine( 919): handleMessage: E msg.what=131083
D/WifiStateMachine( 919): processMsg: InitialState
W/Settings( 1247): Setting wifi_on has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
W/ContextImpl( 1247): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1252 android.content.ContextWrapper.sendBroadcast:365 com.motorola.motocare.internal.settings.SettingsTrigger$1.onChange:395 android.database.ContentObserver.onChange:129 android.database.ContentObserver$NotificationRunnable.run:180
D/Checkin ( 2607): publish the event [tag = MOT_DEVICE_STATS_L1 event name = SettingLogs]
D/Checkin ( 2607): publish the event [tag = MOT_DEVICE_STATS_L1 event name = SettingLogs]
E/WifiStateMachine( 919): Failed to load driver
D/WifiStateMachine( 919): setWifiState: unknown state
I/SBar.NetworkController( 1069): onReceive: WifiManager.WIFI_STATE_CHANGED_ACTION Received
D/BluetoothAdapter( 1533): 1106862184: getState() : mService = null. Returning STATE_OFF
D/WifiStateMachine( 919): handleMessage: X
D/WifiStateMachine( 919): handleMessage: E msg.what=131144
D/WifiStateMachine( 919): processMsg: InitialState
D/WifiStateMachine( 919): processMsg: DefaultState
D/WifiStateMachine( 919): handleMessage: X
W/Settings( 1247): Setting wifi_on has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
W/ContextImpl( 1247): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1252 android.content.ContextWrapper.sendBroadcast:365 com.motorola.motocare.internal.settings.SettingsTrigger$1.onChange:395 android.database.ContentObserver.onChange:129 android.database.ContentObserver$NotificationRunnable.run:180
W/Settings( 1247): Setting wifi_on has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
W/ContextImpl( 1247): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1252 android.content.ContextWrapper.sendBroadcast:365 com.motorola.motocare.internal.settings.SettingsTrigger$1.onChange:395 android.database.ContentObserver.onChange:129 android.database.ContentObserver$NotificationRunnable.run:180
D/WifiStateMachine( 919): handleMessage: E msg.what=131085
D/WifiStateMachine( 919): processMsg: InitialState
D/WifiStateMachine( 919): processMsg: DefaultState
D/WifiStateMachine( 919): handleMessage: X
D/WifiStateMachine( 919): handleMessage: E msg.what=131149
D/WifiStateMachine( 919): processMsg: InitialState
D/WifiStateMachine( 919): processMsg: DefaultState
D/WifiStateMachine( 919): setSuspendOptimizations: 2 true
D/WifiStateMachine( 919): mSuspendOptNeedsDisabled 4
D/WifiStateMachine( 919): handleMessage: X
D/WifiStateMachine( 919): handleMessage: E msg.what=131084
D/WifiStateMachine( 919): processMsg: InitialState
D/WifiStateMachine( 919): processMsg: DefaultState
D/WifiStateMachine( 919): handleMessage: X
D/Ulp_jni ( 919): JNI:In update_settings:currentContextType 4, currentGpsSetting 0, currentAgpsSetting 0,currentNetworkProvSetting 0,currentWifiSetting 0,currentBatteryCharging 0,currentEnhLocationServicesSetting 0 currentPipUserSetting 0
D/Checkin ( 2607): publish the event [tag = MOT_DEVICE_STATS_L1 event name = SettingLogs]
D/Checkin ( 2607): publish the event [tag = MOT_DEVICE_STATS_L1 event name = SettingLogs]
I/SBar.NetworkController( 1069): onDataActivity: direction=0
I/GCM ( 1412): GCM message com.google.android.talk 0:1406441801764291%5a5392733fb5fb85
D/WifiService( 919): acquireWifiLockLocked: WifiLock{NlpWifiLock type=2 [email protected]}
D/ConnectivityService( 919): handleInetConditionHoldEnd: net=0, condition=100, published condition=100
I/ModemStatsDSDetect( 1287): onReceive() -Intent { act=android.net.conn.INET_CONDITION_ACTION flg=0x4000010 (has extras) }
I/SBar.NetworkController( 1069): onReceive: ConnectivityManager.INET_CONDITION_ACTION Received
I/SBar.NetworkController( 1069): updateConnectivity: NetworkInfo: NetworkInfo: type: mobile[LTE], state: CONNECTED/CONNECTED, reason: linkPropertiesChanged, extra: VZWINTERNET, roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false, inetCondition= 1
I/ModemStatsDSDetect( 1287): INET_CONDITION=100 ,activeNet=NetworkInfo: type: mobile[LTE], state: CONNECTED/CONNECTED, reason: linkPropertiesChanged, extra: VZWINTERNET, roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false
I/ModemStatsDSDetect( 1287): onReceive() - done, currentInetCondition=100
Anyone else experiencing this issue and/or have a fix for it?
terabyte128 said:
Recently, whenever I turn on the WiFi on my phone, it immediately flips back off. This issue is intermittent and happens seemingly randomly, persisting for a day or two, after which WiFi will work for a few days and then break again.
I'm running 4.4.4, VZW, completely stock, and I've already tried a factory reset and "safe mode" (without any third-party apps). Rebooting will sometimes stop the problem, but then it will come back.
Here's the logcat from flipping on WiFi:
Code:
D/WifiService( 919): setWifiEnabled: true pid=1533, uid=1000
E/WifiService( 919): Invoking mWifiStateMachine.setWifiEnabled
D/Ulp_jni ( 919): JNI:In update_settings:currentContextType 4, currentGpsSetting 0, currentAgpsSetting 0,currentNetworkProvSetting 0,currentWifiSetting 1,currentBatteryCharging 0,currentEnhLocationServicesSetting 0 currentPipUserSetting 0
W/Settings( 1247): Setting wifi_on has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
W/ContextImpl( 1247): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1252 android.content.ContextWrapper.sendBroadcast:365 com.motorola.motocare.internal.settings.SettingsTrigger$1.onChange:395 android.database.ContentObserver.onChange:129 android.database.ContentObserver$NotificationRunnable.run:180
D/WifiStateMachine( 919): setting operational mode to 1
D/WifiStateMachine( 919): handleMessage: E msg.what=131083
D/WifiStateMachine( 919): processMsg: InitialState
W/Settings( 1247): Setting wifi_on has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
W/ContextImpl( 1247): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1252 android.content.ContextWrapper.sendBroadcast:365 com.motorola.motocare.internal.settings.SettingsTrigger$1.onChange:395 android.database.ContentObserver.onChange:129 android.database.ContentObserver$NotificationRunnable.run:180
D/Checkin ( 2607): publish the event [tag = MOT_DEVICE_STATS_L1 event name = SettingLogs]
D/Checkin ( 2607): publish the event [tag = MOT_DEVICE_STATS_L1 event name = SettingLogs]
E/WifiStateMachine( 919): Failed to load driver
D/WifiStateMachine( 919): setWifiState: unknown state
I/SBar.NetworkController( 1069): onReceive: WifiManager.WIFI_STATE_CHANGED_ACTION Received
D/BluetoothAdapter( 1533): 1106862184: getState() : mService = null. Returning STATE_OFF
D/WifiStateMachine( 919): handleMessage: X
D/WifiStateMachine( 919): handleMessage: E msg.what=131144
D/WifiStateMachine( 919): processMsg: InitialState
D/WifiStateMachine( 919): processMsg: DefaultState
D/WifiStateMachine( 919): handleMessage: X
W/Settings( 1247): Setting wifi_on has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
W/ContextImpl( 1247): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1252 android.content.ContextWrapper.sendBroadcast:365 com.motorola.motocare.internal.settings.SettingsTrigger$1.onChange:395 android.database.ContentObserver.onChange:129 android.database.ContentObserver$NotificationRunnable.run:180
W/Settings( 1247): Setting wifi_on has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
W/ContextImpl( 1247): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1252 android.content.ContextWrapper.sendBroadcast:365 com.motorola.motocare.internal.settings.SettingsTrigger$1.onChange:395 android.database.ContentObserver.onChange:129 android.database.ContentObserver$NotificationRunnable.run:180
D/WifiStateMachine( 919): handleMessage: E msg.what=131085
D/WifiStateMachine( 919): processMsg: InitialState
D/WifiStateMachine( 919): processMsg: DefaultState
D/WifiStateMachine( 919): handleMessage: X
D/WifiStateMachine( 919): handleMessage: E msg.what=131149
D/WifiStateMachine( 919): processMsg: InitialState
D/WifiStateMachine( 919): processMsg: DefaultState
D/WifiStateMachine( 919): setSuspendOptimizations: 2 true
D/WifiStateMachine( 919): mSuspendOptNeedsDisabled 4
D/WifiStateMachine( 919): handleMessage: X
D/WifiStateMachine( 919): handleMessage: E msg.what=131084
D/WifiStateMachine( 919): processMsg: InitialState
D/WifiStateMachine( 919): processMsg: DefaultState
D/WifiStateMachine( 919): handleMessage: X
D/Ulp_jni ( 919): JNI:In update_settings:currentContextType 4, currentGpsSetting 0, currentAgpsSetting 0,currentNetworkProvSetting 0,currentWifiSetting 0,currentBatteryCharging 0,currentEnhLocationServicesSetting 0 currentPipUserSetting 0
D/Checkin ( 2607): publish the event [tag = MOT_DEVICE_STATS_L1 event name = SettingLogs]
D/Checkin ( 2607): publish the event [tag = MOT_DEVICE_STATS_L1 event name = SettingLogs]
I/SBar.NetworkController( 1069): onDataActivity: direction=0
I/GCM ( 1412): GCM message com.google.android.talk 0:1406441801764291%5a5392733fb5fb85
D/WifiService( 919): acquireWifiLockLocked: WifiLock{NlpWifiLock type=2 [email protected]}
D/ConnectivityService( 919): handleInetConditionHoldEnd: net=0, condition=100, published condition=100
I/ModemStatsDSDetect( 1287): onReceive() -Intent { act=android.net.conn.INET_CONDITION_ACTION flg=0x4000010 (has extras) }
I/SBar.NetworkController( 1069): onReceive: ConnectivityManager.INET_CONDITION_ACTION Received
I/SBar.NetworkController( 1069): updateConnectivity: NetworkInfo: NetworkInfo: type: mobile[LTE], state: CONNECTED/CONNECTED, reason: linkPropertiesChanged, extra: VZWINTERNET, roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false, inetCondition= 1
I/ModemStatsDSDetect( 1287): INET_CONDITION=100 ,activeNet=NetworkInfo: type: mobile[LTE], state: CONNECTED/CONNECTED, reason: linkPropertiesChanged, extra: VZWINTERNET, roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false
I/ModemStatsDSDetect( 1287): onReceive() - done, currentInetCondition=100
Anyone else experiencing this issue and/or have a fix for it?
Click to expand...
Click to collapse
Notice it says "Failed to load driver".
I would suspect a couple possibilities...
1) your 4.4.4 flash could have been corrupt. Check the MD5 of the 4.4.4 file you downloaded and make sure it matches what it is supposed to. Did you use the OTA update, or the recently posted FXZ?
If this is the case (MD5 doesn't match), download the entire FXZ, and re-flash -- see if the problem is resolved.
Even if the MD5s DO match, it's possible you have a corrupted/damaged filesystem, and FXZing will probably fix it. (This is the MOST likely possibility)
2) are you unlocked? If so, check your /persist folder and make sure this file is inside: WCNSS_qcom_wlan_factory_nv.bin
3) this probably isn't relevant at all, since your log says "failed to load driver", but do you have an atheros-based chipset in your wifi router? Does the issue happen on just one wifi access point, or every AP you try to connect to? I have seen conflicts with certain Atheros-based chipsets not playing nice with other clients.
samwathegreat said:
Notice it says "Failed to load driver".
I would suspect a couple possibilities...
1) your 4.4.4 flash could have been corrupt. Check the MD5 of the 4.4.4 file you downloaded and make sure it matches what it is supposed to. Did you use the OTA update, or the recently posted FXZ?
If this is the case (MD5 doesn't match), download the entire FXZ, and re-flash -- see if the problem is resolved.
Even if the MD5s DO match, it's possible you have a corrupted/damaged filesystem, and FXZing will probably fix it. (This is the MOST likely possibility)
2) are you unlocked? If so, check your /persist folder and make sure this file is inside: WCNSS_qcom_wlan_factory_nv.bin
3) this probably isn't relevant at all, since your log says "failed to load driver", but do you have an atheros-based chipset in your wifi router? Does the issue happen on just one wifi access point, or every AP you try to connect to? I have seen conflicts with certain Atheros-based chipsets not playing nice with other clients.
Click to expand...
Click to collapse
Thanks for your reply! I've tried reflashing from the 4.4.4 system.img file that I found on the forums, which works sometimes but only for a short while before the problem begins to occur again. Is it possible that something within the ROM is causing the driver to become corrupted?
At any rate, I spoke to Verizon and they'll send me a replacement phone. Until then.... I'm going to try flashing system again just for kicks, although it didn't work last time.
Might it help to reflash the radios? Seems kinda risky...
terabyte128 said:
Thanks for your reply! I've tried reflashing from the 4.4.4 system.img file that I found on the forums, which works sometimes but only for a short while before the problem begins to occur again. Is it possible that something within the ROM is causing the driver to become corrupted?
At any rate, I spoke to Verizon and they'll send me a replacement phone. Until then.... I'm going to try flashing system again just for kicks, although it didn't work last time.
Might it help to reflash the radios? Seems kinda risky...
Click to expand...
Click to collapse
Hi,
Did you ever figure this out? Having the same problem. Or was getting a new phone the only thing that helped?
EDIT: I got mobile data working. WIFI still isn't working for me though.
I have been using CM 11 on my device for a while without any major issues. However, just recently I installed a SaberMod variant of CM 12.1 (from this thread) and can't get internet connectivity since. Due to the rules of this site I'm not allowed to post in the relevant thread though, that's why I'm posting here.
My questions: How can I diagnose the source of this issue? Where can I can get help? Do you have any ideas or quick fixes?
I have added a log snippet below.
Code:
W/thermanager( 4878): failed to attach resource "cpu1" [sysfs], ignoring
E/thermanager( 4878): failed to parse threshold mitigations
E/thermanager( 4878): failed to parse thresholds
E/thermanager( 4878): failed to parse configuration sections
D/WifiService( 2587): setWifiEnabled: true pid=3617, uid=1000
E/WifiService( 2587): Invoking mWifiStateMachine.setWifiEnabled
E/WifiStateMachine( 2587): setting operational mode to 1
E/WifiStateMachine( 2587): InitialState !CMD_START_SUPPLICANT 0 0
D/ALSADevice( 2195): route: devices 0x2 in mode 0
D/ALSADevice( 2195): No valid input device: 0
D/alsa_ucm( 2195): snd_use_case_set(): uc_mgr 0xb8270388 identifier _enadev value Speaker
D/ALSADevice( 2195): switchDevice: mCurTxUCMDevivce None mCurRxDevDevice Speaker
D/alsa_ucm( 2195): snd_use_case_set(): uc_mgr 0xb8270388 identifier _verb value HiFi Lowlatency
D/alsa_ucm( 2195): Set mixer controls for Speaker enable 1
D/alsa_ucm( 2195): acdb_id 263 cap 1 enable 1
D/alsa_ucm( 2195): Setting mixer control: SLIM_0_RX Channels, value: Two
D/alsa_ucm( 2195): Setting mixer control: RX3 MIX1 INP1, value: RX1
D/alsa_ucm( 2195): Setting mixer control: RX3 MIX1 INP2, value: RX2
D/alsa_ucm( 2195): Setting mixer control: LINEOUT1 Volume, value: 100
D/alsa_ucm( 2195): Setting mixer control: RX3 Digital Volume, value: 71
D/alsa_ucm( 2195): Set mixer controls for HiFi Lowlatency enable 1
D/alsa_ucm( 2195): Setting mixer control: SLIMBUS_0_RX Audio Mixer MultiMedia5, value: 1
D/ALSADevice( 2195): close: handle 0xb82b4e90 h 0x0
D/ALSADevice( 2195): open: handle 0xb82b4e90, format 0x2
D/ALSADevice( 2195): Device value returned is hw:0,14
D/ALSADevice( 2195): handle->format: 0x2
D/ALSADevice( 2195): setHardwareParams: reqBuffSize 1024 channels 2 sampleRate 48000
D/ALSADevice( 2195): setHardwareParams: buffer_size 2048, period_size 1024, period_cnt 2
E/WifiStateMachine( 2587): Failed to load driver
E/WifiStateMachine( 2587): InitialState !CMD_SET_OPERATIONAL_MODE 1 0
E/WifiStateMachine( 2587): DefaultState !CMD_SET_OPERATIONAL_MODE 1 0
E/WifiStateMachine( 2587): InitialState !CMD_START_DRIVER 0 0
E/WifiStateMachine( 2587): DefaultState !CMD_START_DRIVER 0 0
E/WifiStateMachine( 2587): InitialState !CMD_SET_HIGH_PERF_MODE 0 0
E/WifiStateMachine( 2587): DefaultState !CMD_SET_HIGH_PERF_MODE 0 0
E/WifiStateMachine( 2587): setSuspendOptimizations: 2 true
E/WifiStateMachine( 2587): mSuspendOptNeedsDisabled 4
W/thermanager( 4889): failed to attach resource "cpu1" [sysfs], ignoring
E/thermanager( 4889): failed to parse threshold mitigations
E/thermanager( 4889): failed to parse thresholds
E/thermanager( 4889): failed to parse configuration sections
D/ALSADevice( 2195): standby: handle 0xb82b4e90 h 0x0
D/alsa_ucm( 2195): snd_use_case_set(): uc_mgr 0xb8270388 identifier _verb value Inactive
D/alsa_ucm( 2195): Set mixer controls for HiFi Lowlatency enable 0
D/alsa_ucm( 2195): Setting mixer control: SLIMBUS_0_RX Audio Mixer MultiMedia5, value: 0
D/alsa_ucm( 2195): snd_use_case_set(): uc_mgr 0xb8270388 identifier _disdev value Speaker
D/alsa_ucm( 2195): Set mixer controls for Speaker enable 0
D/alsa_ucm( 2195): Setting mixer control: RX3 MIX1 INP1, value: ZERO
D/alsa_ucm( 2195): Setting mixer control: RX3 MIX1 INP2, value: ZERO
D/alsa_ucm( 2195): Setting mixer control: LINEOUT1 Volume, value: 0
D/AudioUsbALSA( 2195): exitPlaybackThread, mproxypfdPlayback: -1
D/AudioUsbALSA( 2195): closeDevice handle 0x0
D/AudioUsbALSA( 2195): closeDevice handle 0x0
W/thermanager( 4891): failed to attach resource "cpu1" [sysfs], ignoring
E/thermanager( 4891): failed to parse threshold mitigations
E/thermanager( 4891): failed to parse thresholds
E/thermanager( 4891): failed to parse configuration sections
I/MediaFocusControl( 2587): AudioFocus abandonAudioFocus() from [email][email protected][/email][email protected]
I/HeadsetService( 3411): Audio session removed: 18
I/MediaFocusControl( 2587): AudioFocus abandonAudioFocus() from [email][email protected][/email][email protected]
I/ActivityManager( 2587): Killing 3895:com.android.dialer/u0a8 (adj 15): empty #17
W/thermanager( 4902): failed to attach resource "cpu1" [sysfs], ignoring
E/thermanager( 4902): failed to parse threshold mitigations
E/thermanager( 4902): failed to parse thresholds
E/thermanager( 4902): failed to parse configuration sections
V/lights.huashan( 2587): LEDs : 255 255 0 - delayOn : 0, delayOff : 0 - Flash : 0 - Update : 0/0 - Brightness : 255 - LEDs Mode : 1 - Mode : 2 (Not. 1 / Bat. 2)
W/thermanager( 4905): failed to attach resource "cpu1" [sysfs], ignoring
E/thermanager( 4905): failed to parse threshold mitigations
E/thermanager( 4905): failed to parse thresholds
E/thermanager( 4905): failed to parse configuration sections
I'm a Youtube Premium member and using YouTube Music for a while now in my car with Android Auto. I used to do voice searches all the time (Okay Google play Song x) and this worked great!
Suddenly (about a month ago) a voice search for a song in Android Auto doesn't work anymore. I get a 'Cannot play song' from YouTube Music.
{
"lightbox_close": "Close",
"lightbox_next": "Next",
"lightbox_previous": "Previous",
"lightbox_error": "The requested content cannot be loaded. Please try again later.",
"lightbox_start_slideshow": "Start slideshow",
"lightbox_stop_slideshow": "Stop slideshow",
"lightbox_full_screen": "Full screen",
"lightbox_thumbnails": "Thumbnails",
"lightbox_download": "Download",
"lightbox_share": "Share",
"lightbox_zoom": "Zoom",
"lightbox_new_window": "New window",
"lightbox_toggle_sidebar": "Toggle sidebar"
}
I've uninstalled and reinstalled Android Auto (tried several versions, but now running the latest one) and YouTube Music but get the same result. I can play music from YT music only the voice searches don't work any longer (other voice searches from Android Auto do work). When I give the same voice command to my Google Home Mini the song plays without a problem.
Any suggestions how to get this to work again?
Anyone?
Update google, google play, google play service. If it still not working delete cache and data for all google stuff and reboot.
Good luck!
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 (?)
YouTube Music on Android Auto shows "Cannot play song" and an exclamation mark when asking to play a song via voice command. Default music app in Assistant settings is correctly set.
This only happens within Android Auto: when I ask to play a song to the phone, outside my car, using voice command, Assistant correctly searches for the song and play it correctly using YouTube Music.
This also only happens with YouTube Music: if I set Google Play Music as default music app in Assistant, everything works fine even in Android Auto.
Error message: "Cannot play song"
Are you using Android Auto on your phone screen or car’s display? It happens both in Android Auto on phone screen AND car's display
Does the issue occur outside of Android Auto app? No
Car / head unit make & model: Ford Sync 3 v3.0 build 19025
Phone make & model: Google Pixel 1
Android OS version: Android 10 (security patch: October 6, 2019)
Android Auto app version: 5.5.602944-release
Google Play services version: 20.30.19 (120400-326531024)
Media app name & version: YouTube Music v3.79.50
Click to expand...
Click to collapse
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")
Code:
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=<[email protected] 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=<[email protected] 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>")
Code:
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: [email protected]}, 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([email protected] 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.