I've been having this issue since at least CM10 and currently on CM11 M5 snapshot.
After using the GPS a couple times (usually when camera polls it for picture location data), the GPS will stop getting a fix on location.
Usually it goes like this: Reboot the phone, take a couple pictures (with location enabled), put phone on pocket and couple hours later try to use it say for google maps. It won't be able to get location with GPS, only with radio antenna or wifi/wathever.
Both GPS status and GPS status toolbox won't be able to hook up to satelites at all. Problem is solved by rebooting the phone.
Logcat errors while running GPS status toolbox while GPS has been broken for a while:
04-26 10:31:54.824 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:31:54.824 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::injectPosition(double, double, float):539]: error! status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, inject_pos_ind.status =
04-26 10:32:18.010 E/Sensors (478): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:32:18.060 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:32:37.039 E/AKMD2 (478): AKMD_Init
04-26 10:32:37.259 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:32:37.269 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:32:37.269 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::startFix():313]: set opertion mode failed status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status =
04-26 10:32:37.299 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:32:37.299 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::setTime(GpsUtcTime, int64_t, int):483] status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status = UNKNOWN
04-26 10:32:37.319 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:32:37.329 E/AKMD2 (478): AKMD8975 getFormation: keyboard switch open failed
04-26 10:32:37.550 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:32:37.550 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::setTime(GpsUtcTime, int64_t, int):483] status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status = UNKNOWN
04-26 10:32:37.800 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:32:37.800 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::setTime(GpsUtcTime, int64_t, int):483] status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status = UNKNOWN
04-26 10:32:38.050 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:32:38.050 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::setTime(GpsUtcTime, int64_t, int):483] status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status = UNKNOWN
04-26 10:32:38.301 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:32:38.301 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::setTime(GpsUtcTime, int64_t, int):483] status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status = UNKNOWN
04-26 10:32:38.581 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:32:38.581 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::setXtraData(char*, int):1034]: failed status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, inject_pos_ind.status = UNKNOWN, part num = 1, ind.partNum = 29808
04-26 10:33:03.227 E/Sensors (478): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:33:03.768 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:33:28.504 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:33:28.564 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:35:11.894 E/AKMD2 (478): AKMD_Init
04-26 10:35:12.095 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:35:12.095 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::startFix():313]: set opertion mode failed status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status =
04-26 10:35:12.125 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:35:12.175 E/AKMD2 (478): AKMD8975 getFormation: keyboard switch open failed
04-26 10:35:12.195 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:35:16.319 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:35:16.379 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:35:49.054 E/AKMD2 (478): AKMD_Init
04-26 10:35:49.064 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:35:49.124 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:35:49.264 E/MotoSensors(836): CT406 set ALS poll interval: 200
04-26 10:35:49.264 E/qcom_sensors_hal(836): _hal_sensors_set_delay: max_freq = 0. unsupported sensor? handle:22
04-26 10:35:49.274 E/MotoSensors(836): CT406 set ALS poll interval: 200
04-26 10:35:49.274 E/qcom_sensors_hal(836): _hal_sensors_set_delay: max_freq = 0. unsupported sensor? handle:22
04-26 10:35:49.284 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:35:49.284 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::startFix():313]: set opertion mode failed status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status =
04-26 10:35:49.354 E/AKMD2 (478): AKMD8975 getFormation: keyboard switch open failed
04-26 10:35:52.938 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:36:21.739 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:36:26.944 E/AKMD2 (478): AKMD_Init
04-26 10:36:26.955 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:36:27.015 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:36:27.125 E/AKMD2 (478): AKMD8975 getFormation: keyboard switch open failed
04-26 10:36:27.125 E/MotoSensors(836): CT406 set ALS poll interval: 200
04-26 10:36:27.125 E/qcom_sensors_hal(836): _hal_sensors_set_delay: max_freq = 0. unsupported sensor? handle:22
04-26 10:36:27.135 E/MotoSensors(836): CT406 set ALS poll interval: 200
04-26 10:36:27.135 E/qcom_sensors_hal(836): _hal_sensors_set_delay: max_freq = 0. unsupported sensor? handle:22
04-26 10:36:27.195 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:36:27.195 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::startFix():313]: set opertion mode failed status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, ind..status =
04-26 10:36:33.371 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:36:33.431 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:36:39.828 E/LocSvc_api_v02(836): E/locClientSendReq:1938]: invalid handle
04-26 10:36:39.828 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::injectPosition(double, double, float):539]: error! status = eLOC_CLIENT_FAILURE_INVALID_HANDLE, inject_pos_ind.status =
Logcat right after reboot, same procedure, GPS works fine:
--------- beginning of /dev/log/main
04-26 10:38:57.399 E/QMI_FW (450): QMUXD: Service_id=228 not found over conn_id=0
04-26 10:38:57.409 E/QMI_FW (450): QMUXD: Service_id=228 not found over conn_id=9
04-26 10:39:01.724 E/SELinux (836): SELinux: Loaded file_contexts from /file_contexts
04-26 10:39:06.329 E/ConsumerIrService(836): Can't open consumer IR HW Module, error: -2
04-26 10:39:06.409 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:39:06.419 E/filemap (836): mmap(0,4096) failed: No such device
04-26 10:39:06.419 E/Tokenizer(836): Error reading file '/sys/board_properties/virtualkeys.cyttsp3-i2c', Invalid argument.
04-26 10:39:06.419 E/VirtualKeyMap(836): Error -22 opening virtual key map file /sys/board_properties/virtualkeys.cyttsp3-i2c.
04-26 10:39:06.449 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:39:06.479 E/EventHub(836): could not get driver version for /dev/input/mice, Not a typewriter
04-26 10:39:08.721 E/WifiStateMachine(836): Failed to reload STA firmware java.lang.IllegalArgumentException: command '2 softap fwreload wlan0 STA' failed with '501 2 SoftAP command has failed'
04-26 10:39:09.282 E/lights (836): write_int failed to open /sys/class/leds/keyboard-backlight/brightness
04-26 10:39:09.812 E/OMXMaster(330): A component of name 'OMX.qcom.audio.decoder.aac' already exists, ignoring this one.
04-26 10:39:09.982 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:10.012 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:10.163 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:10.183 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:11.654 E/LocSvc_afw(836): E/const ulpInterface* loc_eng_get_ulp_inf(), dlopen for libulp.so failed, error = dlopen failed: library "libulp2.so" not found
04-26 10:39:11.654 E/LocSvc_afw(836): E/get_extension: Invalid interface passed in
04-26 10:39:11.674 E/LocSvc_afw(836): E/const GpsGeofencingInterface* get_geofence_interface(), dlsym for ulpInterface failed, error = undefined symbol: gps_geofence_get_interface
04-26 10:39:11.754 E/FlpHardwareProvider(836): Error hw_get_module 'flp': -2
04-26 10:39:11.754 E/FlpHardwareProvider(836): Error hw_get_module 'flp': -2
04-26 10:39:12.195 E/QMI_FW (836): QMUXD: WARNING qmi_qmux_if_alloc_service_client failed! service_id = 16, conn_id = 0, rc=-2 error = 31
04-26 10:39:12.405 E/QMI_FW (836): QMUXD: WARNING qmi_qmux_if_alloc_service_client failed! service_id = 16, conn_id = 9, rc=-5 error = 0
04-26 10:39:12.625 E/QMI_FW (836): QMUXD: Service_id=16 not found over conn_id=0
04-26 10:39:13.226 E/QMI_FW (836): QMUXD: Service_id=16 not found over conn_id=9
04-26 10:39:13.536 E/QMI_FW (836): QMUXD: Service_id=16 not found over conn_id=0
04-26 10:39:13.536 E/QMI_FW (836): QMUXD: Service_id=16 not found over conn_id=9
04-26 10:39:13.556 E/QMI_FW (836): QMUXD: Service_id=16 not found over conn_id=0
04-26 10:39:13.596 E/QMI_FW (836): QMUXD: Service_id=16 not found over conn_id=9
04-26 10:39:13.606 E/QMI_FW (836): QMUXD: Service_id=16 not found over conn_id=0
04-26 10:39:13.606 E/QMI_FW (836): QMUXD: Service_id=16 not found over conn_id=9
04-26 10:39:14.617 E/LocSvc_api_v02(836): E/loc_sync_wait_for_ind:460]: slot 0, timed out for ind_id QMI_LOC_SET_PROTOCOL_CONFIG_PARAMETERS_REQ_V02
04-26 10:39:14.617 E/LocSvc_api_v02(836): E/loc_sync_send_req:532]: loc_api_wait_for_ind failed, err -110, select id 0, status eLOC_CLIENT_FAILURE_TIMEOUT
04-26 10:39:14.617 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::setSUPLVersion(uint32_t):1215]: Error status = eLOC_CLIENT_FAILURE_TIMEOUT, ind..status =
04-26 10:39:14.657 E/LocSvc_adapter(836): E/virtual loc_api_adapter_err LocApiV02Adapter::setLPPConfig(uint32_t):1277]: Error status = eLOC_CLIENT_FAILURE_INTERNAL, ind..status =
04-26 10:39:14.738 E/BluetoothPhoneService(1148): Could not get a handle on Connection object for the call
04-26 10:39:14.828 E/LocSvc_eng(836): E/int loc_eng_init(loc_eng_data_s_type&, LocCallbacks*, LOC_API_ADAPTER_EVENT_MASK_T, void (*)(void*, void*)): log_eng state error: instance already initialized
04-26 10:39:14.828 E/LocSvc_eng(836): E/void loc_eng_agps_init(loc_eng_data_s_type&, AGpsCallbacks*): log_eng state error: agps instance already initialized
04-26 10:39:14.828 E/LocSvc_adapter(836): E/virtual void LocApiV02Adapter::setEngineLock(uint32_t):252]: set engine lock failed status = eLOC_CLIENT_FAILURE_INVALID_PARAMETER
04-26 10:39:15.618 E/MP-Decision(1450): MPDecision server starting
04-26 10:39:15.618 E/MP-Decision(1450): OPTION ENABLED: Control sleep modes
04-26 10:39:15.618 E/MP-Decision(1450): OPTION ENABLED: Adjusting average
04-26 10:39:15.618 E/MP-Decision(1450): Decision parameters CPU 0: Nw=0.000000, Tw=0, Ns=0.000000, Ts=0
04-26 10:39:15.618 E/MP-Decision(1450): Decision parameters CPU 1: Nw=1.990000, Tw=140, Ns=1.100000, Ts=190
04-26 10:39:15.618 E/MP-Decision(1450): Decision parameters: poll_ms: 9 decision_ms: 50
--------- beginning of /dev/log/system
04-26 10:39:15.809 E/WifiStateMachine(836): scanCount==0 - aborting
04-26 10:39:15.839 E/LocSvc_adapter(836): E/virtual void LocApiV02Adapter::setEngineLock(uint32_t):252]: set engine lock failed status = eLOC_CLIENT_FAILURE_INVALID_PARAMETER
04-26 10:39:16.840 E/LocSvc_adapter(836): E/virtual void LocApiV02Adapter::setEngineLock(uint32_t):252]: set engine lock failed status = eLOC_CLIENT_FAILURE_INVALID_PARAMETER
04-26 10:39:17.320 E/ConnectivityService(836): Unexpected mtu value: [email protected]
04-26 10:39:17.490 E/ConnectivityService(836): Unexpected mtu value: [email protected]
04-26 10:39:17.861 E/LocSvc_adapter(836): E/virtual void LocApiV02Adapter::setEngineLock(uint32_t):252]: set engine lock failed status = eLOC_CLIENT_FAILURE_INVALID_PARAMETER
04-26 10:39:18.632 E/NotificationService(836): Suppressing notification from package com.achep.activedisplay by user request.
04-26 10:39:19.072 E/LocSvc_adapter(836): E/virtual void LocApiV02Adapter::setEngineLock(uint32_t):252]: set engine lock failed status = eLOC_CLIENT_FAILURE_INVALID_PARAMETER
04-26 10:39:20.504 E/QC-time-services(432): Daemon:Update to modem bit set
04-26 10:39:20.504 E/QC-time-services(836): Receive Passed == base = 2, unit = 1, operation = 0, result = 0
04-26 10:39:21.955 E/QC-time-services(432): Daemon: Time-services: Waiting to acceptconnection
04-26 10:39:23.435 E/SELinux (1729): SELinux: Loaded file_contexts from /file_contexts
04-26 10:39:23.435 E/SELinux (1729): SELinux: Could not open /data/system/packages.list: Permission denied.
04-26 10:39:34.367 E/AppsCustomizePagedView(1177): Widget ComponentInfo{com.devhd.feedly/com.devhd.feedly.widget.FeedlyWidgetProvider_4_1} has invalid dimensions (0, 0)
04-26 10:39:34.367 E/AppsCustomizePagedView(1177): Widget ComponentInfo{fr.xplod.focal/org.cyanogenmod.focal.WidgetProvider} can not fit on this device (800, 184)
04-26 10:39:41.084 E/WiredAccessoryManager(836): No state change.
04-26 10:39:41.244 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.254 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.435 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.445 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.525 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.535 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.625 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.625 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.775 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.785 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.925 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:41.925 E/AwesomePlayer(330): LPAPlayer::Clip duration setting of less than 30sec not supported, defaulting to 60sec
04-26 10:39:44.248 F/ActivityManager(836): Service ServiceRecord{4234b520 u0 com.google.android.music/.preferences.MusicPreferenceService$MusicPreferenceServiceBinder} in process ProcessRecord{41bd1c30 1818:com.google.android.music:main/u0a78} not same as in map: null
04-26 10:39:44.348 F/ActivityManager(836): Service ServiceRecord{41bb3638 u0 com.google.android.music/.net.NetworkMonitor} in process ProcessRecord{41bd1c30 1818:com.google.android.music:main/u0a78} not same as in map: null
04-26 10:39:46.220 E/GoogleConversionPing(2398): Error sending ping
04-26 10:39:46.220 E/GoogleConversionPing(2398): java.net.ConnectException: failed to connect to www.googleadservices.com/127.0.0.1 (port 80): connect failed: ECONNREFUSED (Connection refused)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at libcore.io.IoBridge.connect(IoBridge.java:114)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:192)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:460)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at java.net.Socket.connect(Socket.java:833)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.android.okhttp.internal.Platform.connectSocket(Platform.java:131)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.android.okhttp.Connection.connect(Connection.java:101)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.android.okhttp.internal.http.HttpEngine.connect(HttpEngine.java:294)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.android.okhttp.internal.http.HttpEngine.sendSocketRequest(HttpEngine.java:255)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.android.okhttp.internal.http.HttpEngine.sendRequest(HttpEngine.java:206)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.android.okhttp.internal.http.HttpURLConnectionImpl.execute(HttpURLConnectionImpl.java:345)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.android.okhttp.internal.http.HttpURLConnectionImpl.getResponse(HttpURLConnectionImpl.java:296)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.android.okhttp.internal.http.HttpURLConnectionImpl.getResponseCode(HttpURLConnectionImpl.java:503)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at com.google.ads.conversiontracking.a.run(SourceFile:168)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at java.lang.Thread.run(Thread.java:841)
04-26 10:39:46.220 E/GoogleConversionPing(2398): Caused by: libcore.io.ErrnoException: connect failed: ECONNREFUSED (Connection refused)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at libcore.io.Posix.connect(Native Method)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at libcore.io.BlockGuardOs.connect(BlockGuardOs.java:85)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at libcore.io.IoBridge.connectErrno(IoBridge.java:127)
04-26 10:39:46.220 E/GoogleConversionPing(2398): at libcore.io.IoBridge.connect(IoBridge.java:112)
04-26 10:39:46.220 E/GoogleConversionPing(2398): ... 13 more
04-26 10:39:46.991 E/SQLiteLog(2479): (1) no such table: mmsconfig
04-26 10:39:46.991 E/Babel (2479): canonicalizeMccMnc: invalid mccmnc nullnull
04-26 10:39:47.441 E/ActivityThread(2509): Failed to find provider info for com.dropbox.carousel.CuOwnerCheckProvider
04-26 10:39:51.585 E/PhoneMonitor(2714): onOtaspChanged old =0, new =3
04-26 10:39:55.490 F/MusicLeanback(2654): Wakelock is not held while processing Intent: Intent { act=com.google.android.music.leanback.CONNECTIVITY_CHANGE cmp=com.google.android.music/.leanback.AutoCacheSchedulingService }
04-26 10:39:58.132 E/ConnectivityService(836): Exception trying to remove a route: java.lang.IllegalStateException: command '56 interface route remove rmnet0 default 0.0.0.0 0 201.188.155.174' failed with '400 56 Failed to remove route from default table (No such process)'
04-26 10:39:59.684 E/SQLiteLog(3077): (1) no such table: mmsconfig
04-26 10:39:59.684 E/Babel (3077): canonicalizeMccMnc: invalid mccmnc nullnull
04-26 10:40:01.326 E/JavaBinder(836): !!! FAILED BINDER TRANSACTION !!!
04-26 10:40:05.731 E/SecondaryTablController(320): ip route del failed: /system/bin/ip route del 201.188.155.174/32 via 0.0.0.0 dev rmnet0 table 60
04-26 10:40:05.741 E/SecondaryTablController(320): ip route del failed: /system/bin/ip route del 0.0.0.0/0 via 201.188.155.174 dev rmnet0 table 60
04-26 10:40:08.974 F/MusicLeanback(2654): Wakelock is not held while processing Intent: Intent { act=com.google.android.music.leanback.CONNECTIVITY_CHANGE cmp=com.google.android.music/.leanback.AutoCacheSchedulingService }
04-26 10:40:11.977 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:40:34.011 E/CheckinTask(1473): Checkin failed: https://android.clients.google.com/checkin (request #0): java.net.UnknownHostException: Unable to resolve host "android.clients.google.com": No address associated with hostname
04-26 10:40:37.915 E/HttpRetriever(2292): Couldn't retrieve data from url http://query.yahooapis.com/v1/publi...t ="-34.405120 -71.145143" and locale="es-US"
04-26 10:40:57.986 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:40:58.016 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:40:58.036 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:41:01.470 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:06.626 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:06.666 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:41:06.686 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:41:10.119 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:15.295 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:15.315 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:41:15.335 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:41:17.998 E/HttpRetriever(2292): Couldn't retrieve data from url http://weather.yahooapis.com/forecastrss?w=56048714&u=c
04-26 10:41:18.758 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:18.849 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:22.322 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:22.382 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:25.846 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:41:32.463 E/CheckinTask(1473): Checkin failed: https://android.clients.google.com/checkin (request #0): java.net.UnknownHostException: Unable to resolve host "android.clients.google.com": No address associated with hostname
04-26 10:44:09.631 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:44:09.661 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:09.731 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:21.804 E/Sensors (476): sns_pwr.c(425):sns_pwr_set_cpu_latency: open error: 2
04-26 10:44:21.824 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:21.844 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:25.298 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:25.358 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:30.383 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:30.403 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:33.887 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:38.962 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:38.972 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:42.476 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:47.471 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:47.491 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:50.995 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:56.030 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:56.050 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:59.504 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:44:59.574 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:45:10.736 E/AKMD2 (476): AKMD_Init
04-26 10:45:10.766 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:45:10.826 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
04-26 10:45:10.946 E/MotoSensors(836): CT406 set ALS poll interval: 200
04-26 10:45:10.946 E/qcom_sensors_hal(836): _hal_sensors_set_delay: max_freq = 0. unsupported sensor? handle:22
04-26 10:45:10.946 E/MotoSensors(836): CT406 set ALS poll interval: 200
04-26 10:45:10.946 E/qcom_sensors_hal(836): _hal_sensors_set_delay: max_freq = 0. unsupported sensor? handle:22
04-26 10:45:11.026 E/AKMD2 (476): AKMD8975 getFormation: keyboard switch open failed
04-26 10:45:14.110 E/Ads (3739): IOException connecting to ad url.
04-26 10:45:20.556 E/qcom_sensors_hal(836): hal_process_report_ind: Bad item quality: 11
Related
Hey guys
I'm looking into the cam again right now, but I need a properly working logcat to compare, this means either a logcat taken from android 4.3, or from the official CM11.
It should contain everything, starting with the opening of the cam app, recording a video (just a few seconds), and closing the app again.
Thanks in advance
i'll provide one from official cm11 nightly in an hour or so. :good:
edit:
here we go, 5 seconds capture with a clean install of cm11 1.3. nightly & gapps only.
Flying_Bear said:
i'll provide one from official cm11 nightly in an hour or so. :good:
edit:
here we go, 5 seconds capture with a clean install of cm11 1.3. nightly & gapps only.
Click to expand...
Click to collapse
Thanks a lot
Looks as if my feeling would be right, it seems we're having encoder issues using grouper blobs:
Code:
D/NvOmxCamera( 160): nvOmxCameraVideoFillBufferDone: receiving the first video frame
V/nvaudio_hw( 160): nvaudio_dev_get_parameters : fm_audio
I/str_params( 160): key: 'fm_audio' value: ''
V/nvaudio_hw( 160): nvaudio_dev_set_parameters : nv_param_media_routing=2
V/nvaudio_hw( 160): Media is routed to :2
D/NvOsDebugPrintf( 160): Event_BlockError from 1BlockAvcEnc : Error code - 4
D/NvOsDebugPrintf( 160): Sending error event from 1BlockAvcEnc
D/NvOsDebugPrintf( 160): Event_BlockError from 1BlockAvcEnc : Error code - 4
D/NvOsDebugPrintf( 160): Blocking error event from 1BlockAvcEnc
D/NvOsDebugPrintf( 160): Event_BlockError from 1BlockAvcEnc : Error code - 4
D/NvOsDebugPrintf( 160): Blocking error event from 1BlockAvcEnc
D/NvOsDebugPrintf( 160): Event_BlockError from 1BlockAvcEnc : Error code - 4
D/NvOsDebugPrintf( 160): Blocking error event from 1BlockAvcEnc
[COLOR="Red"]E/OMXCodec( 160): [OMX.Nvidia.h264.encoder] ERROR(0x80001005, 0)[/COLOR]
D/NvOsDebugPrintf( 160): Event_BlockError from 1BlockAvcEnc : Error code - 4
E/MPEG4Writer( 160): The number of recorded samples is 0
D/NvOsDebugPrintf( 160): Blocking error event from 1BlockAvcEnc
W/MPEG4Writer( 160): 0-duration samples found: 1
W/MPEG4Writer( 160): 0-duration samples found: 1
I/MPEG4Writer( 160): Received total/0-length (0/0) buffers and encoded 0 frames. - video
E/CAM_VideoModule(10237): MediaRecorder error. what=268435556. extra=-1007
V/CAM_VideoModule(10237): stopVideoRecording
D/MPEG4Writer( 160): Stopping Video track
D/MPEG4Writer( 160): Stopping Video track source
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x45627e98 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x45627d90 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x45627c88 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x45627ac0 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x462c7cb0 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x462c7b50 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x462c7a08 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x456a7d90 successful
D/NvOsDebugPrintf( 160): NvRmPowerModuleClockControl off MOD[29] INST[0]
D/CameraSource( 160): reset: E
D/NvOmxCamera( 160): disableMsgType => 0x20
D/NvOmxCamera( 160): NvOmxCamera::stopRecording ++
D/NvOmxCamera( 160): NvxDoCameraControl +++++ 7
D/NvOsDebugPrintf( 160): [imx111] GetBestValidSensorMode: DesiredRes W=3264, H=1836, Fps=15.000000, VideoMode(0)
D/NvOsDebugPrintf( 160): GetParameter(): 50 not supported
D/NvOsDebugPrintf( 160): !!!!!!!!!!!!!! NvOdmImagerListSensorModes !!!!!!!!!!!!!!!
D/NvOsDebugPrintf( 160): !!!!!!!!!!!!!! NvOdmImagerListSensorModes !!!!!!!!!!!!!!!
I/MPEG4Writer( 160): setStartTimestampUs: 39853
I/MPEG4Writer( 160): Earliest track starting time: 39853
D/NvOmxCamera( 160): nvOmxCameraVideoFillBufferDone: receiving the first video frame
D/NvOmxCamera( 160): nvOmxCameraVideoFillBufferDone: fall through 0x400e4df0
D/NvOmxCamera( 160): nvOmxCameraVideoFillBufferDone: fall through 0x400e4e80
D/NvOsDebugPrintf( 160): [imx111]NvCameraUpdateResolution 3264x1836 SetViClock(ViClockType_Core) : ClockTemp = 151200
D/NvOmxCamera( 160): <<<Event handler got event [4] == 0x5
D/NvOmxCamera( 160): nvOmxCameraVideoFillBufferDone: receiving EOS from video stream
D/NvOmxCamera( 160): NvxDoCameraControl ----- 7
V/NvOmxCameraEncoderQueue( 160): release: shutting down encoder queue + thread +++.
V/NvOmxCameraEncoderQueue( 160): release: encoder thread finished.
V/NvOmxCameraEncoderQueue( 160): release: done! ---
D/NvOmxCamera( 160): NvOmxCamera::stopRecording --
V/nvaudio_hw( 160): nvaudio_dev_get_parameters : fm_audio
I/str_params( 160): key: 'fm_audio' value: ''
V/nvaudio_hw( 160): nvaudio_dev_set_parameters : nv_param_media_routing=2
V/nvaudio_hw( 160): Media is routed to :2
D/NvOsDebugPrintf( 160): Frame length keep as before : 1874
D/CameraSource( 160): reset: X
D/MPEG4Writer( 160): Video track stopped
D/MPEG4Writer( 160): Stopping Audio track
E/AudioSink( 160): received unknown event type: 1 inside CallbackWrapper !
I/MPEG4Writer( 160): Received total/0-length (4/0) buffers and encoded 3 frames. - audio
I/MPEG4Writer( 160): Audio track drift time: 0 us
D/MPEG4Writer( 160): Stopping Audio track source
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x41c1fe48 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x41c1fd90 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x456a93b8 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x42fb98f8 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x41ce9ac8 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x41ce99a0 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x48d3ea30 successful
I/OMXNodeInstance( 160): OMX_FreeBuffer for buffer header 0x48d3e830 successful
V/nvaudio_hw( 160): nvaudio_in_standby
V/nvaudio_hw( 160): close_alsa_dev : active stream count 1
D/MPEG4Writer( 160): Audio track stopped
D/MPEG4Writer( 160): Duration from tracks range is [0, 192000] us
D/MPEG4Writer( 160): Stopping writer thread
D/MPEG4Writer( 160): 0 chunks are written in the last batch
D/MPEG4Writer( 160): Writer thread stopped
D/MPEG4Writer( 160): Stopping Video track
D/MPEG4Writer( 160): Stopping Audio track
E/MediaRecorder(10237): stop failed: -1007
E/CAM_VideoModule(10237): stop fail
E/CAM_VideoModule(10237): java.lang.RuntimeException: stop failed.
E/CAM_VideoModule(10237): at android.media.MediaRecorder.stop(Native Method)
E/CAM_VideoModule(10237): at com.android.camera.VideoModule.stopVideoRecording(VideoModule.java:1746)
E/CAM_VideoModule(10237): at com.android.camera.VideoModule.onError(VideoModule.java:1553)
E/CAM_VideoModule(10237): at android.media.MediaRecorder$EventHandler.handleMessage(MediaRecorder.java:982)
E/CAM_VideoModule(10237): at android.os.Handler.dispatchMessage(Handler.java:102)
E/CAM_VideoModule(10237): at android.os.Looper.loop(Looper.java:136)
E/CAM_VideoModule(10237): at android.app.ActivityThread.main(ActivityThread.java:5081)
E/CAM_VideoModule(10237): at java.lang.reflect.Method.invokeNative(Native Method)
E/CAM_VideoModule(10237): at java.lang.reflect.Method.invoke(Method.java:515)
E/CAM_VideoModule(10237): at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:791)
E/CAM_VideoModule(10237): at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:607)
E/CAM_VideoModule(10237): at dalvik.system.NativeStart.main(Native Method)
V/CAM_VideoModule(10237): Deleting video /storage/emulated/0/DCIM/Camera/VID_20140302_190521.3gp.tmp
D/CAM_OrientationManager(10237): unlock orientation
V/CAM_VideoModule(10237): Releasing media recorder.
Gonna ping @Adam77Root, as he was working on cam a bit longer than me. So, Adam (or anyone else), ideas?
laufersteppenwolf said:
Gonna ping @Adam77Root, as he was working on cam a bit longer than me. So, Adam (or anyone else), ideas?
Click to expand...
Click to collapse
0x80001005 is bad parameter error (reference). It seems camera blobs want to use some parameter(s) that's missing in new grouper encoders. Otherwise I didn't really check this as QR/barcode scanning was a bigger issue for me.
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
Q&A for [m7] Unofficial CyanogenMod 13 / Official CyanogenMod 12.1 Nightlies
As I have searched Original Android Development and Q&A forums and haven't found nothing about the thread above and questions related to it, so creating this one. Please use search before posting in here.
cm-13.0-20151219-UNOFFICIAL-m7 bugs
Hi,
A few things about "Unofficial CyanogenMod 13" for HTC One M7 Int. (cm-13.0-20151219-UNOFFICIAL-m7)
1. Cannot change preferred network type from "LTE (recommended)" to 3G or 2G. If I try, it just closes the choice window and that's it. Here is logcat for each choice:
a) 3G:
12-21 09:59:24.642 W/InputMethodManagerService( 602): Window already focused, ignoring focus gain of: [email protected] attribute=null, token = [email protected]
12-21 09:59:24.643 D/NetworkSettings( 1354): onPreferenceChange
12-21 09:59:24.643 D/NetworkSettings( 1354): buttonNetworkMode: 0
12-21 09:59:24.643 D/NetworkSettings( 1354): getPreferredNetworkModeForSubId: phoneNwMode = 9 subId = 1
12-21 09:59:24.666 D/NetworkSettings( 1354): handleSetPreferredNetworkTypeResponse: exception in setting network mode.
12-21 09:59:24.666 D/NetworkSettings( 1354): getPreferredNetworkModeForSubId: phoneNwMode = 9 subId = 1
12-21 09:59:24.666 D/NetworkSettings( 1354): updatePreferredNetworkUIFromDb: settingsNetworkMode = 9
12-21 09:59:24.666 D/NetworkSettings( 1354): isWorldMode=false
12-21 09:59:24.723 W/System ( 1088): ClassLoader referenced unknown path: /system/framework/tcmclient.jar
Click to expand...
Click to collapse
b) 2G:
12-21 09:59:30.415 W/InputMethodManagerService( 602): Window already focused, ignoring focus gain of: [email protected] attribute=null, token = [email protected]
12-21 09:59:30.416 D/NetworkSettings( 1354): onPreferenceChange
12-21 09:59:30.419 D/NetworkSettings( 1354): buttonNetworkMode: 1
12-21 09:59:30.420 D/NetworkSettings( 1354): getPreferredNetworkModeForSubId: phoneNwMode = 9 subId = 1
12-21 09:59:30.440 D/NetworkSettings( 1354): handleSetPreferredNetworkTypeResponse: exception in setting network mode.
12-21 09:59:30.440 D/NetworkSettings( 1354): getPreferredNetworkModeForSubId: phoneNwMode = 9 subId = 1
12-21 09:59:30.440 D/NetworkSettings( 1354): updatePreferredNetworkUIFromDb: settingsNetworkMode = 9
12-21 09:59:30.440 D/NetworkSettings( 1354): isWorldMode=false
Click to expand...
Click to collapse
c) LTE (the same as marked):
12-21 09:59:36.671 W/InputEventReceiver( 1354): Attempted to finish an input event but the input event receiver has already been disposed.
12-21 09:59:36.677 W/InputMethodManagerService( 602): Window already focused, ignoring focus gain of: [email protected] attribute=null, token = [email protected]
12-21 09:59:36.678 D/NetworkSettings( 1354): onPreferenceChange
12-21 09:59:36.678 D/NetworkSettings( 1354): buttonNetworkMode: 9
12-21 09:59:36.678 D/NetworkSettings( 1354): getPreferredNetworkModeForSubId: phoneNwMode = 9 subId = 1
Click to expand...
Click to collapse
2. Get random reboots. One thing I noticed, I get rebooted somewhere about the time when "SMS Backup and Restore" runs a scheduled backup. I get somewhere about 1-3 reboots per days as much as I have been using this ROM.
cm-13.0-20151219-UNOFFICIAL-m7 random reboots
A minute ago happened a random reboot, here's the output of logcat:
12-21 12:56:14.583 E/NetlinkEvent( 221): NetlinkEvent::FindParam(): Parameter 'UID' not found
12-21 12:56:26.837 E/NetlinkEvent( 221): NetlinkEvent::FindParam(): Parameter 'UID' not found
12-21 12:56:36.862 E/NetlinkEvent( 221): NetlinkEvent::FindParam(): Parameter 'UID' not found
12-21 12:56:56.846 E/NetlinkEvent( 221): NetlinkEvent::FindParam(): Parameter 'UID' not found
12-21 12:57:07.062 E/NetlinkEvent( 221): NetlinkEvent::FindParam(): Parameter 'UID' not found
12-21 12:58:34.807 I/perfprofd( 233): initiating profile collection
12-21 12:58:34.844 W/perfprofd( 233): type=1400 audit(0.0:9): avc: denied { write } for name="property_service" dev="tmpfs" ino=7181 scontext=u:rerfprofd:s0 tcontext=ubject_rroperty_socket:s0 tclass=sock_file permissive=0
12-21 12:58:34.858 E/perfprofd( 233): setprop ctl.stop mpdecision failed
Click to expand...
Click to collapse
accesspc said:
A minute ago happened a random reboot, here's the output of logcat:
Click to expand...
Click to collapse
As a workaround done this:
Code:
adb shell su -c setenforce 0
And since then - no more reboots. As a result found these AVC message in DMESG:
Code:
adb shell dmesg | grep -i avc
[33691.839812] type=1400 audit(1450776153.729:35): avc: denied { write } for pid=232 comm="perfprofd" name="property_service" dev="tmpfs" ino=7215 scontext=u:rerfprofd:s0 tcontext=ubject_rroperty_socket:s0 tclass=sock_file permissive=1
[33691.840362] type=1400 audit(1450776153.729:36): avc: denied { connectto } for pid=232 comm="perfprofd" path="/dev/socket/property_service" scontext=u:rerfprofd:s0 tcontext=u:r:init:s0 tclass=unix_stream_socket permissive=1
Click to expand...
Click to collapse
Anybody? Help?
accesspc said:
As a workaround done this:
Code:
adb shell su -c setenforce 0
And since then - no more reboots. As a result found these AVC message in DMESG:
Code:
adb shell dmesg | grep -i avc
Anybody? Help?
Click to expand...
Click to collapse
Seems to be this has been fixed in cm-13.0-20151223-UNOFFICIAL-m7.zip build - no more random reboots, but Preferred network type still can't be changed...
Hello
How smooth is it with this rom?
sacaitu said:
How smooth is it with this rom?
Click to expand...
Click to collapse
Hi, the rom is really quite good, only bugs that I have noticed were:
* after fresh install of Facebook and Messenger they force closed - need to start each app at least once, then delete these files:
** /data/data/com.facebook.katana/lib-xzs/libssl*
** /data/data/com.facebook.orca/lib-xzs/libss*
* Still cannot change Preferred network type, either via settings menu, nor via dialpad *#*#4636#*#*. This is set to auto LTE/3G/2G, which drains battery a lot if connection is not stable (many handovers or switching between LTE and 3G)
Other than that - awesome, fast, pretty
Just installed cm-13.0-20160103-UNOFFICIAL-m7 from http://forum.xda-developers.com/showpost.php?p=64632124&postcount=129.
Installation sequence:
1. TWRP -> Wipe -> Advanced: Dalvik Cache, Cache and System
2. Install cm-13.0-20160103-UNOFFICIAL-m7.zip
3. Install open_gapps-arm-6.0-nano-20151225.zip
4. Flash boot.img from cm-13.0-20160103-UNOFFICIAL-m7.zip
Issues that still persist:
* Flashligh FCs after a few seconds from start
* Camera FCs after picture taken, video capture works fine
* Preferred network type cannot be changed, get the same error as before
Haven't tested Facebook and Messenger, as I did not do a full wipe.
How is the battery life? CM is notorious for bad battery life on this device.
With 5.1 GPE ROM, m7 has a very good battery life, with about 2% per hour drain with normal day use and 0.5% per hour drain at idle at night. Does this ROM close to that?
Also, did you collect logcat for those FCs?
I haven't tested the battery life a lot, because before this CM I was using stock latest rom, so the battery drain is about the same, might tell you more tomorrow.
As for Force Closes, here are logcats (to not clutter the forum, put all logs to pastebin:
Flashlight:
1st try: http://pastebin.com/kE1vvdkd
2ndtry: http://pastebin.com/n5WizzmS
Camera:
1st try: http://pastebin.com/HzLXJVKv
Preferred network type change:
To LTE (the same as selected):
Code:
01-05 12:05:53.466 W/InputEventReceiver( 1379): Attempted to finish an input event but the input event receiver has already been disposed.
01-05 12:05:53.467 W/InputMethodManagerService( 620): Window already focused, ignoring focus gain of: [email protected] attribute=null, token = [email protected]
01-05 12:05:53.473 D/NetworkSettings( 1379): onPreferenceChange
01-05 12:05:53.473 D/NetworkSettings( 1379): buttonNetworkMode: 9
To 3G:
Code:
01-05 12:06:04.297 W/InputEventReceiver( 1379): Attempted to finish an input event but the input event receiver has already been disposed.
01-05 12:06:04.298 W/InputMethodManagerService( 620): Window already focused, ignoring focus gain of: [email protected] attribute=null, token = [email protected]
01-05 12:06:04.307 D/NetworkSettings( 1379): onPreferenceChange
01-05 12:06:04.308 D/NetworkSettings( 1379): buttonNetworkMode: 0
01-05 12:06:04.360 D/NetworkSettings( 1379): handleSetPreferredNetworkTypeResponse: exception in setting network mode.
01-05 12:06:04.372 D/NetworkSettings( 1379): updatePreferredNetworkUIFromDb: settingsNetworkMode = 9
01-05 12:06:04.372 D/NetworkSettings( 1379): isWorldMode=false
To 2G:
Code:
01-05 12:06:08.476 W/InputMethodManagerService( 620): Window already focused, ignoring focus gain of: [email protected] attribute=null, token = [email protected]
01-05 12:06:08.476 D/NetworkSettings( 1379): onPreferenceChange
01-05 12:06:08.482 D/NetworkSettings( 1379): buttonNetworkMode: 1
01-05 12:06:08.508 D/NetworkSettings( 1379): handleSetPreferredNetworkTypeResponse: exception in setting network mode.
01-05 12:06:08.521 D/NetworkSettings( 1379): updatePreferredNetworkUIFromDb: settingsNetworkMode = 9
01-05 12:06:08.522 D/NetworkSettings( 1379): isWorldMode=false
devsk said:
How is the battery life? CM is notorious for bad battery life on this device.
With 5.1 GPE ROM, m7 has a very good battery life, with about 2% per hour drain with normal day use and 0.5% per hour drain at idle at night. Does this ROM close to that?
Also, did you collect logcat for those FCs?
Click to expand...
Click to collapse
Now that you asked about battery drain, I measured about 30 % drop (from 100%) in 12 hours (7 normal usage and 5 night time) which equals to 2,5% per hour. That isn't bad, considering that WiFi always on, made a few calls, used GPS for about 15 minutes, Titanium backup ran at night (backing up 5 Apps) and SMS Backup & Restore at night. Oh, and 5 alarms early in the morning.
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.