Audio stops in light doze after 5 minutes even if chrome is whitelisted in ignore battery optimizations setting
Reported by
hkskogl...@gmail.com,
Feb 10 2017
|
|||||||
Issue descriptionExample URL: http://www.stitcher.com/podcast/guru-performance-we-do-science Steps to reproduce the problem: 0. Whitelist chrome in ignore battery optimizations 1. Battery unplugged, device not in motion 1. Listen to URL for about 5 minutes 2. Android enters light doze mode 3. Audio playback stops What is the expected behavior? Plays podcast without issues if chrome is whitelisted in ignore battery optimizations What went wrong? Excerpt from adb logcat: 02-09 23:40:17.430 22820 2509 D AudioTrack: start(): 0xcabcdc00 02-09 23:40:17.432 22820 22882 D AudioTrack: stop(): 0xcabcdc00 02-09 23:40:17.432 22820 22882 D AudioTrackShared: this(0xc7c72580), mCblk(0xc2900000), front(22948416), mIsOut 1, interrupt() FUTEX_WAKE 02-09 23:40:17.433 19668 19856 V AudioFlinger_Tracks: stop(4096), calling pid 22820 02-09 23:40:17.434 19668 19856 V AudioFlinger_Tracks: not stopping/stopped => stopping/stopped (4096) on thread 0xe8003580 02-09 23:40:17.435 19668 19855 V AudioFlinger_Tracks: flush(4096) 02-09 23:40:17.443 19668 19791 V audio_hw_primary: out_get_latency: Latency 8 02-09 23:40:17.443 19668 19791 V AudioFlinger_Tracks: presentationComplete() mPresentationCompleteFrames 0 framesWritten 22949760 02-09 23:40:17.443 19668 19791 V AudioFlinger_Tracks: presentationComplete() reset: mPresentationCompleteFrames 22950144 audioHalFrames 384 02-09 23:40:17.463 19668 19791 V audio_hw_primary: out_get_latency: Latency 8 02-09 23:40:17.463 19668 19791 V AudioFlinger_Tracks: presentationComplete() mPresentationCompleteFrames 22950144 framesWritten 22950720 02-09 23:40:17.463 19668 19791 D AudioTrackShared: this(0xe972c9a0), mCblk(0xe5700000), front(22948800), mIsOut 1, setStreamEndDone() FUTEX_WAKE 02-09 23:40:17.467 19668 19791 V AudioFlinger_Threads: removeTracks_l removing track on session 1729 02-09 23:40:17.479 19668 19791 V AudioPolicyService: AudioCommandThread() adding stop output 21 02-09 23:40:17.479 19668 19791 V AudioPolicyService: inserting command: 6 at index 0, num commands 0 02-09 23:40:17.480 19668 19759 V AudioPolicyService: AudioCommandThread() processing stop output 21 02-09 23:40:17.480 19668 19759 V APM_AudioPolicyManager: stopOutput() output 21, stream 3, session 1729 02-09 23:40:17.480 19668 19759 V APM::AudioOutputDescriptor: changeRefCount() stream 3, count 0 02-09 23:40:17.480 19668 19759 V AudioPolicyManagerEx: getNewOutputDevice() selected device 0 02-09 23:40:17.480 19668 19759 V APM_AudioPolicyManager: setOutputDevice() device 0000 delayMs 96 02-09 23:40:17.480 19668 19759 V APM_AudioPolicyManager: setOutputDevice() prevDevice 0x0002 02-09 23:40:17.480 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::refreshTypes() mDeviceTypes 00000001 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::getDevicesFromType() for type 1 found 0xe9742000 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::refreshTypes() mDeviceTypes 00000003 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::getDevicesFromType() for type 2 found 0xe9742140 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::refreshTypes() mDeviceTypes 00010003 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::getDevicesFromType() for type 10000 found 0xe9742b40 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.481 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.482 19668 19759 V APM_AudioPolicyManager: setOutputDevice() setting same device 0x0000 or null device 02-09 23:40:17.482 19668 19759 D AudioPolicyManagerEx: handleNotificationRoutingForStream() 02-09 23:40:17.482 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.482 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.482 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.482 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.482 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.482 19668 19759 V APM::Devices: DeviceVector::refreshTypes() mDeviceTypes 00000001 02-09 23:40:17.483 19668 19759 V APM::Devices: DeviceVector::getDevicesFromType() for type 1 found 0xe9742000 02-09 23:40:17.483 19668 19759 V APM::Devices: DeviceVector::refreshTypes() mDeviceTypes 00000003 02-09 23:40:17.483 19668 19759 V APM::Devices: DeviceVector::getDevicesFromType() for type 2 found 0xe9742140 02-09 23:40:17.483 19668 19759 V APM::Devices: DeviceVector::refreshTypes() mDeviceTypes 00010003 02-09 23:40:17.483 19668 19759 V APM::Devices: DeviceVector::getDevicesFromType() for type 10000 found 0xe9742b40 02-09 23:40:17.483 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.483 19668 19759 V APM::Devices: DeviceVector::getDevice() for type 00008000 address 0 found 0x0 02-09 23:40:17.483 19668 19759 V AudioPolicyService: -AudioCommandThread 6 02-09 23:40:17.483 19668 19759 V AudioPolicyService: AudioCommandThread() going to sleep 02-09 23:40:20.453 19668 19791 V AudioFlinger_Threads: Audio hardware entering standby, mixer 0xe8003580, suspend count 0 02-09 23:40:20.454 19668 19791 D audio_hw_primary: out_standby: enter: stream (0xe8d8bc00) usecase(12: audio-ull-playback) 02-09 23:40:20.545 19668 19791 V audio_hw_primary: stop_output_stream: enter: usecase(12: audio-ull-playback) 02-09 23:40:20.546 19668 19791 V msm8996_platform_8996: lge_reset_24bit_playback_msm8996 02-09 23:40:20.546 19668 19791 W msm8996_platform_8996: lge_reset_24bit_playback_msm8996: This case is not Offload usecase(12) 02-09 23:40:20.546 19668 19791 V audio_hw_primary: disable_audio_route: enter: usecase(12) 02-09 23:40:20.546 19668 19791 V msm8974_platform_lge: LGE_platform_add_backend_name: enter: 2 02-09 23:40:20.546 19668 19791 D audio_hw_primary: disable_audio_route: reset and update mixer path: audio-ull-playback 02-09 23:40:20.550 19668 19791 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_id 12 of type 0 for Event 2, with Raise=0 02-09 23:40:20.550 19668 19791 V audio_hw_primary: disable_audio_route: exit 02-09 23:40:20.550 19668 19791 D hardware_info: hw_info_append_hw_type : device_name = speaker 02-09 23:40:20.550 19668 19791 D audio_hw_primary: disable_snd_device: snd_device(2: speaker) 02-09 23:40:20.567 19668 19791 D hardware_info: hw_info_append_hw_type : device_name = vi-feedback 02-09 23:40:20.567 19668 19791 D audio_hw_primary: disable_snd_device: snd_device(151: vi-feedback) 02-09 23:40:20.568 19668 19791 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0x97 of type 1 for Event 0, with Raise=0 02-09 23:40:20.568 19668 19791 V audio_hw_primary: disable_audio_route: enter: usecase(38) 02-09 23:40:20.568 19668 19791 V msm8974_platform_lge: LGE_platform_add_backend_name: enter: 107 02-09 23:40:20.568 19668 19791 D audio_hw_primary: disable_audio_route: reset and update mixer path: spkr-vi-record 02-09 23:40:20.568 19668 19791 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_id 38 of type 1 for Event 2, with Raise=0 02-09 23:40:20.569 19668 19791 V audio_hw_primary: disable_audio_route: exit 02-09 23:40:20.569 19668 19791 D audio_hw_spkr_prot: audio_extn_spkr_prot_stop_processing: disable_snd_device(39: speaker-protected) 02-09 23:40:20.585 19668 19791 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0x2 of type 0 for Event 0, with Raise=0 02-09 23:40:20.585 19668 19791 V audio_hw_primary: stop_output_stream: exit: status(0) 02-09 23:40:20.585 19668 19791 D audio_hw_primary: out_standby: exit 02-09 23:40:20.588 19668 19791 V AudioFlinger_Threads: thread 0xe8003580 type 0 TID 19791 going to sleep 02-09 23:40:20.695 20055 20170 I libsuspend: ws name: wlan_scan_wake, act_since: 6, act_cnt: 1645, ev_cnt: 1645, wakeup_cnt: 152 02-09 23:40:20.695 20055 20170 I libsuspend: ws name: wlan_oob_irq_wake, act_since: 198, act_cnt: 11775, ev_cnt: 11853, wakeup_cnt: 637 02-09 23:40:20.695 20055 20170 I libsuspend: ws name: wlan_rx_wake, act_since: 323, act_cnt: 40786, ev_cnt: 1752535, wakeup_cnt: 119063 02-09 23:40:20.695 20055 20170 I libsuspend: ws name: wlan_wake, act_since: 499, act_cnt: 1631957, ev_cnt: 1631957, wakeup_cnt: 111309 02-09 23:40:20.695 20055 20170 I libsuspend: ws name: ipc0000017e_Thread-3, act_since: 2, act_cnt: 94, ev_cnt: 94, wakeup_cnt: 8 02-09 23:40:20.695 20055 20170 I libsuspend: ws name: ipc0000017a_Binder:20055_14, act_since: 15, act_cnt: 100966, ev_cnt: 121028, wakeup_cnt: 4129 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: eventpoll, act_since: 1, act_cnt: 392, ev_cnt: 392, wakeup_cnt: 66 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: ipc00000172_Loc_hal_worker, act_since: 7, act_cnt: 10299, ev_cnt: 26334, wakeup_cnt: 2237 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: ipc00000170_system_server, act_since: 10, act_cnt: 927, ev_cnt: 927, wakeup_cnt: 72 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: ipc0000016f_system_server, act_since: 58, act_cnt: 15103, ev_cnt: 15103, wakeup_cnt: 1541 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: event2, act_since: 1, act_cnt: 469, ev_cnt: 469, wakeup_cnt: 29 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: event1, act_since: 43, act_cnt: 2426429, ev_cnt: 2426429, wakeup_cnt: 175733 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: [timerfd], act_since: 42, act_cnt: 13704, ev_cnt: 13704, wakeup_cnt: 1002 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: [timerfd], act_since: 11, act_cnt: 7454, ev_cnt: 7454, wakeup_cnt: 1002 02-09 23:40:20.696 20055 20170 I libsuspend: ws name: eventpoll, act_since: 16, act_cnt: 37635, ev_cnt: 43348, wakeup_cnt: 1955 02-09 23:40:20.697 20055 20170 I libsuspend: ws name: ipc00000154_system_server, act_since: 4, act_cnt: 1463, ev_cnt: 1463, wakeup_cnt: 72 02-09 23:40:20.697 20055 20170 I libsuspend: ws name: ipc00000153_system_server, act_since: 4, act_cnt: 1075, ev_cnt: 1079, wakeup_cnt: 57 02-09 23:40:20.697 20055 20170 I libsuspend: ws name: PowerManagerService.Broadcasts, act_since: 2, act_cnt: 1149, ev_cnt: 1149, wakeup_cnt: 58 02-09 23:40:20.697 20055 20170 I libsuspend: ws name: SensorService_wakelock, act_since: 6, act_cnt: 4332, ev_cnt: 4332, wakeup_cnt: 253 02-09 23:40:20.697 20055 20170 I libsuspend: ws name: sensor_ind, act_since: 1, act_cnt: 4344, ev_cnt: 4344, wakeup_cnt: 253 02-09 23:40:20.697 20055 20170 I libsuspend: ws name: PowerManagerService.WakeLocks, act_since: 206, act_cnt: 44423, ev_cnt: 44424, wakeup_cnt: 3023 02-09 23:40:20.697 20055 20170 I libsuspend: ws name: KeyEvents, act_since: 30, act_cnt: 1870346, ev_cnt: 1870346, wakeup_cnt: 122017 02-09 23:40:20.697 20055 20170 I libsuspend: ws name: PowerManagerService.Display, act_since: 16, act_cnt: 1362, ev_cnt: 1363, wakeup_cnt: 95 02-09 23:40:20.698 20055 20170 I libsuspend: ws name: ipc00000074_fdal-lo, act_since: 2, act_cnt: 2773, ev_cnt: 3197, wakeup_cnt: 195 02-09 23:40:20.698 20055 20170 I libsuspend: ws name: ipc00000062_atd, act_since: 1, act_cnt: 171, ev_cnt: 171, wakeup_cnt: 13 02-09 23:40:20.698 20055 20170 I libsuspend: ws name: ipc0000004b_rild, act_since: 1, act_cnt: 1195, ev_cnt: 1195, wakeup_cnt: 139 02-09 23:40:20.698 20055 20170 I libsuspend: ws name: ipc0000004a_rild, act_since: 1, act_cnt: 31, ev_cnt: 32, wakeup_cnt: 5 02-09 23:40:20.698 20055 20170 I libsuspend: ws name: NETLINK, act_since: 32, act_cnt: 126494, ev_cnt: 126494, wakeup_cnt: 55970 02-09 23:40:20.698 20055 20170 I libsuspend: ws name: eventpoll, act_since: 464, act_cnt: 126494, ev_cnt: 126819, wakeup_cnt: 56253 02-09 23:40:20.698 20055 20170 I libsuspend: ws name: mpss_IPCRTR, act_since: 1, act_cnt: 81600, ev_cnt: 83061, wakeup_cnt: 6666 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: NETLINK, act_since: 42, act_cnt: 128607, ev_cnt: 128607, wakeup_cnt: 57684 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: [timerfd], act_since: 1, act_cnt: 1804, ev_cnt: 1804, wakeup_cnt: 161 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: ipc00000020_rmt_storage, act_since: 1, act_cnt: 149, ev_cnt: 149, wakeup_cnt: 7 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: eventpoll, act_since: 332, act_cnt: 130238, ev_cnt: 131501, wakeup_cnt: 58156 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: dsps_IPCRTR, act_since: 5, act_cnt: 416777, ev_cnt: 417624, wakeup_cnt: 60202 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: ipc00000017_kworker/1:3, act_since: 39, act_cnt: 2868, ev_cnt: 2868, wakeup_cnt: 274 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: anx_wlock, act_since: 1, act_cnt: 54, ev_cnt: 192, wakeup_cnt: 25 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: bluetooth_pm, act_since: 17, act_cnt: 3596, ev_cnt: 3605, wakeup_cnt: 268 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: qpnp_fg_sanity_check, act_since: 731, act_cnt: 44923, ev_cnt: 44923, wakeup_cnt: 4468 02-09 23:40:20.699 20055 20170 I libsuspend: ws name: qpnp_fg_slope_limit, act_since: 298, act_cnt: 24010, ev_cnt: 24010, wakeup_cnt: 2383 02-09 23:40:20.700 20055 20170 I libsuspend: ws name: qpnp_fg_update_sram, act_since: 123, act_cnt: 8137, ev_cnt: 8137, wakeup_cnt: 811 02-09 23:40:20.700 20055 20170 I libsuspend: ws name: qpnp_fg_update_temp, act_since: 67, act_cnt: 22649, ev_cnt: 22649, wakeup_cnt: 2252 02-09 23:40:20.700 20055 20170 I libsuspend: ws name: qpnp_fg_memaccess, act_since: 117, act_cnt: 165182, ev_cnt: 165182, wakeup_cnt: 16001 02-09 23:40:20.700 20055 20170 I libsuspend: ws name: smb1351, act_since: 48, act_cnt: 74703, ev_cnt: 74703, wakeup_cnt: 5075 02-09 23:40:20.700 20055 20170 I libsuspend: ws name: touch_lpwg, act_since: 9, act_cnt: 509, ev_cnt: 707, wakeup_cnt: 35 02-09 23:40:20.700 20055 20170 I libsuspend: ws name: alarmtimer, act_since: 119, act_cnt: 546, ev_cnt: 546, wakeup_cnt: 546 02-09 23:40:21.307 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.307 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.310 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.310 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.310 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.310 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.311 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.311 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.312 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.312 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.314 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.314 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.314 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.314 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.314 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.314 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.314 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.316 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.316 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.316 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.318 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.318 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.320 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.322 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.323 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.326 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.326 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.327 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.329 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.331 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.332 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.333 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.335 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.337 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.338 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:21.340 19671 20224 V Netd : unexpected event from subsystem firmware 02-09 23:40:22.200 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:22.200 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:22.206 20576 20576 D KeyguardUpdateMonitor: Intent.ACTION_BATTERY_CHANGED status : 3 ,plugged : 0 ,level : 42 ,temperature : 236, temperatureState : 0, EXTRA_CHARGING_CURRENT : 0 / EXTRA_HVDCP_TYPE : false 02-09 23:40:22.206 20576 20576 I LGPowerUI: onReceive = android.intent.action.BATTERY_CHANGED 02-09 23:40:22.207 21191 21404 W QCNEJ : |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED 02-09 23:40:22.208 19668 19668 V APM::Devices: DeviceVector::refreshTypes() mDeviceTypes 00004000 02-09 23:40:22.208 19668 19668 V APM::Devices: DeviceVector::getDevice() for type 00004000 address found 0x0 02-09 23:40:22.208 20822 20822 D UsbHostManagerService: intent receive : android.intent.action.BATTERY_CHANGED 02-09 23:40:22.209 20576 20576 I LGPowerUI: level = 42, plugType = 0, plugged = false, charging = false, temperature = 236, chargingCurrent = 0, factoryCableItem = 0, isFastCharging = false, batteryID = 1 02-09 23:40:22.209 21310 21310 D TeleService: PhoneGlobalsEx: onReceive: android.intent.action.BATTERY_CHANGED 02-09 23:40:22.213 5243 5243 W MainApplication: onReceive intent : Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) } 02-09 23:40:22.214 1756 1826 D HeadsetStateMachine: Disconnected process message: 10, size: 0 02-09 23:40:22.437 22820 22882 D AudioTrack: stop(): 0xcabcdc00 02-09 23:40:22.437 22820 22882 D AudioTrack: stop(): 0xcabcdc00 02-09 23:40:22.437 22820 22882 D AudioTrackShared: this(0xc7c72580), mCblk(0xc2900000), front(22948800), mIsOut 1, interrupt() FUTEX_WAKE 02-09 23:40:22.439 22820 22882 V AudioTrack: ~AudioTrack, releasing session id 1729 from 22820 on behalf of 22820 02-09 23:40:22.440 19668 18387 V AudioFlinger_Threads: remove track (4096) and delete from mixer 02-09 23:40:22.440 19668 19869 V AudioFlinger: releasing 1729 from 22820 for 22820 02-09 23:40:22.440 19668 19869 V AudioFlinger: decremented refcount to 0 02-09 23:40:22.440 19668 19869 V AudioFlinger: purging stale effects 02-09 23:40:22.440 19668 18387 V AudioPolicyService: AudioCommandThread() adding release output 21 02-09 23:40:22.440 19668 18387 V AudioPolicyService: inserting command: 7 at index 0, num commands 0 02-09 23:40:22.440 19668 18387 V AudioFlinger_Tracks: PlaybackThread::Track destructor 02-09 23:40:22.440 19668 19759 V AudioPolicyService: AudioCommandThread() processing release output 21 02-09 23:40:22.440 19668 18387 V AudioFlinger: removeClient_l() pid 22820, calling pid 19668 02-09 23:40:22.441 19668 19759 V APM_AudioPolicyManager: releaseOutput() 21 02-09 23:40:22.441 19668 19759 V AudioPolicyService: -AudioCommandThread 7 02-09 23:40:22.441 19668 19759 V AudioPolicyService: AudioCommandThread() going to sleep 02-09 23:40:24.001 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:24.002 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:25.339 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:25.339 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:26.665 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:26.665 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:26.679 20576 20576 D KeyguardUpdateMonitor: Intent.ACTION_BATTERY_CHANGED status : 3 ,plugged : 0 ,level : 42 ,temperature : 234, temperatureState : 0, EXTRA_CHARGING_CURRENT : 0 / EXTRA_HVDCP_TYPE : false 02-09 23:40:26.679 20576 20576 I LGPowerUI: onReceive = android.intent.action.BATTERY_CHANGED 02-09 23:40:26.680 19668 19668 V APM::Devices: DeviceVector::refreshTypes() mDeviceTypes 00004000 02-09 23:40:26.680 20822 20822 D UsbHostManagerService: intent receive : android.intent.action.BATTERY_CHANGED 02-09 23:40:26.680 19668 19668 V APM::Devices: DeviceVector::getDevice() for type 00004000 address found 0x0 02-09 23:40:26.680 20576 20576 I LGPowerUI: level = 42, plugType = 0, plugged = false, charging = false, temperature = 234, chargingCurrent = 0, factoryCableItem = 0, isFastCharging = false, batteryID = 1 02-09 23:40:26.682 21310 21310 D TeleService: PhoneGlobalsEx: onReceive: android.intent.action.BATTERY_CHANGED 02-09 23:40:26.682 21191 21404 W QCNEJ : |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED 02-09 23:40:26.684 1756 1826 D HeadsetStateMachine: Disconnected process message: 10, size: 0 02-09 23:40:26.684 5243 5243 W MainApplication: onReceive intent : Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) } 02-09 23:40:28.028 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:28.029 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:29.300 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:29.300 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:30.636 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:30.636 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:31.969 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:31.970 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:33.296 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:33.296 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:34.552 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:34.552 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:35.791 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:35.791 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:37.087 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:37.087 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:38.312 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:38.312 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:38.320 20055 20278 D sensors_hal_Time: time_service_sensor1_cb: msg_type 2 02-09 23:40:38.320 20055 20278 D sensors_hal_Time: time_service_sensor1_cb: Sn 24, msg Id 3, txn Id 0 02-09 23:40:38.321 20055 20278 D sensors_hal_Time: tsOffsetIs: Apps: 541446909830899; DSPS: 561936411; Offset : 524297971512082 (diff 11458 @0) 02-09 23:40:39.696 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:39.696 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:40.979 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:40.979 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: wlan_scan_wake, act_since: 6, act_cnt: 1645, ev_cnt: 1645, wakeup_cnt: 152 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: wlan_oob_irq_wake, act_since: 198, act_cnt: 11789, ev_cnt: 11867, wakeup_cnt: 637 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: wlan_rx_wake, act_since: 324, act_cnt: 40802, ev_cnt: 1752587, wakeup_cnt: 119066 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: wlan_wake, act_since: 500, act_cnt: 1632044, ev_cnt: 1632044, wakeup_cnt: 111316 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: ipc0000017e_Thread-3, act_since: 2, act_cnt: 94, ev_cnt: 94, wakeup_cnt: 8 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: ipc0000017a_Binder:20055_14, act_since: 15, act_cnt: 100966, ev_cnt: 121028, wakeup_cnt: 4129 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: eventpoll, act_since: 1, act_cnt: 392, ev_cnt: 392, wakeup_cnt: 66 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: ipc00000172_Loc_hal_worker, act_since: 7, act_cnt: 10299, ev_cnt: 26334, wakeup_cnt: 2237 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: ipc00000170_system_server, act_since: 10, act_cnt: 927, ev_cnt: 927, wakeup_cnt: 72 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: ipc0000016f_system_server, act_since: 58, act_cnt: 15105, ev_cnt: 15105, wakeup_cnt: 1541 02-09 23:40:40.981 20055 20170 I libsuspend: ws name: event2, act_since: 1, act_cnt: 469, ev_cnt: 469, wakeup_cnt: 29 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: event1, act_since: 43, act_cnt: 2426429, ev_cnt: 2426429, wakeup_cnt: 175733 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: [timerfd], act_since: 42, act_cnt: 13704, ev_cnt: 13704, wakeup_cnt: 1002 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: [timerfd], act_since: 11, act_cnt: 7454, ev_cnt: 7454, wakeup_cnt: 1002 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: eventpoll, act_since: 16, act_cnt: 37661, ev_cnt: 43374, wakeup_cnt: 1955 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: ipc00000154_system_server, act_since: 4, act_cnt: 1463, ev_cnt: 1463, wakeup_cnt: 72 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: ipc00000153_system_server, act_since: 4, act_cnt: 1075, ev_cnt: 1079, wakeup_cnt: 57 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: PowerManagerService.Broadcasts, act_since: 2, act_cnt: 1149, ev_cnt: 1149, wakeup_cnt: 58 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: SensorService_wakelock, act_since: 6, act_cnt: 4332, ev_cnt: 4332, wakeup_cnt: 253 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: sensor_ind, act_since: 1, act_cnt: 4344, ev_cnt: 4344, wakeup_cnt: 253 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: PowerManagerService.WakeLocks, act_since: 206, act_cnt: 44423, ev_cnt: 44424, wakeup_cnt: 3023 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: KeyEvents, act_since: 30, act_cnt: 1870346, ev_cnt: 1870346, wakeup_cnt: 122017 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: PowerManagerService.Display, act_since: 16, act_cnt: 1362, ev_cnt: 1363, wakeup_cnt: 95 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: ipc00000074_fdal-lo, act_since: 2, act_cnt: 2773, ev_cnt: 3197, wakeup_cnt: 195 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: ipc00000062_atd, act_since: 1, act_cnt: 171, ev_cnt: 171, wakeup_cnt: 13 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: ipc0000004b_rild, act_since: 1, act_cnt: 1195, ev_cnt: 1195, wakeup_cnt: 139 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: ipc0000004a_rild, act_since: 1, act_cnt: 31, ev_cnt: 32, wakeup_cnt: 5 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: NETLINK, act_since: 32, act_cnt: 126608, ev_cnt: 126608, wakeup_cnt: 56040 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: eventpoll, act_since: 465, act_cnt: 126607, ev_cnt: 126932, wakeup_cnt: 56323 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: mpss_IPCRTR, act_since: 1, act_cnt: 81600, ev_cnt: 83061, wakeup_cnt: 6666 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: NETLINK, act_since: 42, act_cnt: 128721, ev_cnt: 128721, wakeup_cnt: 57754 02-09 23:40:40.982 20055 20170 I libsuspend: ws name: [timerfd], act_since: 1, act_cnt: 1804, ev_cnt: 1804, wakeup_cnt: 161 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: ipc00000020_rmt_storage, act_since: 1, act_cnt: 149, ev_cnt: 149, wakeup_cnt: 7 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: eventpoll, act_since: 332, act_cnt: 130351, ev_cnt: 131615, wakeup_cnt: 58227 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: dsps_IPCRTR, act_since: 5, act_cnt: 416777, ev_cnt: 417624, wakeup_cnt: 60202 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: ipc00000017_kworker/1:3, act_since: 40, act_cnt: 2869, ev_cnt: 2869, wakeup_cnt: 275 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: anx_wlock, act_since: 1, act_cnt: 54, ev_cnt: 192, wakeup_cnt: 25 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: bluetooth_pm, act_since: 17, act_cnt: 3596, ev_cnt: 3605, wakeup_cnt: 268 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: qpnp_fg_sanity_check, act_since: 732, act_cnt: 44926, ev_cnt: 44926, wakeup_cnt: 4469 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: qpnp_fg_slope_limit, act_since: 299, act_cnt: 24012, ev_cnt: 24012, wakeup_cnt: 2384 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: qpnp_fg_update_sram, act_since: 124, act_cnt: 8138, ev_cnt: 8138, wakeup_cnt: 812 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: qpnp_fg_update_temp, act_since: 67, act_cnt: 22651, ev_cnt: 22651, wakeup_cnt: 2253 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: qpnp_fg_memaccess, act_since: 117, act_cnt: 165201, ev_cnt: 165201, wakeup_cnt: 16019 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: smb1351, act_since: 48, act_cnt: 74759, ev_cnt: 74759, wakeup_cnt: 5075 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: touch_lpwg, act_since: 9, act_cnt: 509, ev_cnt: 707, wakeup_cnt: 35 02-09 23:40:40.983 20055 20170 I libsuspend: ws name: alarmtimer, act_since: 119, act_cnt: 546, ev_cnt: 546, wakeup_cnt: 546 02-09 23:40:42.326 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:42.326 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:43.654 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:43.655 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:44.977 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:44.977 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:46.302 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:46.302 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:47.531 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:47.531 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:48.917 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:48.918 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:50.216 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:50.216 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:51.528 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:51.528 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:52.263 20055 20278 D sensors_hal_Time: time_service_sensor1_cb: msg_type 2 02-09 23:40:52.263 20055 20278 D sensors_hal_Time: time_service_sensor1_cb: Sn 24, msg Id 3, txn Id 0 02-09 23:40:52.264 20055 20278 D sensors_hal_Time: tsOffsetIs: Apps: 541460852017919; DSPS: 562393269; Offset : 524297971511374 (diff 37813 @0) 02-09 23:40:53.594 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:53.594 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:54.939 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:54.939 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:56.235 19671 20224 V Netd : unexpected event from subsystem power_supply 02-09 23:40:56.235 19671 20224 V Netd : unexpected event from subsystem power_supply Did this work before? N/A Is it a problem with Flash or HTML5? N/A Does this work in other browsers? Yes Chrome version: 55.0.2883.91 Channel: stable OS Version: 7.0 Flash Version: Shockwave Flash 24.0 r0 Contents of chrome://gpu:
,
Feb 24 2017
hkskoglund@gmail.com, which Android device are you using? Dale, I assign this bug to you to start this bug. Please re-assign appropriately.
,
Feb 24 2017
I'm using an LG G5. Is the PM (power manager?) going into some kind of look
with entry/exit (ref. dmesg)?
logcat:
02-24 11:58:37.603 14330 14724 D AudioTrack: start(): 0xc2de9000
02-24 11:58:37.604 14330 14408 D AudioTrack: stop(): 0xc2de9000
02-24 11:58:37.605 14330 14408 D AudioTrackShared: this(0xc3a47b80),
mCblk(0xc0880000), front(17693704), mIsOut 1, interrupt() FUTEX_WAKE
02-24 11:58:37.606 984 1399 V AudioFlinger_Tracks: stop(4096), calling
pid 14330
02-24 11:58:37.607 984 1399 V AudioFlinger_Tracks: not stopping/stopped
=> stopping/stopped (4096) on thread 0xea503d00
02-24 11:58:37.608 984 15015 V AudioFlinger_Tracks: flush(4096)
02-24 11:58:37.619 984 1366 V audio_hw_primary: out_get_latency: Latency
8
02-24 11:58:37.619 984 1366 V AudioFlinger_Tracks:
presentationComplete() mPresentationCompleteFrames 0 framesWritten 17696640
02-24 11:58:37.620 984 1366 V AudioFlinger_Tracks:
presentationComplete() reset: mPresentationCompleteFrames 17697024
audioHalFrames 384
02-24 11:58:37.639 984 1366 V audio_hw_primary: out_get_latency: Latency
8
02-24 11:58:37.639 984 1366 V AudioFlinger_Tracks:
presentationComplete() mPresentationCompleteFrames 17697024 framesWritten
17697600
02-24 11:58:37.639 984 1366 D AudioTrackShared: this(0xebcac540),
mCblk(0xe9280000), front(17694088), mIsOut 1, setStreamEndDone() FUTEX_WAKE
02-24 11:58:37.643 984 1366 V AudioFlinger_Threads: removeTracks_l
removing track on session 49
02-24 11:58:37.654 984 1366 V AudioPolicyService: AudioCommandThread()
adding stop output 21
02-24 11:58:37.654 984 1366 V AudioPolicyService: inserting command: 6
at index 0, num commands 0
02-24 11:58:37.654 984 1239 V AudioPolicyService: AudioCommandThread()
processing stop output 21
02-24 11:58:37.655 984 1239 V APM_AudioPolicyManager: stopOutput()
output 21, stream 3, session 49
02-24 11:58:37.655 984 1239 V APM::AudioOutputDescriptor:
changeRefCount() stream 3, count 0
02-24 11:58:37.655 984 1239 V AudioPolicyManagerEx: getNewOutputDevice()
selected device 0
02-24 11:58:37.655 984 1239 V APM_AudioPolicyManager: setOutputDevice()
device 0000 delayMs 96
02-24 11:58:37.655 984 1239 V APM_AudioPolicyManager: setOutputDevice()
prevDevice 0x0008
02-24 11:58:37.656 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.656 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.656 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.656 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.656 984 1239 V APM::Devices: DeviceVector::refreshTypes()
mDeviceTypes 00000001
02-24 11:58:37.656 984 1239 V APM::Devices:
DeviceVector::getDevicesFromType() for type 1 found 0xebcc2000
02-24 11:58:37.656 984 1239 V APM::Devices: DeviceVector::refreshTypes()
mDeviceTypes 00000003
02-24 11:58:37.656 984 1239 V APM::Devices:
DeviceVector::getDevicesFromType() for type 2 found 0xebcc2140
02-24 11:58:37.656 984 1239 V APM::Devices: DeviceVector::refreshTypes()
mDeviceTypes 0000000b
02-24 11:58:37.656 984 1239 V APM::Devices:
DeviceVector::getDevicesFromType() for type 8 found 0xebcc23c0
02-24 11:58:37.656 984 1239 V APM::Devices: DeviceVector::refreshTypes()
mDeviceTypes 0001000b
02-24 11:58:37.656 984 1239 V APM::Devices:
DeviceVector::getDevicesFromType() for type 10000 found 0xebcc2b40
02-24 11:58:37.657 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.657 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.657 984 1239 V APM_AudioPolicyManager: setOutputDevice()
setting same device 0x0000 or null device
02-24 11:58:37.657 984 1239 D AudioPolicyManagerEx:
handleNotificationRoutingForStream()
02-24 11:58:37.657 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.657 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.657 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.657 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.658 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.658 984 1239 V APM::Devices: DeviceVector::refreshTypes()
mDeviceTypes 00000001
02-24 11:58:37.658 984 1239 V APM::Devices:
DeviceVector::getDevicesFromType() for type 1 found 0xebcc2000
02-24 11:58:37.658 984 1239 V APM::Devices: DeviceVector::refreshTypes()
mDeviceTypes 00000003
02-24 11:58:37.658 984 1239 V APM::Devices:
DeviceVector::getDevicesFromType() for type 2 found 0xebcc2140
02-24 11:58:37.658 984 1239 V APM::Devices: DeviceVector::refreshTypes()
mDeviceTypes 0000000b
02-24 11:58:37.658 984 1239 V APM::Devices:
DeviceVector::getDevicesFromType() for type 8 found 0xebcc23c0
02-24 11:58:37.658 984 1239 V APM::Devices: DeviceVector::refreshTypes()
mDeviceTypes 0001000b
02-24 11:58:37.658 984 1239 V APM::Devices:
DeviceVector::getDevicesFromType() for type 10000 found 0xebcc2b40
02-24 11:58:37.658 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.658 984 1239 V APM::Devices: DeviceVector::getDevice()
for type 00008000 address 0 found 0x0
02-24 11:58:37.659 984 1239 V AudioPolicyService: -AudioCommandThread 6
02-24 11:58:37.659 984 1239 V AudioPolicyService: AudioCommandThread()
going to sleep
02-24 11:58:37.801 1862 2283 E WifiStateMachine: LG_RSSI UPDATE newRssi =
-71
02-24 11:58:37.811 2446 2795 I NetworkController: onReceive:
intent=Intent { act=android.net.wifi.RSSI_CHANGED flg=0x4000010 (has
extras) }
02-24 11:58:37.812 2446 2795 I LGWifiStatusTracker: Wifi connected =
true, Wifi level = 1
02-24 11:58:37.812 2446 2795 I NetworkController.WifiSignalController:
Change in state to:
connected=true,enabled=true,level=1,inetCondition=1,iconGroup=IconGroup(Default
Wi-Fi
Icons),activityIn=true,activityOut=true,rssi=-71,,ssid="Skoglund-5GHz",isGigaWiFi=false,isAttWifiCall=false
02-24 11:58:38.807 1862 2283 E WifiStateMachine: LG_RSSI UPDATE newRssi =
-68
02-24 11:58:38.822 2446 2795 I NetworkController: onReceive:
intent=Intent { act=android.net.wifi.RSSI_CHANGED flg=0x4000010 (has
extras) }
02-24 11:58:38.823 2446 2795 I LGWifiStatusTracker: Wifi connected =
true, Wifi level = 2
02-24 11:58:38.824 2446 2795 I NetworkController.WifiSignalController:
Change in state to:
connected=true,enabled=true,level=2,inetCondition=1,iconGroup=IconGroup(Default
Wi-Fi
Icons),activityIn=true,activityOut=true,rssi=-68,,ssid="Skoglund-5GHz",isGigaWiFi=false,isAttWifiCall=false
02-24 11:58:40.623 984 1366 V AudioFlinger_Threads: Audio hardware
entering standby, mixer 0xea503d00, suspend count 0
02-24 11:58:40.624 984 1366 D audio_hw_primary: out_standby: enter:
stream (0xeb28bc00) usecase(12: audio-ull-playback)
02-24 11:58:40.696 984 1366 V audio_hw_primary: stop_output_stream:
enter: usecase(12: audio-ull-playback)
02-24 11:58:40.696 984 1366 V msm8996_platform_8996:
lge_reset_24bit_playback_msm8996
02-24 11:58:40.696 984 1366 W msm8996_platform_8996:
lge_reset_24bit_playback_msm8996: This case is not Offload usecase(12)
02-24 11:58:40.696 984 1366 V audio_hw_primary: disable_audio_route:
enter: usecase(12)
02-24 11:58:40.696 984 1366 V msm8974_platform_lge:
LGE_platform_add_backend_name: enter: 58
02-24 11:58:40.697 984 1366 D audio_hw_primary: disable_audio_route:
reset and update mixer path: audio-ull-playback headphones
02-24 11:58:40.700 984 1366 D soundtrigger:
audio_extn_sound_trigger_update_stream_status: uc_id 12 of type 0 for Event
2, with Raise=0
02-24 11:58:40.700 984 1366 V audio_hw_primary: disable_audio_route: exit
02-24 11:58:40.700 984 1366 D hardware_info: hw_info_append_hw_type :
device_name = headphones-aux
02-24 11:58:40.700 984 1366 D audio_hw_primary: disable_snd_device:
snd_device(58: headphones-aux)
02-24 11:58:40.724 984 1366 I soundtrigger:
audio_extn_sound_trigger_update_device_status: device 0x3a of type 0 for
Event 0, with Raise=0
02-24 11:58:40.724 984 1366 V audio_hw_primary: stop_output_stream:
exit: status(0)
02-24 11:58:40.724 984 1366 D audio_hw_primary: out_standby: exit
02-24 11:58:40.727 984 1366 V AudioFlinger_Threads: thread 0xea503d00
type 0 TID 1366 going to sleep
02-24 11:58:41.295 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.299 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.299 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.299 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.299 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.300 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.300 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.301 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.303 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.305 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.307 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.307 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.307 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.308 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.308 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.308 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.310 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.311 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.311 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.315 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.317 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.319 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.320 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.323 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.324 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.324 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.328 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.328 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.330 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.332 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.336 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.337 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.339 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.341 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.345 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:41.348 1056 2113 V Netd : unexpected event from subsystem
firmware
02-24 11:58:42.128 1056 2113 V Netd : unexpected event from subsystem
power_supply
02-24 11:58:42.128 1056 2113 V Netd : unexpected event from subsystem
power_supply
dmesg:
[ 3777.759571 / 02-24 11:57:09.842][3] pet_watchdog [enable : 1, jiffies :
4295315072, delay_time : 1000]
[ 3780.591234 / 02-24 11:57:12.672][2] CFG80211-INFO2)
wl_cfg80211_add_iw_ie : Previous IW IE is equals to current IE
[ 3780.591265 / 02-24 11:57:12.672][2] CFG80211-TRACE) __wl_cfg80211_scan :
START SCAN
[ 3780.591286 / 02-24 11:57:12.672][2] DHD Runtime PM Disabled
[ 3782.240844 / 02-24 11:57:14.322][0] i2c-msm-v2 75b5000.i2c: NACK: slave
not responding, ensure its powered: msgs(n:2 cur:0 tx) bc(rx:1 tx:1)
mode:FIFO slv_addr:0x1d MSTR_STS:0x0d1300c8 OPER:0x00000010
[ 3782.241041 / 02-24 11:57:14.322][0] SMB1351 smb1351_read_reg: i2c read
fail: can't read from 3a: -107
[ 3782.241090 / 02-24 11:57:14.322][0] SMB1351
smb1351_get_prop_batt_status: Couldn't read STATUS_4 rc = -107
[ 3782.241233 / 02-24 11:57:14.322][0] SMBCHG: lgcc_charger_reginfo: [C],
USB_PRESENT, WIRELESS_PRESENT, PARALLEL_STATUS, USB_TYPE, CABLE_INFO,
USBIN_VOL, BATT_TEMP, BATT_SOC, BATT_VOL, TOTAL_IUSB_SET, PMI_IUSB_AICL,
PMI_IBAT_SET, SMB_IBAT_SET, TOTAL_IBAT_NOW
[ 3782.241284 / 02-24 11:57:14.322][0] SMBCHG: lgcc_charger_reginfo: [I],
0, 0, 0,Absent, OPEN, 0, 246, 94, 4212621, 0, 1500000, 2000000, 1000000,
181731
[ 3784.396835 / 02-24 11:57:16.472][1] CFG80211-TRACE) wl_escan_handler :
SCAN COMPLETED: scanned AP count=8
[ 3784.401952 / 02-24 11:57:16.482][1] DHD Runtime PM Enabled
[ 3787.780014 / 02-24 11:57:19.862][2] pet_watchdog [enable : 1, jiffies :
4295316074, delay_time : 1000]
[ 3791.848072 / 02-24 11:57:23.922][0] [TM][I] XO, 28,PA0, 27,BD2,
27,VTS,297
[ 3797.799476 / 02-24 11:57:29.882][2] pet_watchdog [enable : 1, jiffies :
4295317076, delay_time : 1000]
[ 3803.533454 / 02-24 11:57:35.612][2] FG: update_sram_data: soc:[94],
soc_raw[8755], voltage:[4210333], ocv:[4221471], current:[184477],
batt_temp:[246], charge_raw [2182232 / 2757000]
[ 3807.819543 / 02-24 11:57:39.902][2] pet_watchdog [enable : 1, jiffies :
4295318078, delay_time : 1000]
[ 3817.839940 / 02-24 11:57:49.922][3] pet_watchdog [enable : 1, jiffies :
4295319080, delay_time : 1000]
[ 3827.859817 / 02-24 11:57:59.942][1] pet_watchdog [enable : 1, jiffies :
4295320082, delay_time : 1000]
[ 3833.610689 / 02-24 11:58:05.692][2] FG: update_sram_data: soc:[94],
soc_raw[8755], voltage:[4208196], ocv:[4221471], current:[188444],
batt_temp:[248], charge_raw [2180740 / 2757000]
[ 3837.879891 / 02-24 11:58:09.962][2] pet_watchdog [enable : 1, jiffies :
4295321084, delay_time : 1000]
[ 3842.081151 / 02-24 11:58:14.162][0] i2c-msm-v2 75b5000.i2c: NACK: slave
not responding, ensure its powered: msgs(n:2 cur:0 tx) bc(rx:1 tx:1)
mode:FIFO slv_addr:0x1d MSTR_STS:0x0d1300c8 OPER:0x00000010
[ 3842.081347 / 02-24 11:58:14.162][0] SMB1351 smb1351_read_reg: i2c read
fail: can't read from 3a: -107
[ 3842.081396 / 02-24 11:58:14.162][0] SMB1351
smb1351_get_prop_batt_status: Couldn't read STATUS_4 rc = -107
[ 3842.081548 / 02-24 11:58:14.162][0] SMBCHG: lgcc_charger_reginfo: [C],
USB_PRESENT, WIRELESS_PRESENT, PARALLEL_STATUS, USB_TYPE, CABLE_INFO,
USBIN_VOL, BATT_TEMP, BATT_SOC, BATT_VOL, TOTAL_IUSB_SET, PMI_IUSB_AICL,
PMI_IBAT_SET, SMB_IBAT_SET, TOTAL_IBAT_NOW
[ 3842.081599 / 02-24 11:58:14.162][0] SMBCHG: lgcc_charger_reginfo: [I],
0, 0, 0,Absent, OPEN, 0, 248, 94, 4208196, 0, 1500000, 2000000, 1000000,
188444
[ 3847.899377 / 02-24 11:58:19.982][0] pet_watchdog [enable : 1, jiffies :
4295322086, delay_time : 1000]
[ 3852.007271 / 02-24 11:58:24.082][0] [TM][I] XO, 28,PA0, 27,BD2,
27,VTS,297
[ 3857.919304 / 02-24 11:58:30.002][0] pet_watchdog [enable : 1, jiffies :
4295323088, delay_time : 1000]
[ 3863.691273 / 02-24 11:58:35.772][2] FG: update_sram_data: soc:[94],
soc_raw[8755], voltage:[4208502], ocv:[4221471], current:[174712],
batt_temp:[246], charge_raw [2179188 / 2757000]
[ 3867.939681 / 02-24 11:58:40.022][1] pet_watchdog [enable : 1, jiffies :
4295324090, delay_time : 1000]
[ 3869.149640 / 02-24 11:58:41.232][0] PM: suspend entry 2017-02-24
10:58:41.236174317 UTC
[ 3869.149719 / 02-24 11:58:41.232][0] PM: Check and Syncing filesystems
...
[ 3869.204896 / 02-24 11:58:41.282][2] PM: done.
[ 3869.262599 / 02-24 11:58:41.352][0] Freezing user space processes ...
[ 3869.269458 / 02-24 11:58:41.352][0] Error: returning -512 value
[ 3869.269490 / 02-24 11:58:41.352][1] PM: Wakeup pending, aborting suspend
[ 3869.269618 / 02-24 11:58:41.352][1] active wakeup source: wlan_rx_wake
pending_count: 1
[ 3869.269691 / 02-24 11:58:41.352][1]
[ 3869.269707 / 02-24 11:58:41.352][1] Freezing of tasks aborted after
0.007 seconds
[ 3869.269724 / 02-24 11:58:41.352][1] Restarting tasks ... done.
[ 3869.277267 / 02-24 11:58:41.352][1] PM: suspend exit 2017-02-24
10:58:41.363820411 UTC
[ 3869.779712 / 02-24 11:58:41.862][0] PM: suspend entry 2017-02-24
10:58:41.866245307 UTC
[ 3869.779788 / 02-24 11:58:41.862][0] PM: Check and Syncing filesystems
...
[ 3869.818555 / 02-24 11:58:41.892][0] PM: done.
[ 3869.821447 / 02-24 11:58:41.912][1] Freezing user space processes ...
[ 3869.830588 / 02-24 11:58:41.912][1] Error: returning -512 value
[ 3869.835185 / 02-24 11:58:41.912][0] (elapsed 0.013 seconds) done.
[ 3869.835236 / 02-24 11:58:41.922][0] Freezing remaining freezable tasks
... (elapsed 0.009 seconds) done.
[ 3869.844415 / 02-24 11:58:41.922][0] Suspending console(s) (use
no_console_suspend to debug)
[ 3869.847159 / 02-24 11:58:41.922][0] DHD Runtime PM Disabled
[ 3869.866689 / 02-24 11:58:41.942][2] dhdpcie_suspend_dev: Enter
[ 3869.890283 / 02-24 11:58:41.972][2] msm_pcie_disable: PCIe: Assert the
reset of endpoint of RC0.
[ 3869.895503 / 02-24 11:58:41.972][2] [Touch] touch_spi_pm_suspend :
DEV_PM_SUSPEND
[ 3869.895551 / 02-24 11:58:41.972][2] fpc1020 spi8.1: fpc1020_suspend do
nothing
[ 3869.895781 / 02-24 11:58:41.972][2] hdmi_tx_event_handler: event =
MDSS_EVENT_SUSPEND suspend=0, hpd_feature=0
[ 3869.897594 / 02-24 11:58:41.972][2] bluetooth_pm_suspend:
[ 3869.901916 / 02-24 11:58:41.982][2] qcom,qpnp-haptic qpnp-haptic-24:
qpnp_hap_play: on = 0, voltage = 3016
[ 3869.919735 / 02-24 11:58:42.002][2] PM: Wakeup pending, aborting suspend
[ 3869.919933 / 02-24 11:58:42.002][2] last active wakeup source:
ipc00000015_kworker/0:3 pending_count: 1
[ 3869.920000 / 02-24 11:58:42.002][2] PM: Some devices failed to suspend,
or early wake event detected
[ 3869.939226 / 02-24 11:58:42.022][0] pci 0000:00:00.0: Refused to change
power state, currently in D3
[ 3869.942338 / 02-24 11:58:42.022][0] async_resume: host0 (scsi) 22078
[ 3869.942363 / 02-24 11:58:42.022][0] dpm_resume: 624000.ufshc (ufshcd)
21913
[ 3869.942530 / 02-24 11:58:42.022][1] async_resume: target0:0:0 (scsi)
22088
[ 3869.942553 / 02-24 11:58:42.022][0] async_resume: 0:0:0:49488 (scsi)
22028
[ 3869.942583 / 02-24 11:58:42.022][2] async_resume: 0:0:0:0 (sd) 21931
[ 3869.942593 / 02-24 11:58:42.022][1] async_resume: 0:0:0:49456 (scsi)
22013
[ 3869.942687 / 02-24 11:58:42.022][1] async_resume: 0:0:0:49476 (scsi)
22085
[ 3869.942702 / 02-24 11:58:42.022][3] async_resume: 0:0:0:6 (sd) 21523
[ 3869.942708 / 02-24 11:58:42.022][0] async_resume: 0:0:0:3 (sd) 21750
[ 3869.942719 / 02-24 11:58:42.022][2] async_resume: 0:0:0:2 (sd) 21832
[ 3869.942808 / 02-24 11:58:42.022][2] async_resume: 0:0:0:5 (sd) 21697
[ 3869.942819 / 02-24 11:58:42.022][3] async_resume: 0:0:0:4 (sd) 21784
[ 3869.942888 / 02-24 11:58:42.022][0] spi_master spi0: queue restart failed
[ 3869.942910 / 02-24 11:58:42.022][0] dpm_run_callback():
platform_pm_resume+0x0/0x54 returns -16
[ 3869.942920 / 02-24 11:58:42.022][3] async_resume: 0:0:0:1 (sd) 22100
[ 3869.942927 / 02-24 11:58:42.022][0] PM: Device 7575000.spi failed to
resume: error -16
[ 3869.944642 / 02-24 11:58:42.022][0] gpio_keys_gpio_report_event:
code(115) state(0)
[ 3869.944654 / 02-24 11:58:42.022][0] gpio_keys_gpio_report_event:
code(222) state(0)
[ 3869.944717 / 02-24 11:58:42.022][0] spi_master spi8: queue restart failed
[ 3869.944736 / 02-24 11:58:42.022][0] dpm_run_callback():
platform_pm_resume+0x0/0x54 returns -16
[ 3869.944742 / 02-24 11:58:42.022][0] PM: Device 75b7000.spi failed to
resume: error -16
[ 3869.944788 / 02-24 11:58:42.022][0] spi_master spi12: queue restart
failed
[ 3869.944796 / 02-24 11:58:42.022][0] dpm_run_callback():
platform_pm_resume+0x0/0x54 returns -16
[ 3869.944802 / 02-24 11:58:42.022][0] PM: Device 75ba000.spi failed to
resume: error -16
[ 3869.944816 / 02-24 11:58:42.022][0] bluetooth_pm_resume:
[ 3869.945366 / 02-24 11:58:42.022][0] hdmi_tx_event_handler: event =
MDSS_EVENT_RESUME suspend=0, hpd_feature=0
[ 3869.945448 / 02-24 11:58:42.022][0] fpc1020 spi8.1: fpc1020_resume do
nothing
[ 3869.945457 / 02-24 11:58:42.022][0] [Touch] touch_spi_pm_resume :
DEV_PM_RESUME
[ 3869.946027 / 02-24 11:58:42.022][3] msm-dwc3 6a00000.ssusb:
dwc3_resume_work: dwc3 resume work
[ 3869.946037 / 02-24 11:58:42.022][3] dwc3_ext_event_notify
[ 3869.946127 / 02-24 11:58:42.022][3] msm-dwc3 6a00000.ssusb: b_idle
state, vbus:offline
[ 3869.946154 / 02-24 11:58:42.022][3] msm-dwc3 6a00000.ssusb:
dwc3_msm_power_set_property_usb: charger type: UNKNOWN_CHARGER
[ 3869.946318 / 02-24 11:58:42.022][3] vote: name[SMBCHG: battchg_suspend],
client_id[1], state[0], val[0]
[ 3869.946328 / 02-24 11:58:42.022][3] SMBCHG: battchg_suspend: votes
unchanged; skipping
[ 3869.946430 / 02-24 11:58:42.022][3] SMBCHG: smbchg_stay_awake: staying
awake: 0x02 (bit 2)
[ 3869.946444 / 02-24 11:58:42.022][3] SMBCHG: smbchg_vfloat_adjust_check:
Starting vfloat adjustments
[ 3869.946595 / 02-24 11:58:42.022][3] dwc3_msm_power_get_property_usb:
POWER_SUPPLY_PROP_VOLTAGE_NOW
[ 3869.946826 / 02-24 11:58:42.022][3] SMBCHG: smbchg_relax: relaxing: 0x00
(bit 2)
[ 3869.959127 / 02-24 11:58:42.042][0] pci 0000:00:00.0: Refused to change
power state, currently in D3
[ 3869.959171 / 02-24 11:58:42.042][0] async_resume: 0000:00:00.0 (pci)
37993
[ 3869.979221 / 02-24 11:58:42.062][1] pcieh 0000:01:00.0: Refused to
change power state, currently in D3
[ 3869.979351 / 02-24 11:58:42.062][1] msm_pcie_enable: PCIe: Assert the
reset of endpoint of RC0.
[ 3869.983864 / 02-24 11:58:42.062][1] msm_pcie_enable: PCIe RC0 PHY is
ready!
[ 3869.994034 / 02-24 11:58:42.072][1] msm_pcie_enable: PCIe: Release the
reset of endpoint of RC0.
[ 3870.029686 / 02-24 11:58:42.112][1] msm_pcie_enable: PCIe RC0 link
initialized
[ 3870.029931 / 02-24 11:58:42.112][1] dhdpcie_resume_dev: Enter
[ 3870.030503 / 02-24 11:58:42.112][1] dpm_resume: 0000:01:00.0 (pcieh)
84518
[ 3870.032509 / 02-24 11:58:42.112][0] PM: resume of devices complete after
112.482 msecs
[ 3870.032550 / 02-24 11:58:42.112][0] resume_time:02-24 11:58:42.112 112
[ 3870.033013 / 02-24 11:58:42.112][0] DHD Runtime PM Enabled
[ 3870.035680 / 02-24 11:58:42.122][1] Restarting tasks ... done.
[ 3870.049717 / 02-24 11:58:42.132][1] PM: suspend exit 2017-02-24
10:58:42.136272130 UTC
[ 3870.050431 / 02-24 11:58:42.132][0] healthd: battery l=94 v=4208502
t=24.6 h=2 st=3 c=174 chg=
[ 3870.050476 / 02-24 11:58:42.132][0] healthd: battery l=94 v=4208502
t=24.6 h=2 st=3 c=174 chg=
[ 3870.050507 / 02-24 11:58:42.132][0] healthd: send_already = 0
[ 3870.053424 / 02-24 11:58:42.132][0] healthd: battery l=94 v=4208502
t=24.6 h=2 st=3 c=174 chg=
[ 3870.053474 / 02-24 11:58:42.132][0] healthd: battery l=94 v=4208502
t=24.6 h=2 st=3 c=174 chg=
[ 3870.053791 / 02-24 11:58:42.132][0] healthd: send_already = 0
[ 3870.060835 / 02-24 11:58:42.142][0] i2c-msm-v2 75b5000.i2c: NACK: slave
not responding, ensure its powered: msgs(n:2 cur:0 tx) bc(rx:1 tx:1)
mode:FIFO slv_addr:0x1d MSTR_STS:0x0d1300c8 OPER:0x00000010
[ 3870.060925 / 02-24 11:58:42.142][3] SMB1351 smb1351_read_reg: i2c read
fail: can't read from 36: -107
[ 3870.060947 / 02-24 11:58:42.142][3] SMB1351 smb1351_get_usb_chg_current:
read STATUS_0 failed, rc=-107
[ 3870.060960 / 02-24 11:58:42.142][3] SMB1351
smb1351_parallel_get_property: Get ICL result failed, rc=-107
[ 3870.060979 / 02-24 11:58:42.142][3] power_supply usb-parallel: driver
failed to report `current_max' property: -107
[ 3870.061315 / 02-24 11:58:42.142][0] i2c-msm-v2 75b5000.i2c: NACK: slave
not responding, ensure its powered: msgs(n:2 cur:0 tx) bc(rx:1 tx:1)
mode:FIFO slv_addr:0x1d MSTR_STS:0x0d1300c8 OPER:0x00000010
[ 3870.061378 / 02-24 11:58:42.142][3] SMB1351 smb1351_read_reg: i2c read
fail: can't read from 47: -107
[ 3870.061394 / 02-24 11:58:42.142][3] SMB1351
smb1351_is_input_current_limited: Failed to read IRQ_H_REG for ICL status:
-107
[ 3870.064085 / 02-24 11:58:42.142][0] i2c-msm-v2 75b5000.i2c: NACK: slave
not responding, ensure its powered: msgs(n:2 cur:0 tx) bc(rx:1 tx:1)
mode:FIFO slv_addr:0x1d MSTR_STS:0x0d1300c8 OPER:0x00000010
[ 3870.064727 / 02-24 11:58:42.142][3] SMB1351 smb1351_read_reg: i2c read
fail: can't read from 36: -107
[ 3870.064753 / 02-24 11:58:42.142][3] SMB1351 smb1351_get_usb_chg_current:
read STATUS_0 failed, rc=-107
[ 3870.064766 / 02-24 11:58:42.142][3] SMB1351
smb1351_parallel_get_property: Get ICL result failed, rc=-107
[ 3870.064786 / 02-24 11:58:42.142][3] power_supply usb-parallel: driver
failed to report `current_max' property: -107
[ 3870.065481 / 02-24 11:58:42.142][0] i2c-msm-v2 75b5000.i2c: NACK: slave
not responding, ensure its powered: msgs(n:2 cur:0 tx) bc(rx:1 tx:1)
mode:FIFO slv_addr:0x1d MSTR_STS:0x0d1300c8 OPER:0x00000010
[ 3870.066178 / 02-24 11:58:42.142][3] SMB1351 smb1351_read_reg: i2c read
fail: can't read from 47: -107
[ 3870.066201 / 02-24 11:58:42.142][3] SMB1351
smb1351_is_input_current_limited: Failed to read IRQ_H_REG for ICL status:
-107
[ 3870.151008 / 02-24 11:58:42.232][2] PM: suspend entry 2017-02-24
10:58:42.237549786 UTC
[ 3870.151067 / 02-24 11:58:42.232][2] PM: Check and Syncing filesystems
...
[ 3870.184448 / 02-24 11:58:42.262][2] PM: done.
[ 3870.186333 / 02-24 11:58:42.272][3] Freezing user space processes ...
[ 3870.193453 / 02-24 11:58:42.272][3] Error: returning -512 value
[ 3870.193519 / 02-24 11:58:42.272][2] PM: Wakeup pending, aborting suspend
[ 3870.193627 / 02-24 11:58:42.272][2] active wakeup source: wlan_rx_wake
pending_count: 2
[ 3870.193695 / 02-24 11:58:42.272][2]
[ 3870.193706 / 02-24 11:58:42.272][2] Freezing of tasks aborted after
0.007 seconds
[ 3870.193720 / 02-24 11:58:42.282][2] Restarting tasks ... done.
[ 3870.201097 / 02-24 11:58:42.282][2] PM: suspend exit 2017-02-24
10:58:42.287653692 UTC
[ 3871.081385 / 02-24 11:58:43.162][2] PM: suspend entry 2017-02-24
10:58:43.167919056 UTC
[ 3871.081462 / 02-24 11:58:43.162][2] PM: Check and Syncing filesystems
...
[ 3871.127763 / 02-24 11:58:43.202][2] PM: done.
[ 3871.130335 / 02-24 11:58:43.212][0] Freezing user space processes ...
[ 3871.138878 / 02-24 11:58:43.212][0] Error: returning -512 value
[ 3871.142900 / 02-24 11:58:43.222][2] (elapsed 0.012 seconds) done.
[ 3871.142946 / 02-24 11:58:43.232][2] Freezing remaining freezable tasks
... (elapsed 0.007 seconds) done.
[ 3871.150832 / 02-24 11:58:43.232][2] Suspending console(s) (use
no_console_suspend to debug)
[ 3871.153144 / 02-24 11:58:43.232][2] DHD Runtime PM Disabled
[ 3871.163278 / 02-24 11:58:43.242][2] dhdpcie_suspend_dev: Enter
[ 3871.192019 / 02-24 11:58:43.272][2] msm_pcie_disable: PCIe: Assert the
reset of endpoint of RC0.
[ 3871.195786 / 02-24 11:58:43.272][2] [Touch] touch_spi_pm_suspend :
DEV_PM_SUSPEND
[ 3871.195817 / 02-24 11:58:43.272][2] fpc1020 spi8.1: fpc1020_suspend do
nothing
[ 3871.195961 / 02-24 11:58:43.272][2] hdmi_tx_event_handler: event =
MDSS_EVENT_SUSPEND suspend=0, hpd_feature=0
[ 3871.197133 / 02-24 11:58:43.272][2] bluetooth_pm_suspend:
[ 3871.200219 / 02-24 11:58:43.282][2] qcom,qpnp-haptic qpnp-haptic-24:
qpnp_hap_play: on = 0, voltage = 3016
[ 3871.218585 / 02-24 11:58:43.292][2] PM: suspend of devices complete
after 64.922 msecs
[ 3871.223714 / 02-24 11:58:43.302][2] PM: late suspend of devices complete
after 5.047 msecs
[ 3871.229995 / 02-24 11:58:43.312][2] PM: noirq suspend of devices
complete after 6.219 msecs
[ 3871.230047 / 02-24 11:58:43.312][2] Disabling non-boot CPUs ...
2017-02-24 1:53 GMT+01:00 yini… via monorail <
monorail+v2.1273159690@chromium.org>:
,
Feb 24 2017
What version of CHrome are you on?
,
Feb 25 2017
I'm running chrome stable 56.0.2924.87. It seems like "Thread-9" in com.android.chrome is reacting to something and stops AudioTrack It may be unrelated to doze, since the same issue also appears with USB charing enabled. [henning@satellite ~]$ adb shell ps -t | grep u0_a91 (new instance of chrome -> new pids) u0_a91 20580 881 1566524 168396 SyS_epoll_ 0000000000 S com.android.chrome u0_a91 20585 20580 1566524 168396 futex_wait 0000000000 S Jit thread pool u0_a91 20586 20580 1566524 168396 do_sigtime 0000000000 S Signal Catcher u0_a91 20587 20580 1566524 168396 futex_wait 0000000000 S ReferenceQueueD u0_a91 20588 20580 1566524 168396 futex_wait 0000000000 S FinalizerDaemon u0_a91 20589 20580 1566524 168396 futex_wait 0000000000 S FinalizerWatchd u0_a91 20590 20580 1566524 168396 futex_wait 0000000000 S HeapTaskDaemon u0_a91 20591 20580 1566524 168396 binder_thr 0000000000 S Binder:20580_1 u0_a91 20592 20580 1566524 168396 binder_thr 0000000000 S Binder:20580_2 u0_a91 20620 20580 1566524 168396 SyS_epoll_ 0000000000 S RenderThread u0_a91 20637 20580 1566524 168396 binder_thr 0000000000 S Binder:20580_3 u0_a91 20638 20580 1566524 168396 SyS_epoll_ 0000000000 S DnsConfigServic u0_a91 20641 20580 1566524 168396 futex_wait 0000000000 S Thread-4 u0_a91 20642 20580 1566524 168396 futex_wait 0000000000 S Thread-6 u0_a91 20643 20580 1566524 168396 binder_thr 0000000000 S Binder:20580_4 u0_a91 20644 20580 1566524 168396 futex_wait 0000000000 S Chrome_DBThread u0_a91 20645 20580 1566524 168396 SyS_epoll_ 0000000000 S Chrome_FileThre u0_a91 20646 20580 1566524 168396 futex_wait 0000000000 S Chrome_FileUser u0_a91 20647 20580 1566524 168396 futex_wait 0000000000 S Chrome_ProcessL u0_a91 20648 20580 1566524 168396 futex_wait 0000000000 S Chrome_CacheThr u0_a91 20649 20580 1566524 168396 SyS_epoll_ 0000000000 S Thread-5 u0_a91 20650 20580 1566524 168396 futex_wait 0000000000 S IndexedDB u0_a91 20651 20580 1566524 168396 futex_wait 0000000000 S Thread-9 u0_a91 20652 20580 1566524 168396 futex_wait 0000000000 S GAC_Executor[0] u0_a91 20653 20580 1566524 168396 futex_wait 0000000000 S Thread-22 u0_a91 20666 20580 1566524 168396 futex_wait 0000000000 S Thread-8 u0_a91 20669 20580 1566524 168396 futex_wait 0000000000 S Thread-7 u0_a91 20684 20580 1566524 168396 futex_wait 0000000000 S SimpleCacheWork u0_a91 20686 20580 1566524 168396 SyS_epoll_ 0000000000 S ConnectivityMan u0_a91 20688 20580 1566524 168396 futex_wait 0000000000 S pool-3-thread-1 u0_a91 20689 20580 1566524 168396 futex_wait 0000000000 S GAC_Executor[1] u0_a91 20692 20580 1566524 168396 futex_wait 0000000000 S Thread-14 u0_a91 20694 20580 1566524 168396 futex_wait 0000000000 S hwuiTask1 u0_a91 20695 20580 1566524 168396 futex_wait 0000000000 S hwuiTask2 u0_a91 20728 20580 1566524 168396 futex_wait 0000000000 S SimpleCacheWork u0_a91 20730 20580 1566524 168396 inotify_re 0000000000 S FileObserver u0_a91 20732 20580 1566524 168396 SyS_epoll_ 0000000000 S InputConnection u0_a91 20738 20580 1566524 168396 futex_wait 0000000000 S Thread-16 u0_a91 20745 20580 1566524 168396 futex_wait 0000000000 S pool-1-thread-1 u0_a91 20767 20580 1566524 168396 futex_wait 0000000000 S SimpleCacheWork u0_a91 20782 20580 1566524 168396 futex_wait 0000000000 S SimpleCacheWork u0_a91 20829 20580 1566524 168396 SyS_epoll_ 0000000000 S Thread-18 u0_a91 20872 20580 1566524 168396 SyS_epoll_ 0000000000 S Gservices u0_a91 20878 20580 1566524 168396 futex_wait 0000000000 S Okio Watchdog u0_a91 20886 20580 1566524 168396 futex_wait 0000000000 S SimpleCacheWork u0_a91 23629 20580 1566524 168396 binder_thr 0000000000 S Binder:20580_5 u0_a91 23733 20580 1566524 168396 SyS_epoll_ 0000000000 S DeviceMotionAnd u0_a91 24103 20580 1566524 168396 futex_wait 0000000000 S Thread-28 u0_a91 24209 20580 1566524 168396 futex_wait 0000000000 S LevelDBEnv u0_a91 24296 20580 1566524 168396 binder_thr 0000000000 S Binder:20580_6 u0_a91 13093 20580 1566524 168396 futex_wait 0000000000 S Thread-71 u0_a91 13095 20580 1566524 168396 futex_wait 0000000000 S WorkerPool/1309 u0_a91 20654 881 1347428 63596 SyS_epoll_ 0000000000 S com.android.chrome:privileged_process0 u0_a91 20659 20654 1347428 63596 futex_wait 0000000000 S Jit thread pool u0_a91 20660 20654 1347428 63596 do_sigtime 0000000000 S Signal Catcher u0_a91 20661 20654 1347428 63596 futex_wait 0000000000 S ReferenceQueueD u0_a91 20662 20654 1347428 63596 futex_wait 0000000000 S FinalizerDaemon u0_a91 20663 20654 1347428 63596 futex_wait 0000000000 S FinalizerWatchd u0_a91 20664 20654 1347428 63596 futex_wait 0000000000 S HeapTaskDaemon u0_a91 20665 20654 1347428 63596 binder_thr 0000000000 S Binder:20654_1 u0_a91 20667 20654 1347428 63596 binder_thr 0000000000 S Binder:20654_2 u0_a91 20675 20654 1347428 63596 SyS_epoll_ 0000000000 S CrGpuMain u0_a91 20691 20654 1347428 63596 futex_wait 0000000000 S Watchdog u0_a91 20693 20654 1347428 63596 SyS_epoll_ 0000000000 S Chrome_ChildIOT u0_a91 12024 20654 1347428 63596 futex_wait 0000000000 S media 2017-02-24 18:45 GMT+01:00 dalecur… via monorail < monorail+v2.2801085551@chromium.org>:
,
Feb 27 2017
I tried remote chrome tracing. Maybe its the ChildProcessMsg_SetProcessBackgrounded thats stopping audio after awhile? Its not possible to resume podcast after its stopped (pause icon is still displayed after 6 min:49 seconds) 2017-02-25 13:39 GMT+01:00 Henning Skoglund <hkskoglund@gmail.com>:
,
Feb 27 2017
That statement is a bit different than your first one. Can you clarify which your issue is: 1) Audio playback suddenly fails and you are unable to resume it. 2) You manually stopping the podcast and you are unable to resume it. 3) The podcast reached the end of its current track and is unable to play the next one.
,
Feb 27 2017
I'm trying to listen to a podcast which suddenly stops (1) 2017-02-27 18:46 GMT+01:00 dalecur… via monorail < monorail+v2.2801085551@chromium.org>:
,
Feb 27 2017
Is there any other source of audio on the system when it stops? I.e., even a chat ping? I ask because I'm trying to determine if our power save blocker is broken or just goes away too freely.
,
Feb 27 2017
No, just audio from the podcast. 2017-02-27 19:04 GMT+01:00 dalecur… via monorail < monorail+v2.2801085551@chromium.org>:
,
Feb 27 2017
If you start playing a long YouTube video in the background does it also stop after ~5minutes? Can you grab the contents of chrome://media-internals when your podcast is playing?
,
Feb 27 2017
No problems with youtube video in chrome tab. 2017-02-27 19:12 GMT+01:00 dalecur… via monorail < monorail+v2.2801085551@chromium.org>:
,
Feb 27 2017
How about on a bare audio clip like this: http://www.noiseaddicts.com/samples_1w72b820/3906.mp3 Feel free to grab something from http://www.noiseaddicts.com/free-samples-mp3/?category_name=Classic_Radio_Shows for different content if you like.
,
Feb 27 2017
Bare audio clip keeps playing just fine. 2017-02-27 19:59 GMT+01:00 dalecur… via monorail < monorail+v2.2801085551@chromium.org>:
,
Feb 27 2017
Hmm, it might be that this site is using HLS and not reporting it's playback properties correctly. Can you try Chrome Canary and see if your issue is resolved? We changed how we handle HLS content in M57.
,
Feb 27 2017
Same issue appears in chrome canary v. 58.0.3021.0. 2017-02-27 21:33 GMT+01:00 dalecur… via monorail < monorail+v2.2801085551@chromium.org>:
,
Feb 27 2017
Thanks +tguilbert to take a look. It seems like this page might be doing something weird.
,
Mar 2 2017
Testing chrome canary v 58.0.3027.0 Afterall it seems like the www.noiseaddicts.com test file only plays through the first buffered data from the site. After about 20 minutes chrome does not ask for more data to play. I can see this by using the wireshark network analyser. It's seems like there are some issues with restarting/resuming an audio stream? I also noticed the audio is stopped, but the media player is displaying "pause" icon. Time hh:mm is not updated during playing (not visible). 2017-02-27 22:12 GMT+01:00 dalecur… via monorail < monorail+v2.2801085551@chromium.org>:
,
Mar 2 2017
Network buffering is only done at the start, the audio pipeline buffer
state is BUFFERING_HAVE_ENOUGH and progress to BUFFERING_HAVE_NOTHING and
audio stops.
[
{
"properties": {
"render_id": 5,
"player_id": 0,
"pipeline_state": "kPlaying",
"event": "PLAY",
"url": "
http://hwcdn.libsyn.com/p/e/b/0/eb030b98765203af/Michael_Joyner_24_02_2017.mp3?c_id=14305398&expiration=1488450557&hwt=ef2eef5685fadf0a599e91696fcc3592
",
"total_bytes": 82497336,
"streaming": false,
"single_origin": true,
"passed_cors_access_check": false,
"range_header_supported": true,
"is_downloading_data": true,
"info": "FFmpegDemuxer: created audio stream, config codec: mp3
bytes_per_channel: 2 channel_layout: 3 samples_per_second: 22050
sample_format: 5 bytes_per_frame: 4 seek_preroll: 0ms codec_delay: 0 has
extra data? false encrypted? false",
"audio_channels_count": 2,
"audio_codec_name": "mp3",
"audio_sample_format": "Signed 16-bit planar",
"audio_samples_per_second": 22050,
"bitrate": 160004,
"found_audio_stream": true,
"found_video_stream": false,
"max_duration": 4124.76075,
"start_time": 0,
"audio_dds": false,
"audio_decoder": "FFmpegAudioDecoder",
"audio_buffering_state": "BUFFERING_HAVE_NOTHING",
"pipeline_buffering_state": "BUFFERING_HAVE_NOTHING",
"duration": 4124.76075
},
"events": [
{
"time": 0,
"key": "pipeline_state",
"value": "kCreated"
},
{
"time": 0.15299999999115244,
"key": "event",
"value": "WEBMEDIAPLAYER_CREATED"
},
{
"time": 1.3649999999906868,
"key": "url",
"value": "
http://hwcdn.libsyn.com/p/e/b/0/eb030b98765203af/Michael_Joyner_24_02_2017.mp3?c_id=14305398&expiration=1488450557&hwt=ef2eef5685fadf0a599e91696fcc3592
"
},
{
"time": 72.72100000001956,
"key": "total_bytes",
"value": 82497336
},
{
"time": 72.7390000000014,
"key": "streaming",
"value": false
},
{
"time": 72.7510000000475,
"key": "single_origin",
"value": true
},
{
"time": 72.75500000000466,
"key": "passed_cors_access_check",
"value": false
},
{
"time": 72.75800000003073,
"key": "range_header_supported",
"value": true
},
{
"time": 91.6600000000326,
"key": "pipeline_state",
"value": "kStarting"
},
{
"time": 132.58900000003632,
"key": "info",
"value": "FFmpegDemuxer: created audio stream, config codec: mp3
bytes_per_channel: 2 channel_layout: 3 samples_per_second: 22050
sample_format: 5 bytes_per_frame: 4 seek_preroll: 0ms codec_delay: 0 has
extra data? false encrypted? false"
},
{
"time": 132.7950000000419,
"key": "audio_channels_count",
"value": 2
},
{
"time": 132.7950000000419,
"key": "audio_codec_name",
"value": "mp3"
},
{
"time": 132.7950000000419,
"key": "audio_sample_format",
"value": "Signed 16-bit planar"
},
{
"time": 132.7950000000419,
"key": "audio_samples_per_second",
"value": 22050
},
{
"time": 132.7950000000419,
"key": "bitrate",
"value": 160004
},
{
"time": 132.7950000000419,
"key": "found_audio_stream",
"value": true
},
{
"time": 132.7950000000419,
"key": "found_video_stream",
"value": false
},
{
"time": 132.7950000000419,
"key": "max_duration",
"value": 4124.76075
},
{
"time": 132.7950000000419,
"key": "start_time",
"value": 0
},
{
"time": 147.82300000003306,
"key": "audio_dds",
"value": false
},
{
"time": 147.83300000004238,
"key": "audio_decoder",
"value": "FFmpegAudioDecoder"
},
{
"time": 148.03700000001118,
"key": "pipeline_state",
"value": "kPlaying"
},
{
"time": 160.76500000001397,
"key": "audio_buffering_state",
"value": "BUFFERING_HAVE_ENOUGH"
},
{
"time": 162.67900000000373,
"key": "pipeline_buffering_state",
"value": "BUFFERING_HAVE_ENOUGH"
},
{
"time": 132.75800000003073,
"key": "duration",
"value": 4124.76075
},
{
"time": 15180.726000000024,
"key": "pipeline_state",
"value": "kSuspending"
},
{
"time": 15185.159000000043,
"key": "pipeline_state",
"value": "kSuspended"
},
{
"time": 111617.95399999997,
"key": "event",
"value": "PLAY"
},
{
"time": 111618.42900000006,
"key": "pipeline_state",
"value": "kResuming"
},
{
"time": 111625.52000000008,
"key": "audio_dds",
"value": false
},
{
"time": 111625.555,
"key": "audio_decoder",
"value": "FFmpegAudioDecoder"
},
{
"time": 111627.02299999999,
"key": "pipeline_state",
"value": "kPlaying"
},
{
"time": 111634.82199999999,
"key": "audio_buffering_state",
"value": "BUFFERING_HAVE_ENOUGH"
},
{
"time": 111635.761,
"key": "pipeline_buffering_state",
"value": "BUFFERING_HAVE_ENOUGH"
},
{
"time": 576912.9950000001,
"key": "audio_buffering_state",
"value": "BUFFERING_HAVE_NOTHING"
},
{
"time": 576915.1070000001,
"key": "pipeline_buffering_state",
"value": "BUFFERING_HAVE_NOTHING"
}
]
}
]
,
Mar 2 2017
Thanks for the details; they seem to imply that our network connections are getting hung up in the background. I've fixed this in the past, but perhaps something has broken it again ( issue 644515 ). +Internals>Network folks to see if they know of any recent background/suspend changes to the network stack.
,
Apr 27 2017
Is this still an issue? I wasn't able to repro on my pixel w/ N.
,
Apr 27 2017
The TCP receive buffer fills up and sends a TCP ZeroWindow to the server. After awhile TCP Window Update is sent, but the server does resume sending audio. After the TCP keep-alive packets are exhausted a RST is sent and a new connection is established and audio resumes. Since the server does not respond to the Window Update, I guess part of the problem is on the server side also. I can use other options to listen to mp3 (like downloading entire file for offline-listen, or use app), so I guess you can close the issue. Tested version : Versjon 59.0.3071.15 dev (64-bit)/Linux, Also testet on Chrome Canary version 60
,
Apr 27 2017
I'm not aware of any change, but I'm not sure I/network folks would be; it sounds as if the question is whether OnSuspend/OnResume is being called for background events. The network stack does still close connections when base::PowerObserver signals OnSuspend().
,
Apr 27 2017
(Removing Internals>Network since I don't think this is something that net triagers needs to pay attention to, but starring the bug so that I'll see questions in case I'm wrong. Also please feel free to add Internals>Network back on if you disagree.)
,
Apr 27 2017
rdsmith: OnSuspend() is not fired on Android, so this would be some other network level issue (either in Chrome or Android) preventing the connection from continuing; see c#20 for a link to the issue where OnSuspend is removed for Android.
,
Apr 27 2017
I'm not aware of any such. Matt, you know of anything?
,
Apr 27 2017
No, I don't. Do we have a specific net error code? That might be illuminating.
,
Apr 27 2017
When I opened the issue I was thinking probably doze mode was responsible for audio stop. But it seems like its related to the server wanting to send audio data fast, but chrome wants to read it quite slowly. In addition the server does not for some reason does react to a TCP Window update from the OS when chrome eventually starts reading the next chunk of data. So the connection follows the pattern: OS: TCP Zero window (TCP receive buffer full after just a short amount of time 1300ms) Server: TCP Keep-alive OS: TCP Keep-alive OS: TCP Window update (chrome audio read next chunk of data, about 50s after first chunk) Server Keep-alive (does not send more data, it should?) Server Keep-alive OS: Keep-alive ... Chrome audio: BUFFERING_HAVE_NOTHING ... OS: RST packet (eventually after OS configured number of keep-alive packets) Chrome/OS: a new connection is created and BUFFERING_HAVE_ENOUGH It is possible to increase the buffer size? Chrome is reading just for about 1300ms, then waits about 50 seconds before reading the next chunk. Maybe for a file under a certain size the entire file could be downloaded direcly instead of reading small chunks of data. At least on a broadband connection, with sufficient free RAM/disk etc. I just tested firefox v53, it downloads the entire file immediately. Tested on : Versjon 59.0.3071.25 dev (64-bit)/linux desktop 2017-04-27 20:54 GMT+02:00 mme… via monorail < monorail+v2.3079273537@chromium.org>:
,
May 1 2017
I just noticed that the server is setting a keep-alive header timeout to 10 seconds on the http connection. So chrome tries to fetch data after this timeout has expired when reading the next chunk of data after about 50 seconds. 2017-04-27 22:44 GMT+02:00 Henning Skoglund <hkskoglund@gmail.com>:
,
May 24 2017
Seems like this is a server issue then? For non-standard buffering sites need to use Media Source Extensions (preferred) or buffer in JS and deliver via data:// or blob://. Especially on mobile we would be very reluctant to increase the buffer size. We already buffer ~2.5 seconds of compressed audio data and up to ~7 seconds in the case of underflow type events. In this case, the site would run into similar issues if paused for too long, so it doesn't seem to make sense for us to change our buffering strategy. Marking as WontFix given this. If this isn't your site I recommend reaching out to the authors to update their server settings. |
|||||||
►
Sign in to add a comment |
|||||||
Comment 1 by dalecur...@chromium.org
, Feb 10 2017