07-24 10:41:40.665 1221 1434 I PowerManagerService: Waking up from Asleep (uid=1000, reason=WAKE_REASON_POWER_BUTTON, details=android.policy:POWER)...
07-24 10:41:40.667 1221 1271 I DisplayPowerController: Blocking screen on until initial contents have been drawn.
,.....
///keyguard绘制完成
07-24 10:41:40.692 1221 1239 W WindowManager: Setting mKeyguardDrawComplete
07-24 10:41:40.692 1221 1239 D WindowManager: finishKeyguardDrawn mLock
07-24 10:41:40.693 1221 1239 D WindowManager: finishKeyguardDrawn mScreenOnEarly=true mKeyguardDrawComplete=false
///Window 绘制完成,通知回调完成
07-24 10:41:40.778 1221 1242 D WindowManager: All windows drawn!
Looper分发慢
07-24 10:41:56.274 1221 1239 W Looper : Drained
07-24 10:41:56.551 1221 1239 W Looper : Slow dispatch took 142ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=31
07-24 10:41:57.717 1221 1239 W Looper : Slow dispatch took 101ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=53
07-24 10:41:57.829 1221 1239 W Looper : Slow dispatch took 112ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=31
07-24 10:41:58.521 1221 1239 W Looper : Slow dispatch took 174ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=31
07-24 10:42:01.501 1221 1239 W Looper : Slow dispatch took 136ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=53
07-24 10:42:02.545 1221 1239 W Looper : Slow dispatch took 109ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=53
07-24 10:42:03.981 1221 1239 W Looper : Slow dispatch took 117ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=53
07-24 10:42:04.598 1221 1239 W Looper : Slow dispatch took 127ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=53
07-24 10:42:05.700 1221 1239 W Looper : Slow dispatch took 173ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=31
07-24 10:42:07.526 1221 1239 W Looper : Slow dispatch took 113ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=31
07-24 10:42:09.073 1221 1239 W Looper : Slow dispatch took 188ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=31
07-24 10:42:11.639 1221 1239 W Looper : Slow dispatch took 105ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=31
07-24 10:42:12.056 1221 1239 W Looper : Slow dispatch took 146ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=31
07-24 10:42:13.495 1221 1239 W Looper : Slow dispatch took 117ms android.ui h=com.android.server.am.ActivityManagerService$UiHandler c=null m=53
.....
///Windwos回调完成绘制.
07-24 10:43:13.046 1221 1239 W WindowManager: Setting mWindowManagerDrawComplete
07-24 10:43:13.046 1221 1239 D WindowManager: finishWindowsDrawn
07-24 10:43:13.046 1221 1239 D WindowManager: finishWindowsDrawn mLock
07-24 10:43:13.046 1221 1239 D WindowManager: finishWindowsDrawn mScreenOnEarly=true mWindowManagerDrawComplete=false
07-24 10:43:13.051 1221 1271 I DisplayPowerController: Unblocked screen on after 92384 ms
AMS中的Looper分发耗时加大,由于属于同一个线程,导致WindowManagerService中的mH.sendEmptyMessage(H.ALL_WINDOWS_DRAWN)发送超时,没有及时调用mWaitingForDrawnCallback的回调。