blob: 9b01dfd8852ca1a33b6554c51e911a60a625d435 [file] [log] [blame]
Wale Ogunwale59507092018-10-29 09:00:30 -07001package com.android.server.wm;
Filip Gruszczynski77d94482015-12-11 13:59:52 -08002
Jorim Jaggi3878ca32017-02-02 17:13:05 -08003import static android.app.ActivityManager.START_SUCCESS;
4import static android.app.ActivityManager.START_TASK_TO_FRONT;
Michal Karpinski201bc0c2018-07-20 15:32:00 +01005import static android.app.ActivityManager.processStateAmToProto;
Bryce Lee6c605092017-10-12 11:14:49 -07006import static android.app.WindowConfiguration.WINDOWING_MODE_FREEFORM;
Wale Ogunwale3382ab12017-07-27 08:55:03 -07007import static android.app.WindowConfiguration.WINDOWING_MODE_FULLSCREEN;
8import static android.app.WindowConfiguration.WINDOWING_MODE_PINNED;
Vishnu Nair132ee832018-09-28 15:00:05 -07009import static android.app.WindowConfiguration.WINDOWING_MODE_SPLIT_SCREEN_PRIMARY;
Wale Ogunwale926aade2017-08-29 11:24:37 -070010import static android.app.WindowConfiguration.WINDOWING_MODE_SPLIT_SCREEN_SECONDARY;
11import static android.app.WindowConfiguration.WINDOWING_MODE_UNDEFINED;
Vishnu Nair132ee832018-09-28 15:00:05 -070012
Michal Karpinski201bc0c2018-07-20 15:32:00 +010013import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.ACTION_ACTIVITY_START;
Jorim Jaggi3878ca32017-02-02 17:13:05 -080014import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION;
Jorim Jaggi515dd682017-05-05 15:05:07 +020015import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_BIND_APPLICATION_DELAY_MS;
Todd Kennedy50d946c12017-03-17 13:55:38 -070016import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_CALLING_PACKAGE_NAME;
Jorim Jaggi172e99f2017-10-20 14:33:18 +020017import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_CANCELLED;
Jorim Jaggi3878ca32017-02-02 17:13:05 -080018import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_DELAY_MS;
19import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_DEVICE_UPTIME_SECONDS;
Todd Kennedy50d946c12017-03-17 13:55:38 -070020import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_IS_EPHEMERAL;
Jorim Jaggi4d27b842017-08-17 17:22:26 +020021import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_PROCESS_RUNNING;
22import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_REPORTED_DRAWN;
23import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_REPORTED_DRAWN_MS;
Jorim Jaggi3878ca32017-02-02 17:13:05 -080024import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_STARTING_WINDOW_DELAY_MS;
25import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.APP_TRANSITION_WINDOWS_DRAWN_DELAY_MS;
Michal Karpinski201bc0c2018-07-20 15:32:00 +010026import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_FLAGS;
27import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_IS_FULLSCREEN;
28import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_IS_NO_DISPLAY;
29import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_IS_VISIBLE;
30import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_IS_VISIBLE_IGNORING_KEYGUARD;
Vishnu Nair132ee832018-09-28 15:00:05 -070031import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_LAUNCH_MODE;
Michal Karpinski201bc0c2018-07-20 15:32:00 +010032import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_MILLIS_SINCE_LAST_LAUNCH;
33import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_MILLIS_SINCE_LAST_VISIBLE;
Michal Karpinski201bc0c2018-07-20 15:32:00 +010034import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_PROCESS_NAME;
35import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_REAL_ACTIVITY;
36import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_RESULT_TO_PKG_NAME;
37import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_RESULT_TO_SHORT_COMPONENT_NAME;
38import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_SHORT_COMPONENT_NAME;
39import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_ACTIVITY_RECORD_TARGET_ACTIVITY;
40import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_CALLING_PACKAGE_NAME;
41import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_CALLING_UID;
42import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_CALLING_UID_HAS_ANY_VISIBLE_WINDOW;
43import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_CALLING_UID_PROC_STATE;
Jorim Jaggicdfc04e2017-04-28 19:06:24 +020044import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_CLASS_NAME;
Michal Karpinski201bc0c2018-07-20 15:32:00 +010045import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_COMING_FROM_PENDING_INTENT;
Todd Kennedy50d946c12017-03-17 13:55:38 -070046import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_INSTANT_APP_LAUNCH_TOKEN;
Michal Karpinski201bc0c2018-07-20 15:32:00 +010047import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_INTENT_ACTION;
48import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_CUR_PROC_STATE;
49import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_HAS_CLIENT_ACTIVITIES;
50import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_HAS_FOREGROUND_ACTIVITIES;
51import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_HAS_FOREGROUND_SERVICES;
52import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_HAS_OVERLAY_UI;
53import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_HAS_TOP_UI;
54import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_MILLIS_SINCE_FG_INTERACTION;
55import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_MILLIS_SINCE_LAST_INTERACTION_EVENT;
56import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_MILLIS_SINCE_UNIMPORTANT;
57import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_PENDING_UI_CLEAN;
58import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_PROCESS_RECORD_PROCESS_NAME;
59import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_REAL_CALLING_UID;
Michal Karpinski201bc0c2018-07-20 15:32:00 +010060import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_REAL_CALLING_UID_HAS_ANY_VISIBLE_WINDOW;
Vishnu Nair132ee832018-09-28 15:00:05 -070061import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_REAL_CALLING_UID_PROC_STATE;
Michal Karpinski201bc0c2018-07-20 15:32:00 +010062import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_TARGET_PACKAGE_NAME;
63import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_TARGET_SHORT_COMPONENT_NAME;
64import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_TARGET_UID;
65import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_TARGET_UID_HAS_ANY_VISIBLE_WINDOW;
66import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_TARGET_UID_PROC_STATE;
67import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.FIELD_TARGET_WHITELIST_TAG;
Calin Juravle759fbda2018-02-20 19:52:30 +000068import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.PACKAGE_OPTIMIZATION_COMPILATION_FILTER;
Vishnu Nair132ee832018-09-28 15:00:05 -070069import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.PACKAGE_OPTIMIZATION_COMPILATION_REASON;
Jorim Jaggi3878ca32017-02-02 17:13:05 -080070import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.TYPE_TRANSITION_COLD_LAUNCH;
71import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.TYPE_TRANSITION_HOT_LAUNCH;
Jorim Jaggi4d27b842017-08-17 17:22:26 +020072import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.TYPE_TRANSITION_REPORTED_DRAWN_NO_BUNDLE;
73import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.TYPE_TRANSITION_REPORTED_DRAWN_WITH_BUNDLE;
Jorim Jaggi3878ca32017-02-02 17:13:05 -080074import static com.android.internal.logging.nano.MetricsProto.MetricsEvent.TYPE_TRANSITION_WARM_LAUNCH;
Wale Ogunwale59507092018-10-29 09:00:30 -070075import static com.android.server.wm.ActivityTaskManagerDebugConfig.DEBUG_METRICS;
76import static com.android.server.wm.ActivityTaskManagerDebugConfig.TAG_ATM;
77import static com.android.server.wm.ActivityTaskManagerDebugConfig.TAG_WITH_CLASS_NAME;
Vishnu Nair132ee832018-09-28 15:00:05 -070078import static com.android.server.am.EventLogTags.AM_ACTIVITY_LAUNCH_TIME;
Ng Zhi Anbbefdec2018-01-30 17:12:39 -080079import static com.android.server.am.MemoryStatUtil.MemoryStat;
Rajeev Kumarbfcd9202018-03-02 22:42:13 +000080import static com.android.server.am.MemoryStatUtil.readMemoryStatFromFilesystem;
Vishnu Nair132ee832018-09-28 15:00:05 -070081import static com.android.server.wm.ActivityTaskManagerInternal.APP_TRANSITION_TIMEOUT;
Filip Gruszczynski77d94482015-12-11 13:59:52 -080082
Igor Murashkin212d06c2018-10-22 16:34:39 -070083import android.app.WindowConfiguration.WindowingMode;
Filip Gruszczynski77d94482015-12-11 13:59:52 -080084import android.content.Context;
Michal Karpinski201bc0c2018-07-20 15:32:00 +010085import android.content.Intent;
Calin Juravle759fbda2018-02-20 19:52:30 +000086import android.content.pm.ApplicationInfo;
87import android.content.pm.dex.ArtManagerInternal;
88import android.content.pm.dex.PackageOptimizationInfo;
Jorim Jaggi3878ca32017-02-02 17:13:05 -080089import android.metrics.LogMaker;
Jorim Jaggi172e99f2017-10-20 14:33:18 +020090import android.os.Handler;
91import android.os.Looper;
92import android.os.Message;
Filip Gruszczynski77d94482015-12-11 13:59:52 -080093import android.os.SystemClock;
Vishnu Nair132ee832018-09-28 15:00:05 -070094import android.os.Trace;
95import android.util.EventLog;
96import android.util.Log;
Jorim Jaggi172e99f2017-10-20 14:33:18 +020097import android.util.Slog;
Jorim Jaggi3878ca32017-02-02 17:13:05 -080098import android.util.SparseArray;
99import android.util.SparseIntArray;
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000100import android.util.StatsLog;
Vishnu Nair132ee832018-09-28 15:00:05 -0700101import android.util.TimeUtils;
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800102
103import com.android.internal.logging.MetricsLogger;
Ng Zhi An83473542018-02-20 09:02:14 -0800104import com.android.internal.os.BackgroundThread;
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200105import com.android.internal.os.SomeArgs;
Calin Juravle759fbda2018-02-20 19:52:30 +0000106import com.android.server.LocalServices;
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800107
108/**
Vishnu Nair132ee832018-09-28 15:00:05 -0700109 * Listens to activity launches, transitions, visibility changes and window drawn callbacks to
110 * determine app launch times and draw delays. Source of truth for activity metrics and provides
111 * data for Tron, logcat, event logs and {@link android.app.WaitResult}.
112 *
113 * Tests:
Vishnu Nairf8accc52018-10-11 10:19:54 -0700114 * atest CtsActivityManagerDeviceTestCases:ActivityMetricsLoggerTests
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800115 */
116class ActivityMetricsLogger {
Jorim Jaggif9704102016-05-05 19:14:22 -0700117
Wale Ogunwale98875612018-10-12 07:53:02 -0700118 private static final String TAG = TAG_WITH_CLASS_NAME ? "ActivityMetricsLogger" : TAG_ATM;
Jorim Jaggif9704102016-05-05 19:14:22 -0700119
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800120 // Window modes we are interested in logging. If we ever introduce a new type, we need to add
121 // a value here and increase the {@link #TRON_WINDOW_STATE_VARZ_STRINGS} array.
122 private static final int WINDOW_STATE_STANDARD = 0;
123 private static final int WINDOW_STATE_SIDE_BY_SIDE = 1;
124 private static final int WINDOW_STATE_FREEFORM = 2;
Winson Chung83471632016-12-13 11:02:12 -0800125 private static final int WINDOW_STATE_ASSISTANT = 3;
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800126 private static final int WINDOW_STATE_INVALID = -1;
127
Jorim Jaggi275561a2016-02-23 10:11:02 -0500128 private static final long INVALID_START_TIME = -1;
Vishnu Nair132ee832018-09-28 15:00:05 -0700129 private static final int INVALID_DELAY = -1;
130 private static final int INVALID_TRANSITION_TYPE = -1;
Jorim Jaggi275561a2016-02-23 10:11:02 -0500131
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200132 private static final int MSG_CHECK_VISIBILITY = 0;
133
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800134 // Preallocated strings we are sending to tron, so we don't have to allocate a new one every
135 // time we log.
136 private static final String[] TRON_WINDOW_STATE_VARZ_STRINGS = {
Winson Chung83471632016-12-13 11:02:12 -0800137 "window_time_0", "window_time_1", "window_time_2", "window_time_3"};
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800138
139 private int mWindowState = WINDOW_STATE_STANDARD;
140 private long mLastLogTimeSecs;
141 private final ActivityStackSupervisor mSupervisor;
142 private final Context mContext;
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800143 private final MetricsLogger mMetricsLogger = new MetricsLogger();
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800144
Igor Murashkin212d06c2018-10-22 16:34:39 -0700145 // set to INVALID_START_TIME in reset.
146 // set to valid value in notifyActivityLaunching
Jorim Jaggi275561a2016-02-23 10:11:02 -0500147 private long mCurrentTransitionStartTime = INVALID_START_TIME;
Jorim Jaggi4d27b842017-08-17 17:22:26 +0200148 private long mLastTransitionStartTime = INVALID_START_TIME;
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800149
150 private int mCurrentTransitionDeviceUptime;
151 private int mCurrentTransitionDelayMs;
Igor Murashkin212d06c2018-10-22 16:34:39 -0700152
153 /** If the any app transitions have been logged as starting, after the latest reset. */
Jorim Jaggi275561a2016-02-23 10:11:02 -0500154 private boolean mLoggedTransitionStarting;
155
Igor Murashkin212d06c2018-10-22 16:34:39 -0700156 /** Map : @WindowingMode int => WindowingModeTransitionInfo */
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100157 private final SparseArray<WindowingModeTransitionInfo> mWindowingModeTransitionInfo =
158 new SparseArray<>();
Igor Murashkin212d06c2018-10-22 16:34:39 -0700159 /** Map : @WindowingMode int => WindowingModeTransitionInfo */
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100160 private final SparseArray<WindowingModeTransitionInfo> mLastWindowingModeTransitionInfo =
161 new SparseArray<>();
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200162 private final H mHandler;
Calin Juravle759fbda2018-02-20 19:52:30 +0000163
164 private ArtManagerInternal mArtManagerInternal;
Vishnu Nair132ee832018-09-28 15:00:05 -0700165 private final StringBuilder mStringBuilder = new StringBuilder();
Calin Juravle759fbda2018-02-20 19:52:30 +0000166
Igor Murashkin212d06c2018-10-22 16:34:39 -0700167 /**
168 * Due to the global single concurrent launch sequence, all calls to this observer must be made
169 * in-order on the same thread to fulfill the "happens-before" guarantee in LaunchObserver.
170 */
171 private final ActivityMetricsLaunchObserver mLaunchObserver = null;
172
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200173 private final class H extends Handler {
174
175 public H(Looper looper) {
176 super(looper);
177 }
178
179 @Override
180 public void handleMessage(Message msg) {
181 switch (msg.what) {
182 case MSG_CHECK_VISIBILITY:
183 final SomeArgs args = (SomeArgs) msg.obj;
184 checkVisibility((TaskRecord) args.arg1, (ActivityRecord) args.arg2);
185 break;
186 }
187 }
Calin Juravle759fbda2018-02-20 19:52:30 +0000188 }
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800189
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100190 private final class WindowingModeTransitionInfo {
Igor Murashkin212d06c2018-10-22 16:34:39 -0700191 /** The latest activity to have been launched. */
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800192 private ActivityRecord launchedActivity;
193 private int startResult;
194 private boolean currentTransitionProcessRunning;
Vishnu Nair132ee832018-09-28 15:00:05 -0700195 /** Elapsed time from when we launch an activity to when its windows are drawn. */
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800196 private int windowsDrawnDelayMs;
Vishnu Nair132ee832018-09-28 15:00:05 -0700197 private int startingWindowDelayMs = INVALID_DELAY;
198 private int bindApplicationDelayMs = INVALID_DELAY;
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800199 private int reason = APP_TRANSITION_TIMEOUT;
200 private boolean loggedWindowsDrawn;
201 private boolean loggedStartingWindowDrawn;
Vishnu Nair132ee832018-09-28 15:00:05 -0700202 private boolean launchTraceActive;
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800203 }
204
Vishnu Nair132ee832018-09-28 15:00:05 -0700205 final class WindowingModeTransitionInfoSnapshot {
Calin Juravle759fbda2018-02-20 19:52:30 +0000206 final private ApplicationInfo applicationInfo;
Wale Ogunwalef6733932018-06-27 05:14:34 -0700207 final private WindowProcessController processRecord;
Vishnu Nair132ee832018-09-28 15:00:05 -0700208 final String packageName;
209 final String launchedActivityName;
Calin Juravle759fbda2018-02-20 19:52:30 +0000210 final private String launchedActivityLaunchedFromPackage;
211 final private String launchedActivityLaunchToken;
212 final private String launchedActivityAppRecordRequiredAbi;
Vishnu Nair132ee832018-09-28 15:00:05 -0700213 final String launchedActivityShortComponentName;
Ng Zhi An83473542018-02-20 09:02:14 -0800214 final private String processName;
Calin Juravle759fbda2018-02-20 19:52:30 +0000215 final private int reason;
216 final private int startingWindowDelayMs;
217 final private int bindApplicationDelayMs;
Vishnu Nair132ee832018-09-28 15:00:05 -0700218 final int windowsDrawnDelayMs;
219 final int type;
220 final int userId;
221 /**
222 * Elapsed time from when we launch an activity to when the app reported it was
223 * fully drawn. If this is not reported then the value is set to INVALID_DELAY.
224 */
225 final int windowsFullyDrawnDelayMs;
226 final int activityRecordIdHashCode;
Calin Juravle759fbda2018-02-20 19:52:30 +0000227
228 private WindowingModeTransitionInfoSnapshot(WindowingModeTransitionInfo info) {
Vishnu Nair132ee832018-09-28 15:00:05 -0700229 this(info, info.launchedActivity);
230 }
231
232 private WindowingModeTransitionInfoSnapshot(WindowingModeTransitionInfo info,
233 ActivityRecord launchedActivity) {
234 this(info, launchedActivity, INVALID_DELAY);
235 }
236
237 private WindowingModeTransitionInfoSnapshot(WindowingModeTransitionInfo info,
238 ActivityRecord launchedActivity, int windowsFullyDrawnDelayMs) {
239 applicationInfo = launchedActivity.appInfo;
240 packageName = launchedActivity.packageName;
241 launchedActivityName = launchedActivity.info.name;
242 launchedActivityLaunchedFromPackage = launchedActivity.launchedFromPackage;
243 launchedActivityLaunchToken = launchedActivity.info.launchToken;
244 launchedActivityAppRecordRequiredAbi = launchedActivity.app == null
Calin Juravle759fbda2018-02-20 19:52:30 +0000245 ? null
Vishnu Nairaf0ea312018-10-15 16:23:55 -0700246 : launchedActivity.app.getRequiredAbi();
Calin Juravle759fbda2018-02-20 19:52:30 +0000247 reason = info.reason;
248 startingWindowDelayMs = info.startingWindowDelayMs;
249 bindApplicationDelayMs = info.bindApplicationDelayMs;
250 windowsDrawnDelayMs = info.windowsDrawnDelayMs;
251 type = getTransitionType(info);
Vishnu Nairaf0ea312018-10-15 16:23:55 -0700252 processRecord = findProcessForActivity(launchedActivity);
253 processName = launchedActivity.processName;
Vishnu Nair132ee832018-09-28 15:00:05 -0700254 userId = launchedActivity.userId;
255 launchedActivityShortComponentName = launchedActivity.shortComponentName;
256 activityRecordIdHashCode = System.identityHashCode(launchedActivity);
257 this.windowsFullyDrawnDelayMs = windowsFullyDrawnDelayMs;
Calin Juravle759fbda2018-02-20 19:52:30 +0000258 }
259 }
260
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200261 ActivityMetricsLogger(ActivityStackSupervisor supervisor, Context context, Looper looper) {
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800262 mLastLogTimeSecs = SystemClock.elapsedRealtime() / 1000;
263 mSupervisor = supervisor;
264 mContext = context;
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200265 mHandler = new H(looper);
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800266 }
267
268 void logWindowState() {
269 final long now = SystemClock.elapsedRealtime() / 1000;
270 if (mWindowState != WINDOW_STATE_INVALID) {
271 // We log even if the window state hasn't changed, because the user might remain in
272 // home/fullscreen move forever and we would like to track this kind of behavior
273 // too.
274 MetricsLogger.count(mContext, TRON_WINDOW_STATE_VARZ_STRINGS[mWindowState],
275 (int) (now - mLastLogTimeSecs));
276 }
277 mLastLogTimeSecs = now;
278
Wale Ogunwale926aade2017-08-29 11:24:37 -0700279 mWindowState = WINDOW_STATE_INVALID;
Andrii Kulian5f750bc2018-07-17 08:57:23 -0700280 ActivityStack stack = mSupervisor.getTopDisplayFocusedStack();
lumarkf6c4a982018-06-15 15:43:12 +0800281 if (stack == null) {
282 return;
283 }
284
Wale Ogunwale926aade2017-08-29 11:24:37 -0700285 if (stack.isActivityTypeAssistant()) {
286 mWindowState = WINDOW_STATE_ASSISTANT;
Filip Gruszczynskicaae14e2015-12-16 14:40:04 -0800287 return;
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800288 }
Wale Ogunwale926aade2017-08-29 11:24:37 -0700289
Igor Murashkin212d06c2018-10-22 16:34:39 -0700290 @WindowingMode int windowingMode = stack.getWindowingMode();
Wale Ogunwale3382ab12017-07-27 08:55:03 -0700291 if (windowingMode == WINDOWING_MODE_PINNED) {
Filip Gruszczynskicaae14e2015-12-16 14:40:04 -0800292 stack = mSupervisor.findStackBehind(stack);
Wale Ogunwale3382ab12017-07-27 08:55:03 -0700293 windowingMode = stack.getWindowingMode();
Filip Gruszczynskicaae14e2015-12-16 14:40:04 -0800294 }
Wale Ogunwale926aade2017-08-29 11:24:37 -0700295 switch (windowingMode) {
296 case WINDOWING_MODE_FULLSCREEN:
297 mWindowState = WINDOW_STATE_STANDARD;
298 break;
299 case WINDOWING_MODE_SPLIT_SCREEN_PRIMARY:
300 case WINDOWING_MODE_SPLIT_SCREEN_SECONDARY:
301 mWindowState = WINDOW_STATE_SIDE_BY_SIDE;
302 break;
Bryce Lee6c605092017-10-12 11:14:49 -0700303 case WINDOWING_MODE_FREEFORM:
Wale Ogunwale926aade2017-08-29 11:24:37 -0700304 mWindowState = WINDOW_STATE_FREEFORM;
305 break;
306 default:
307 if (windowingMode != WINDOWING_MODE_UNDEFINED) {
308 throw new IllegalStateException("Unknown windowing mode for stack=" + stack
309 + " windowingMode=" + windowingMode);
310 }
Filip Gruszczynski77d94482015-12-11 13:59:52 -0800311 }
312 }
Jorim Jaggi275561a2016-02-23 10:11:02 -0500313
314 /**
315 * Notifies the tracker at the earliest possible point when we are starting to launch an
316 * activity.
317 */
Igor Murashkin212d06c2018-10-22 16:34:39 -0700318 void notifyActivityLaunching(Intent intent) {
319 if (DEBUG_METRICS) {
320 Slog.i(TAG, String.format("notifyActivityLaunching: active:%b, intent:%s",
321 isAnyTransitionActive(),
322 intent));
323 }
324
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800325 if (!isAnyTransitionActive()) {
Igor Murashkin212d06c2018-10-22 16:34:39 -0700326
Alison Cichowlasb7f67ab2017-04-25 18:04:40 -0400327 mCurrentTransitionStartTime = SystemClock.uptimeMillis();
Jorim Jaggi4d27b842017-08-17 17:22:26 +0200328 mLastTransitionStartTime = mCurrentTransitionStartTime;
Igor Murashkin212d06c2018-10-22 16:34:39 -0700329
330 launchObserverNotifyIntentStarted(intent);
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800331 }
Jorim Jaggi275561a2016-02-23 10:11:02 -0500332 }
333
334 /**
Jorim Jaggi1e630c02016-05-16 12:13:13 -0700335 * Notifies the tracker that the activity is actually launching.
336 *
337 * @param resultCode one of the ActivityManager.START_* flags, indicating the result of the
338 * launch
339 * @param launchedActivity the activity that is being launched
340 */
341 void notifyActivityLaunched(int resultCode, ActivityRecord launchedActivity) {
Wale Ogunwalef6733932018-06-27 05:14:34 -0700342 final WindowProcessController processRecord = findProcessForActivity(launchedActivity);
Jorim Jaggi1e630c02016-05-16 12:13:13 -0700343 final boolean processRunning = processRecord != null;
Jorim Jaggi1e630c02016-05-16 12:13:13 -0700344
345 // We consider this a "process switch" if the process of the activity that gets launched
346 // didn't have an activity that was in started state. In this case, we assume that lot
347 // of caches might be purged so the time until it produces the first frame is very
348 // interesting.
349 final boolean processSwitch = processRecord == null
Wale Ogunwalef6733932018-06-27 05:14:34 -0700350 || !processRecord.hasStartedActivity(launchedActivity);
Jorim Jaggi1e630c02016-05-16 12:13:13 -0700351
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800352 notifyActivityLaunched(resultCode, launchedActivity, processRunning, processSwitch);
Jorim Jaggi1e630c02016-05-16 12:13:13 -0700353 }
354
Jorim Jaggi1e630c02016-05-16 12:13:13 -0700355 /**
Jorim Jaggi275561a2016-02-23 10:11:02 -0500356 * Notifies the tracker the the activity is actually launching.
357 *
358 * @param resultCode one of the ActivityManager.START_* flags, indicating the result of the
359 * launch
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800360 * @param launchedActivity the activity being launched
Jorim Jaggi275561a2016-02-23 10:11:02 -0500361 * @param processRunning whether the process that will contains the activity is already running
Jorim Jaggibe67c902016-04-12 00:53:16 -0700362 * @param processSwitch whether the process that will contain the activity didn't have any
363 * activity that was stopped, i.e. the started activity is "switching"
364 * processes
Jorim Jaggi275561a2016-02-23 10:11:02 -0500365 */
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800366 private void notifyActivityLaunched(int resultCode, ActivityRecord launchedActivity,
Jorim Jaggibe67c902016-04-12 00:53:16 -0700367 boolean processRunning, boolean processSwitch) {
Jorim Jaggi275561a2016-02-23 10:11:02 -0500368
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200369 if (DEBUG_METRICS) Slog.i(TAG, "notifyActivityLaunched"
370 + " resultCode=" + resultCode
371 + " launchedActivity=" + launchedActivity
372 + " processRunning=" + processRunning
373 + " processSwitch=" + processSwitch);
374
Igor Murashkin212d06c2018-10-22 16:34:39 -0700375 // If we are already in an existing transition, only update the activity name, but not the
376 // other attributes.
377 final @WindowingMode int windowingMode = launchedActivity != null
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100378 ? launchedActivity.getWindowingMode()
379 : WINDOWING_MODE_UNDEFINED;
Vishnu Nairf8accc52018-10-11 10:19:54 -0700380 final WindowingModeTransitionInfo info = mWindowingModeTransitionInfo.get(windowingMode);
Jorim Jaggicdfc04e2017-04-28 19:06:24 +0200381 if (mCurrentTransitionStartTime == INVALID_START_TIME) {
Vishnu Nairf8accc52018-10-11 10:19:54 -0700382 // No transition is active ignore this launch.
Jorim Jaggicdfc04e2017-04-28 19:06:24 +0200383 return;
384 }
385
Vishnu Nairf8accc52018-10-11 10:19:54 -0700386 if (launchedActivity != null && launchedActivity.nowVisible) {
387 // Launched activity is already visible. We cannot measure windows drawn delay.
Igor Murashkin212d06c2018-10-22 16:34:39 -0700388 reset(true /* abort */, info, "launched activity already visible");
Vishnu Nairf8accc52018-10-11 10:19:54 -0700389 return;
390 }
391
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800392 if (launchedActivity != null && info != null) {
Vishnu Nairf8accc52018-10-11 10:19:54 -0700393 // If we are already in an existing transition, only update the activity name, but not
394 // the other attributes.
Igor Murashkin212d06c2018-10-22 16:34:39 -0700395
396 // Coalesce multiple (trampoline) activities from a single sequence together.
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800397 info.launchedActivity = launchedActivity;
Jorim Jaggi275561a2016-02-23 10:11:02 -0500398 return;
399 }
400
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100401 final boolean otherWindowModesLaunching =
402 mWindowingModeTransitionInfo.size() > 0 && info == null;
Jorim Jaggi54cff642018-03-15 15:51:32 +0100403 if ((!isLoggableResultCode(resultCode) || launchedActivity == null || !processSwitch
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100404 || windowingMode == WINDOWING_MODE_UNDEFINED) && !otherWindowModesLaunching) {
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800405 // Failed to launch or it was not a process switch, so we don't care about the timing.
Igor Murashkin212d06c2018-10-22 16:34:39 -0700406 reset(true /* abort */, info, "failed to launch or not a process switch");
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800407 return;
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100408 } else if (otherWindowModesLaunching) {
409 // Don't log this windowing mode but continue with the other windowing modes.
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800410 return;
411 }
412
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200413 if (DEBUG_METRICS) Slog.i(TAG, "notifyActivityLaunched successful");
414
Igor Murashkin212d06c2018-10-22 16:34:39 -0700415 // A new launch sequence [with the windowingMode] has begun.
416 // Start tracking it.
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100417 final WindowingModeTransitionInfo newInfo = new WindowingModeTransitionInfo();
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800418 newInfo.launchedActivity = launchedActivity;
419 newInfo.currentTransitionProcessRunning = processRunning;
420 newInfo.startResult = resultCode;
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100421 mWindowingModeTransitionInfo.put(windowingMode, newInfo);
422 mLastWindowingModeTransitionInfo.put(windowingMode, newInfo);
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800423 mCurrentTransitionDeviceUptime = (int) (SystemClock.uptimeMillis() / 1000);
Vishnu Nair132ee832018-09-28 15:00:05 -0700424 startTraces(newInfo);
Igor Murashkin212d06c2018-10-22 16:34:39 -0700425 launchObserverNotifyActivityLaunched(newInfo);
Jorim Jaggi275561a2016-02-23 10:11:02 -0500426 }
427
428 /**
Jorim Jaggi54cff642018-03-15 15:51:32 +0100429 * @return True if we should start logging an event for an activity start that returned
430 * {@code resultCode} and that we'll indeed get a windows drawn event.
431 */
432 private boolean isLoggableResultCode(int resultCode) {
433 return resultCode == START_SUCCESS || resultCode == START_TASK_TO_FRONT;
434 }
435
436 /**
Jorim Jaggi275561a2016-02-23 10:11:02 -0500437 * Notifies the tracker that all windows of the app have been drawn.
438 */
Igor Murashkin212d06c2018-10-22 16:34:39 -0700439 WindowingModeTransitionInfoSnapshot notifyWindowsDrawn(@WindowingMode int windowingMode,
440 long timestamp) {
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100441 if (DEBUG_METRICS) Slog.i(TAG, "notifyWindowsDrawn windowingMode=" + windowingMode);
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200442
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100443 final WindowingModeTransitionInfo info = mWindowingModeTransitionInfo.get(windowingMode);
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800444 if (info == null || info.loggedWindowsDrawn) {
Vishnu Nair132ee832018-09-28 15:00:05 -0700445 return null;
Jorim Jaggi275561a2016-02-23 10:11:02 -0500446 }
Sudheer Shankac766db02017-06-12 10:37:29 -0700447 info.windowsDrawnDelayMs = calculateDelay(timestamp);
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800448 info.loggedWindowsDrawn = true;
Vishnu Nair132ee832018-09-28 15:00:05 -0700449 final WindowingModeTransitionInfoSnapshot infoSnapshot =
450 new WindowingModeTransitionInfoSnapshot(info);
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100451 if (allWindowsDrawn() && mLoggedTransitionStarting) {
Igor Murashkin212d06c2018-10-22 16:34:39 -0700452 reset(false /* abort */, info, "notifyWindowsDrawn - all windows drawn");
Jorim Jaggi275561a2016-02-23 10:11:02 -0500453 }
Vishnu Nair132ee832018-09-28 15:00:05 -0700454 return infoSnapshot;
Jorim Jaggi275561a2016-02-23 10:11:02 -0500455 }
456
457 /**
458 * Notifies the tracker that the starting window was drawn.
459 */
Igor Murashkin212d06c2018-10-22 16:34:39 -0700460 void notifyStartingWindowDrawn(@WindowingMode int windowingMode, long timestamp) {
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100461 final WindowingModeTransitionInfo info = mWindowingModeTransitionInfo.get(windowingMode);
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800462 if (info == null || info.loggedStartingWindowDrawn) {
Jorim Jaggi275561a2016-02-23 10:11:02 -0500463 return;
464 }
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800465 info.loggedStartingWindowDrawn = true;
Sudheer Shankac766db02017-06-12 10:37:29 -0700466 info.startingWindowDelayMs = calculateDelay(timestamp);
Jorim Jaggi275561a2016-02-23 10:11:02 -0500467 }
468
469 /**
470 * Notifies the tracker that the app transition is starting.
471 *
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100472 * @param windowingModeToReason A map from windowing mode to a reason integer, which must be on
Vishnu Nair132ee832018-09-28 15:00:05 -0700473 * of ActivityTaskManagerInternal.APP_TRANSITION_* reasons.
Jorim Jaggi275561a2016-02-23 10:11:02 -0500474 */
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100475 void notifyTransitionStarting(SparseIntArray windowingModeToReason, long timestamp) {
Jorim Jaggid8a57772017-04-14 16:50:42 -0700476 if (!isAnyTransitionActive() || mLoggedTransitionStarting) {
Igor Murashkin212d06c2018-10-22 16:34:39 -0700477 // Ignore calls to this made after a reset and prior to notifyActivityLaunching.
478
479 // Ignore any subsequent notifyTransitionStarting until the next reset.
Jorim Jaggi275561a2016-02-23 10:11:02 -0500480 return;
481 }
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200482 if (DEBUG_METRICS) Slog.i(TAG, "notifyTransitionStarting");
Sudheer Shankac766db02017-06-12 10:37:29 -0700483 mCurrentTransitionDelayMs = calculateDelay(timestamp);
Jorim Jaggi275561a2016-02-23 10:11:02 -0500484 mLoggedTransitionStarting = true;
Igor Murashkin212d06c2018-10-22 16:34:39 -0700485
486 WindowingModeTransitionInfo foundInfo = null;
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100487 for (int index = windowingModeToReason.size() - 1; index >= 0; index--) {
Igor Murashkin212d06c2018-10-22 16:34:39 -0700488 final @WindowingMode int windowingMode = windowingModeToReason.keyAt(index);
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100489 final WindowingModeTransitionInfo info = mWindowingModeTransitionInfo.get(
490 windowingMode);
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800491 if (info == null) {
492 continue;
493 }
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100494 info.reason = windowingModeToReason.valueAt(index);
Igor Murashkin212d06c2018-10-22 16:34:39 -0700495 foundInfo = info;
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800496 }
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100497 if (allWindowsDrawn()) {
Igor Murashkin212d06c2018-10-22 16:34:39 -0700498 reset(false /* abort */, foundInfo, "notifyTransitionStarting - all windows drawn");
Jorim Jaggi275561a2016-02-23 10:11:02 -0500499 }
500 }
501
Jorim Jaggicdfc04e2017-04-28 19:06:24 +0200502 /**
503 * Notifies the tracker that the visibility of an app is changing.
504 *
505 * @param activityRecord the app that is changing its visibility
Jorim Jaggicdfc04e2017-04-28 19:06:24 +0200506 */
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200507 void notifyVisibilityChanged(ActivityRecord activityRecord) {
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100508 final WindowingModeTransitionInfo info = mWindowingModeTransitionInfo.get(
509 activityRecord.getWindowingMode());
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200510 if (info == null) {
511 return;
512 }
513 if (info.launchedActivity != activityRecord) {
514 return;
515 }
516 final TaskRecord t = activityRecord.getTask();
517 final SomeArgs args = SomeArgs.obtain();
518 args.arg1 = t;
519 args.arg2 = activityRecord;
520 mHandler.obtainMessage(MSG_CHECK_VISIBILITY, args).sendToTarget();
521 }
Jorim Jaggicdfc04e2017-04-28 19:06:24 +0200522
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200523 private void checkVisibility(TaskRecord t, ActivityRecord r) {
Wale Ogunwalec9e57de2018-05-08 14:28:07 -0700524 synchronized (mSupervisor.mService.mGlobalLock) {
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200525
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100526 final WindowingModeTransitionInfo info = mWindowingModeTransitionInfo.get(
527 r.getWindowingMode());
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200528
529 // If we have an active transition that's waiting on a certain activity that will be
530 // invisible now, we'll never get onWindowsDrawn, so abort the transition if necessary.
531 if (info != null && !t.isVisible()) {
532 if (DEBUG_METRICS) Slog.i(TAG, "notifyVisibilityChanged to invisible"
533 + " activity=" + r);
534 logAppTransitionCancel(info);
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100535 mWindowingModeTransitionInfo.remove(r.getWindowingMode());
536 if (mWindowingModeTransitionInfo.size() == 0) {
Igor Murashkin212d06c2018-10-22 16:34:39 -0700537 reset(true /* abort */, info, "notifyVisibilityChanged to invisible");
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200538 }
Jorim Jaggicdfc04e2017-04-28 19:06:24 +0200539 }
540 }
541 }
542
Jorim Jaggi515dd682017-05-05 15:05:07 +0200543 /**
544 * Notifies the tracker that we called immediately before we call bindApplication on the client.
545 *
Wale Ogunwale31913b52018-10-13 08:29:31 -0700546 * @param appInfo The client into which we'll call bindApplication.
Jorim Jaggi515dd682017-05-05 15:05:07 +0200547 */
Wale Ogunwale31913b52018-10-13 08:29:31 -0700548 void notifyBindApplication(ApplicationInfo appInfo) {
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100549 for (int i = mWindowingModeTransitionInfo.size() - 1; i >= 0; i--) {
550 final WindowingModeTransitionInfo info = mWindowingModeTransitionInfo.valueAt(i);
Jorim Jaggi515dd682017-05-05 15:05:07 +0200551
552 // App isn't attached to record yet, so match with info.
Wale Ogunwale31913b52018-10-13 08:29:31 -0700553 if (info.launchedActivity.appInfo == appInfo) {
Jorim Jaggi515dd682017-05-05 15:05:07 +0200554 info.bindApplicationDelayMs = calculateCurrentDelay();
555 }
556 }
557 }
558
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100559 private boolean allWindowsDrawn() {
560 for (int index = mWindowingModeTransitionInfo.size() - 1; index >= 0; index--) {
561 if (!mWindowingModeTransitionInfo.valueAt(index).loggedWindowsDrawn) {
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800562 return false;
563 }
564 }
565 return true;
Jorim Jaggi275561a2016-02-23 10:11:02 -0500566 }
567
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800568 private boolean isAnyTransitionActive() {
569 return mCurrentTransitionStartTime != INVALID_START_TIME
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100570 && mWindowingModeTransitionInfo.size() > 0;
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800571 }
572
Igor Murashkin212d06c2018-10-22 16:34:39 -0700573 private void reset(boolean abort, WindowingModeTransitionInfo info, String cause) {
574 if (DEBUG_METRICS) Slog.i(TAG, "reset abort=" + abort + ",cause=" + cause);
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800575 if (!abort && isAnyTransitionActive()) {
576 logAppTransitionMultiEvents();
577 }
Vishnu Nair132ee832018-09-28 15:00:05 -0700578 stopLaunchTrace(info);
Igor Murashkin212d06c2018-10-22 16:34:39 -0700579
580 // Ignore reset-after reset.
581 if (isAnyTransitionActive()) {
582 // LaunchObserver callbacks.
583 if (abort) {
584 launchObserverNotifyActivityLaunchCancelled(info);
585 } else {
586 launchObserverNotifyActivityLaunchFinished(info);
587 }
588 } else {
589 launchObserverNotifyIntentFailed();
590 }
591
Jorim Jaggi275561a2016-02-23 10:11:02 -0500592 mCurrentTransitionStartTime = INVALID_START_TIME;
Vishnu Nair132ee832018-09-28 15:00:05 -0700593 mCurrentTransitionDelayMs = INVALID_DELAY;
Jorim Jaggi275561a2016-02-23 10:11:02 -0500594 mLoggedTransitionStarting = false;
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100595 mWindowingModeTransitionInfo.clear();
Jorim Jaggi275561a2016-02-23 10:11:02 -0500596 }
597
598 private int calculateCurrentDelay() {
Jorim Jaggi275561a2016-02-23 10:11:02 -0500599 // Shouldn't take more than 25 days to launch an app, so int is fine here.
Alison Cichowlasb7f67ab2017-04-25 18:04:40 -0400600 return (int) (SystemClock.uptimeMillis() - mCurrentTransitionStartTime);
Jorim Jaggi275561a2016-02-23 10:11:02 -0500601 }
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800602
Sudheer Shankac766db02017-06-12 10:37:29 -0700603 private int calculateDelay(long timestamp) {
604 // Shouldn't take more than 25 days to launch an app, so int is fine here.
605 return (int) (timestamp - mCurrentTransitionStartTime);
606 }
607
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100608 private void logAppTransitionCancel(WindowingModeTransitionInfo info) {
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200609 final int type = getTransitionType(info);
Vishnu Nair132ee832018-09-28 15:00:05 -0700610 if (type == INVALID_TRANSITION_TYPE) {
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200611 return;
612 }
613 final LogMaker builder = new LogMaker(APP_TRANSITION_CANCELLED);
614 builder.setPackageName(info.launchedActivity.packageName);
615 builder.setType(type);
616 builder.addTaggedData(FIELD_CLASS_NAME, info.launchedActivity.info.name);
617 mMetricsLogger.write(builder);
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000618 StatsLog.write(
Chenjie Yu5caaa9d2018-03-06 15:48:54 -0800619 StatsLog.APP_START_CANCELED,
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000620 info.launchedActivity.appInfo.uid,
621 info.launchedActivity.packageName,
622 convertAppStartTransitionType(type),
623 info.launchedActivity.info.name);
Igor Murashkin212d06c2018-10-22 16:34:39 -0700624 if (DEBUG_METRICS) {
625 Slog.i(TAG, String.format("APP_START_CANCELED(%s, %s, %s, %s)",
626 info.launchedActivity.appInfo.uid,
627 info.launchedActivity.packageName,
628 convertAppStartTransitionType(type),
629 info.launchedActivity.info.name));
630 }
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200631 }
632
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800633 private void logAppTransitionMultiEvents() {
Jorim Jaggi172e99f2017-10-20 14:33:18 +0200634 if (DEBUG_METRICS) Slog.i(TAG, "logging transition events");
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100635 for (int index = mWindowingModeTransitionInfo.size() - 1; index >= 0; index--) {
636 final WindowingModeTransitionInfo info = mWindowingModeTransitionInfo.valueAt(index);
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800637 final int type = getTransitionType(info);
Vishnu Nair132ee832018-09-28 15:00:05 -0700638 if (type == INVALID_TRANSITION_TYPE) {
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800639 return;
640 }
Calin Juravle759fbda2018-02-20 19:52:30 +0000641
642 // Take a snapshot of the transition info before sending it to the handler for logging.
643 // This will avoid any races with other operations that modify the ActivityRecord.
644 final WindowingModeTransitionInfoSnapshot infoSnapshot =
645 new WindowingModeTransitionInfoSnapshot(info);
Ng Zhi An83473542018-02-20 09:02:14 -0800646 final int currentTransitionDeviceUptime = mCurrentTransitionDeviceUptime;
647 final int currentTransitionDelayMs = mCurrentTransitionDelayMs;
648 BackgroundThread.getHandler().post(() -> logAppTransition(
649 currentTransitionDeviceUptime, currentTransitionDelayMs, infoSnapshot));
Vishnu Nair132ee832018-09-28 15:00:05 -0700650 BackgroundThread.getHandler().post(() -> logAppDisplayed(infoSnapshot));
Calin Juravle759fbda2018-02-20 19:52:30 +0000651
652 info.launchedActivity.info.launchToken = null;
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800653 }
654 }
655
Ng Zhi An83473542018-02-20 09:02:14 -0800656 // This gets called on a background thread without holding the activity manager lock.
Calin Juravle759fbda2018-02-20 19:52:30 +0000657 private void logAppTransition(int currentTransitionDeviceUptime, int currentTransitionDelayMs,
658 WindowingModeTransitionInfoSnapshot info) {
659 final LogMaker builder = new LogMaker(APP_TRANSITION);
660 builder.setPackageName(info.packageName);
661 builder.setType(info.type);
662 builder.addTaggedData(FIELD_CLASS_NAME, info.launchedActivityName);
663 final boolean isInstantApp = info.applicationInfo.isInstantApp();
664 if (info.launchedActivityLaunchedFromPackage != null) {
665 builder.addTaggedData(APP_TRANSITION_CALLING_PACKAGE_NAME,
666 info.launchedActivityLaunchedFromPackage);
667 }
668 String launchToken = info.launchedActivityLaunchToken;
669 if (launchToken != null) {
670 builder.addTaggedData(FIELD_INSTANT_APP_LAUNCH_TOKEN, launchToken);
671 }
672 builder.addTaggedData(APP_TRANSITION_IS_EPHEMERAL, isInstantApp ? 1 : 0);
673 builder.addTaggedData(APP_TRANSITION_DEVICE_UPTIME_SECONDS,
674 currentTransitionDeviceUptime);
675 builder.addTaggedData(APP_TRANSITION_DELAY_MS, currentTransitionDelayMs);
676 builder.setSubtype(info.reason);
Vishnu Nair132ee832018-09-28 15:00:05 -0700677 if (info.startingWindowDelayMs != INVALID_DELAY) {
Calin Juravle759fbda2018-02-20 19:52:30 +0000678 builder.addTaggedData(APP_TRANSITION_STARTING_WINDOW_DELAY_MS,
679 info.startingWindowDelayMs);
680 }
Vishnu Nair132ee832018-09-28 15:00:05 -0700681 if (info.bindApplicationDelayMs != INVALID_DELAY) {
Calin Juravle759fbda2018-02-20 19:52:30 +0000682 builder.addTaggedData(APP_TRANSITION_BIND_APPLICATION_DELAY_MS,
683 info.bindApplicationDelayMs);
684 }
685 builder.addTaggedData(APP_TRANSITION_WINDOWS_DRAWN_DELAY_MS, info.windowsDrawnDelayMs);
686 final ArtManagerInternal artManagerInternal = getArtManagerInternal();
687 final PackageOptimizationInfo packageOptimizationInfo =
688 (artManagerInternal == null) || (info.launchedActivityAppRecordRequiredAbi == null)
689 ? PackageOptimizationInfo.createWithNoInfo()
690 : artManagerInternal.getPackageOptimizationInfo(
691 info.applicationInfo,
692 info.launchedActivityAppRecordRequiredAbi);
693 builder.addTaggedData(PACKAGE_OPTIMIZATION_COMPILATION_REASON,
694 packageOptimizationInfo.getCompilationReason());
695 builder.addTaggedData(PACKAGE_OPTIMIZATION_COMPILATION_FILTER,
696 packageOptimizationInfo.getCompilationFilter());
697 mMetricsLogger.write(builder);
698 StatsLog.write(
Chenjie Yu5caaa9d2018-03-06 15:48:54 -0800699 StatsLog.APP_START_OCCURRED,
Calin Juravle759fbda2018-02-20 19:52:30 +0000700 info.applicationInfo.uid,
701 info.packageName,
702 convertAppStartTransitionType(info.type),
703 info.launchedActivityName,
704 info.launchedActivityLaunchedFromPackage,
705 isInstantApp,
706 currentTransitionDeviceUptime * 1000,
707 info.reason,
708 currentTransitionDelayMs,
709 info.startingWindowDelayMs,
710 info.bindApplicationDelayMs,
711 info.windowsDrawnDelayMs,
712 launchToken,
713 packageOptimizationInfo.getCompilationReason(),
714 packageOptimizationInfo.getCompilationFilter());
Igor Murashkin212d06c2018-10-22 16:34:39 -0700715
716 if (DEBUG_METRICS) {
717 Slog.i(TAG, String.format("APP_START_OCCURRED(%s, %s, %s, %s, %s)",
718 info.applicationInfo.uid,
719 info.packageName,
720 convertAppStartTransitionType(info.type),
721 info.launchedActivityName,
722 info.launchedActivityLaunchedFromPackage));
723 }
724
725
Ng Zhi An83473542018-02-20 09:02:14 -0800726 logAppStartMemoryStateCapture(info);
Calin Juravle759fbda2018-02-20 19:52:30 +0000727 }
728
Vishnu Nair132ee832018-09-28 15:00:05 -0700729 private void logAppDisplayed(WindowingModeTransitionInfoSnapshot info) {
730 if (info.type != TYPE_TRANSITION_WARM_LAUNCH && info.type != TYPE_TRANSITION_COLD_LAUNCH) {
731 return;
732 }
733
734 EventLog.writeEvent(AM_ACTIVITY_LAUNCH_TIME,
735 info.userId, info.activityRecordIdHashCode, info.launchedActivityShortComponentName,
736 info.windowsDrawnDelayMs);
737
738 StringBuilder sb = mStringBuilder;
739 sb.setLength(0);
740 sb.append("Displayed ");
741 sb.append(info.launchedActivityShortComponentName);
742 sb.append(": ");
743 TimeUtils.formatDuration(info.windowsDrawnDelayMs, sb);
744 Log.i(TAG, sb.toString());
745 }
746
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000747 private int convertAppStartTransitionType(int tronType) {
748 if (tronType == TYPE_TRANSITION_COLD_LAUNCH) {
Chenjie Yu5caaa9d2018-03-06 15:48:54 -0800749 return StatsLog.APP_START_OCCURRED__TYPE__COLD;
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000750 }
751 if (tronType == TYPE_TRANSITION_WARM_LAUNCH) {
Chenjie Yu5caaa9d2018-03-06 15:48:54 -0800752 return StatsLog.APP_START_OCCURRED__TYPE__WARM;
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000753 }
754 if (tronType == TYPE_TRANSITION_HOT_LAUNCH) {
Chenjie Yu5caaa9d2018-03-06 15:48:54 -0800755 return StatsLog.APP_START_OCCURRED__TYPE__HOT;
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000756 }
Chenjie Yu5caaa9d2018-03-06 15:48:54 -0800757 return StatsLog.APP_START_OCCURRED__TYPE__UNKNOWN;
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000758 }
759
Vishnu Nair132ee832018-09-28 15:00:05 -0700760 WindowingModeTransitionInfoSnapshot logAppTransitionReportedDrawn(ActivityRecord r,
761 boolean restoredFromBundle) {
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100762 final WindowingModeTransitionInfo info = mLastWindowingModeTransitionInfo.get(
763 r.getWindowingMode());
Jorim Jaggi4d27b842017-08-17 17:22:26 +0200764 if (info == null) {
Vishnu Nair132ee832018-09-28 15:00:05 -0700765 return null;
Jorim Jaggi4d27b842017-08-17 17:22:26 +0200766 }
Chris Wailes35c193c2018-10-09 18:12:00 -0700767
768 // Record the handling of the reportFullyDrawn callback in the trace system. This is not
769 // actually used to trace this function, but instead the logical task that this function
770 // fullfils (handling reportFullyDrawn() callbacks).
771 Trace.traceBegin(Trace.TRACE_TAG_ACTIVITY_MANAGER,
772 "ActivityManager:ReportingFullyDrawn " + info.launchedActivity.packageName);
773
Jorim Jaggi4d27b842017-08-17 17:22:26 +0200774 final LogMaker builder = new LogMaker(APP_TRANSITION_REPORTED_DRAWN);
775 builder.setPackageName(r.packageName);
776 builder.addTaggedData(FIELD_CLASS_NAME, r.info.name);
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000777 long startupTimeMs = SystemClock.uptimeMillis() - mLastTransitionStartTime;
778 builder.addTaggedData(APP_TRANSITION_REPORTED_DRAWN_MS, startupTimeMs);
Jorim Jaggi4d27b842017-08-17 17:22:26 +0200779 builder.setType(restoredFromBundle
780 ? TYPE_TRANSITION_REPORTED_DRAWN_WITH_BUNDLE
781 : TYPE_TRANSITION_REPORTED_DRAWN_NO_BUNDLE);
782 builder.addTaggedData(APP_TRANSITION_PROCESS_RUNNING,
783 info.currentTransitionProcessRunning ? 1 : 0);
784 mMetricsLogger.write(builder);
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000785 StatsLog.write(
Chenjie Yu5caaa9d2018-03-06 15:48:54 -0800786 StatsLog.APP_START_FULLY_DRAWN,
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000787 info.launchedActivity.appInfo.uid,
788 info.launchedActivity.packageName,
789 restoredFromBundle
Chenjie Yu5caaa9d2018-03-06 15:48:54 -0800790 ? StatsLog.APP_START_FULLY_DRAWN__TYPE__WITH_BUNDLE
791 : StatsLog.APP_START_FULLY_DRAWN__TYPE__WITHOUT_BUNDLE,
Olivier Gaillardaed7f122017-12-12 14:26:22 +0000792 info.launchedActivity.info.name,
793 info.currentTransitionProcessRunning,
794 startupTimeMs);
Chris Wailes35c193c2018-10-09 18:12:00 -0700795
796 // Ends the trace started at the beginning of this function. This is located here to allow
797 // the trace slice to have a noticable duration.
798 Trace.traceEnd(Trace.TRACE_TAG_ACTIVITY_MANAGER);
799
Vishnu Nair132ee832018-09-28 15:00:05 -0700800 final WindowingModeTransitionInfoSnapshot infoSnapshot =
801 new WindowingModeTransitionInfoSnapshot(info, r, (int) startupTimeMs);
802 BackgroundThread.getHandler().post(() -> logAppFullyDrawn(infoSnapshot));
803 return infoSnapshot;
804 }
805
806 private void logAppFullyDrawn(WindowingModeTransitionInfoSnapshot info) {
807 if (info.type != TYPE_TRANSITION_WARM_LAUNCH && info.type != TYPE_TRANSITION_COLD_LAUNCH) {
808 return;
809 }
810
811 StringBuilder sb = mStringBuilder;
812 sb.setLength(0);
813 sb.append("Fully drawn ");
814 sb.append(info.launchedActivityShortComponentName);
815 sb.append(": ");
816 TimeUtils.formatDuration(info.windowsFullyDrawnDelayMs, sb);
817 Log.i(TAG, sb.toString());
Jorim Jaggi4d27b842017-08-17 17:22:26 +0200818 }
819
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700820 void logActivityStart(Intent intent, WindowProcessController callerApp, ActivityRecord r,
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100821 int callingUid, String callingPackage, int callingUidProcState,
822 boolean callingUidHasAnyVisibleWindow,
823 int realCallingUid, int realCallingUidProcState,
824 boolean realCallingUidHasAnyVisibleWindow,
825 int targetUid, String targetPackage, int targetUidProcState,
826 boolean targetUidHasAnyVisibleWindow, String targetWhitelistTag,
827 boolean comingFromPendingIntent) {
828
829 final long nowElapsed = SystemClock.elapsedRealtime();
830 final long nowUptime = SystemClock.uptimeMillis();
831 final LogMaker builder = new LogMaker(ACTION_ACTIVITY_START);
832 builder.setTimestamp(System.currentTimeMillis());
833 builder.addTaggedData(FIELD_CALLING_UID, callingUid);
834 builder.addTaggedData(FIELD_CALLING_PACKAGE_NAME, callingPackage);
835 builder.addTaggedData(FIELD_CALLING_UID_PROC_STATE,
836 processStateAmToProto(callingUidProcState));
837 builder.addTaggedData(FIELD_CALLING_UID_HAS_ANY_VISIBLE_WINDOW,
838 callingUidHasAnyVisibleWindow ? 1 : 0);
839 builder.addTaggedData(FIELD_REAL_CALLING_UID, realCallingUid);
840 builder.addTaggedData(FIELD_REAL_CALLING_UID_PROC_STATE,
841 processStateAmToProto(realCallingUidProcState));
842 builder.addTaggedData(FIELD_REAL_CALLING_UID_HAS_ANY_VISIBLE_WINDOW,
843 realCallingUidHasAnyVisibleWindow ? 1 : 0);
844 builder.addTaggedData(FIELD_TARGET_UID, targetUid);
845 builder.addTaggedData(FIELD_TARGET_PACKAGE_NAME, targetPackage);
846 builder.addTaggedData(FIELD_TARGET_UID_PROC_STATE,
847 processStateAmToProto(targetUidProcState));
848 builder.addTaggedData(FIELD_TARGET_UID_HAS_ANY_VISIBLE_WINDOW,
849 targetUidHasAnyVisibleWindow ? 1 : 0);
850 builder.addTaggedData(FIELD_TARGET_WHITELIST_TAG, targetWhitelistTag);
851 builder.addTaggedData(FIELD_TARGET_SHORT_COMPONENT_NAME, r.shortComponentName);
852 builder.addTaggedData(FIELD_COMING_FROM_PENDING_INTENT, comingFromPendingIntent ? 1 : 0);
853 builder.addTaggedData(FIELD_INTENT_ACTION, intent.getAction());
854 if (callerApp != null) {
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700855 builder.addTaggedData(FIELD_PROCESS_RECORD_PROCESS_NAME, callerApp.mName);
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100856 builder.addTaggedData(FIELD_PROCESS_RECORD_CUR_PROC_STATE,
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700857 processStateAmToProto(callerApp.getCurrentProcState()));
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100858 builder.addTaggedData(FIELD_PROCESS_RECORD_HAS_CLIENT_ACTIVITIES,
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700859 callerApp.hasClientActivities() ? 1 : 0);
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100860 builder.addTaggedData(FIELD_PROCESS_RECORD_HAS_FOREGROUND_SERVICES,
861 callerApp.hasForegroundServices() ? 1 : 0);
862 builder.addTaggedData(FIELD_PROCESS_RECORD_HAS_FOREGROUND_ACTIVITIES,
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700863 callerApp.hasForegroundActivities() ? 1 : 0);
864 builder.addTaggedData(FIELD_PROCESS_RECORD_HAS_TOP_UI, callerApp.hasTopUi() ? 1 : 0);
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100865 builder.addTaggedData(FIELD_PROCESS_RECORD_HAS_OVERLAY_UI,
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700866 callerApp.hasOverlayUi() ? 1 : 0);
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100867 builder.addTaggedData(FIELD_PROCESS_RECORD_PENDING_UI_CLEAN,
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700868 callerApp.hasPendingUiClean() ? 1 : 0);
869 if (callerApp.getInteractionEventTime() != 0) {
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100870 builder.addTaggedData(FIELD_PROCESS_RECORD_MILLIS_SINCE_LAST_INTERACTION_EVENT,
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700871 (nowElapsed - callerApp.getInteractionEventTime()));
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100872 }
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700873 if (callerApp.getFgInteractionTime() != 0) {
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100874 builder.addTaggedData(FIELD_PROCESS_RECORD_MILLIS_SINCE_FG_INTERACTION,
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700875 (nowElapsed - callerApp.getFgInteractionTime()));
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100876 }
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700877 if (callerApp.getWhenUnimportant() != 0) {
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100878 builder.addTaggedData(FIELD_PROCESS_RECORD_MILLIS_SINCE_UNIMPORTANT,
Wale Ogunwale342fbe92018-10-09 08:44:10 -0700879 (nowUptime - callerApp.getWhenUnimportant()));
Michal Karpinski201bc0c2018-07-20 15:32:00 +0100880 }
881 }
882 builder.addTaggedData(FIELD_ACTIVITY_RECORD_LAUNCH_MODE, r.info.launchMode);
883 builder.addTaggedData(FIELD_ACTIVITY_RECORD_TARGET_ACTIVITY, r.info.targetActivity);
884 builder.addTaggedData(FIELD_ACTIVITY_RECORD_FLAGS, r.info.flags);
885 builder.addTaggedData(FIELD_ACTIVITY_RECORD_REAL_ACTIVITY, r.realActivity.toShortString());
886 builder.addTaggedData(FIELD_ACTIVITY_RECORD_SHORT_COMPONENT_NAME, r.shortComponentName);
887 builder.addTaggedData(FIELD_ACTIVITY_RECORD_PROCESS_NAME, r.processName);
888 builder.addTaggedData(FIELD_ACTIVITY_RECORD_IS_FULLSCREEN, r.fullscreen ? 1 : 0);
889 builder.addTaggedData(FIELD_ACTIVITY_RECORD_IS_NO_DISPLAY, r.noDisplay ? 1 : 0);
890 if (r.lastVisibleTime != 0) {
891 builder.addTaggedData(FIELD_ACTIVITY_RECORD_MILLIS_SINCE_LAST_VISIBLE,
892 (nowUptime - r.lastVisibleTime));
893 }
894 if (r.resultTo != null) {
895 builder.addTaggedData(FIELD_ACTIVITY_RECORD_RESULT_TO_PKG_NAME, r.resultTo.packageName);
896 builder.addTaggedData(FIELD_ACTIVITY_RECORD_RESULT_TO_SHORT_COMPONENT_NAME,
897 r.resultTo.shortComponentName);
898 }
899 builder.addTaggedData(FIELD_ACTIVITY_RECORD_IS_VISIBLE, r.visible ? 1 : 0);
900 builder.addTaggedData(FIELD_ACTIVITY_RECORD_IS_VISIBLE_IGNORING_KEYGUARD,
901 r.visibleIgnoringKeyguard ? 1 : 0);
902 if (r.lastLaunchTime != 0) {
903 builder.addTaggedData(FIELD_ACTIVITY_RECORD_MILLIS_SINCE_LAST_LAUNCH,
904 (nowUptime - r.lastLaunchTime));
905 }
906 mMetricsLogger.write(builder);
907 }
908
Jorim Jaggi9b58f2d2018-02-19 17:48:44 +0100909 private int getTransitionType(WindowingModeTransitionInfo info) {
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800910 if (info.currentTransitionProcessRunning) {
911 if (info.startResult == START_SUCCESS) {
912 return TYPE_TRANSITION_WARM_LAUNCH;
913 } else if (info.startResult == START_TASK_TO_FRONT) {
914 return TYPE_TRANSITION_HOT_LAUNCH;
915 }
916 } else if (info.startResult == START_SUCCESS) {
917 return TYPE_TRANSITION_COLD_LAUNCH;
918 }
Vishnu Nair132ee832018-09-28 15:00:05 -0700919 return INVALID_TRANSITION_TYPE;
Jorim Jaggi3878ca32017-02-02 17:13:05 -0800920 }
Ng Zhi Anbbefdec2018-01-30 17:12:39 -0800921
Ng Zhi An83473542018-02-20 09:02:14 -0800922 private void logAppStartMemoryStateCapture(WindowingModeTransitionInfoSnapshot info) {
923 if (info.processRecord == null) {
Ng Zhi Anbbefdec2018-01-30 17:12:39 -0800924 if (DEBUG_METRICS) Slog.i(TAG, "logAppStartMemoryStateCapture processRecord null");
925 return;
926 }
927
Wale Ogunwalef6733932018-06-27 05:14:34 -0700928 final int pid = info.processRecord.getPid();
Ng Zhi An83473542018-02-20 09:02:14 -0800929 final int uid = info.applicationInfo.uid;
Rajeev Kumarbfcd9202018-03-02 22:42:13 +0000930 final MemoryStat memoryStat = readMemoryStatFromFilesystem(uid, pid);
Ng Zhi Anbbefdec2018-01-30 17:12:39 -0800931 if (memoryStat == null) {
932 if (DEBUG_METRICS) Slog.i(TAG, "logAppStartMemoryStateCapture memoryStat null");
933 return;
934 }
935
936 StatsLog.write(
937 StatsLog.APP_START_MEMORY_STATE_CAPTURED,
938 uid,
Ng Zhi An83473542018-02-20 09:02:14 -0800939 info.processName,
940 info.launchedActivityName,
Ng Zhi Anbbefdec2018-01-30 17:12:39 -0800941 memoryStat.pgfault,
942 memoryStat.pgmajfault,
943 memoryStat.rssInBytes,
944 memoryStat.cacheInBytes,
945 memoryStat.swapInBytes);
946 }
947
Wale Ogunwalef6733932018-06-27 05:14:34 -0700948 private WindowProcessController findProcessForActivity(ActivityRecord launchedActivity) {
Ng Zhi Anbbefdec2018-01-30 17:12:39 -0800949 return launchedActivity != null
Wale Ogunwalef6733932018-06-27 05:14:34 -0700950 ? mSupervisor.mService.mProcessNames.get(
Wale Ogunwalec9e57de2018-05-08 14:28:07 -0700951 launchedActivity.processName, launchedActivity.appInfo.uid)
Ng Zhi Anbbefdec2018-01-30 17:12:39 -0800952 : null;
953 }
Calin Juravle759fbda2018-02-20 19:52:30 +0000954
955 private ArtManagerInternal getArtManagerInternal() {
956 if (mArtManagerInternal == null) {
957 // Note that this may be null.
958 // ArtManagerInternal is registered during PackageManagerService
959 // initialization which happens after ActivityManagerService.
960 mArtManagerInternal = LocalServices.getService(ArtManagerInternal.class);
961 }
962 return mArtManagerInternal;
963 }
Vishnu Nair132ee832018-09-28 15:00:05 -0700964
965 /**
Chris Wailes35c193c2018-10-09 18:12:00 -0700966 * Starts traces for app launch.
Vishnu Nair132ee832018-09-28 15:00:05 -0700967 *
968 * @param info
969 * */
970 private void startTraces(WindowingModeTransitionInfo info) {
971 if (info == null) {
972 return;
973 }
Vishnu Nair132ee832018-09-28 15:00:05 -0700974 int transitionType = getTransitionType(info);
975 if (!info.launchTraceActive && transitionType == TYPE_TRANSITION_WARM_LAUNCH
976 || transitionType == TYPE_TRANSITION_COLD_LAUNCH) {
977 Trace.asyncTraceBegin(Trace.TRACE_TAG_ACTIVITY_MANAGER, "launching: "
978 + info.launchedActivity.packageName, 0);
Vishnu Nair132ee832018-09-28 15:00:05 -0700979 info.launchTraceActive = true;
980 }
981 }
982
983 private void stopLaunchTrace(WindowingModeTransitionInfo info) {
984 if (info == null) {
985 return;
986 }
987 if (info.launchTraceActive) {
988 Trace.asyncTraceEnd(Trace.TRACE_TAG_ACTIVITY_MANAGER, "launching: "
989 + info.launchedActivity.packageName, 0);
990 info.launchTraceActive = false;
991 }
992 }
Igor Murashkin212d06c2018-10-22 16:34:39 -0700993
994 /** Notify the {@link ActivityMetricsLaunchObserver} that a new launch sequence has begun. */
995 private void launchObserverNotifyIntentStarted(Intent intent) {
996 if (mLaunchObserver != null) {
997 // Beginning a launch is timing sensitive and so should be observed as soon as possible.
998 mLaunchObserver.onIntentStarted(intent);
999 }
1000 }
1001
1002 /**
1003 * Notify the {@link ActivityMetricsLaunchObserver} that the previous launch sequence has
1004 * aborted due to intent failure (e.g. intent resolve failed or security error, etc) or
1005 * intent being delivered to the top running activity.
1006 */
1007 private void launchObserverNotifyIntentFailed() {
1008 if (mLaunchObserver != null) {
1009 mLaunchObserver.onIntentFailed();
1010 }
1011 }
1012
1013 /**
1014 * Notify the {@link ActivityMetricsLaunchObserver} that the current launch sequence's activity
1015 * has started.
1016 */
1017 private void launchObserverNotifyActivityLaunched(WindowingModeTransitionInfo info) {
1018 @ActivityMetricsLaunchObserver.Temperature int temperature =
1019 convertTransitionTypeToLaunchObserverTemperature(getTransitionType(info));
1020
1021 if (mLaunchObserver != null) {
1022 // Beginning a launch is timing sensitive and so should be observed as soon as possible.
1023 mLaunchObserver.onActivityLaunched(info.launchedActivity,
1024 temperature);
1025 }
1026 }
1027
1028 /**
1029 * Notify the {@link ActivityMetricsLaunchObserver} that the current launch sequence is
1030 * cancelled.
1031 */
1032 private void launchObserverNotifyActivityLaunchCancelled(WindowingModeTransitionInfo info) {
1033 final ActivityRecord launchedActivity = info != null ? info.launchedActivity : null;
1034
1035 if (mLaunchObserver != null) {
1036 mLaunchObserver.onActivityLaunchCancelled(launchedActivity);
1037 }
1038 }
1039
1040 /**
1041 * Notify the {@link ActivityMetricsLaunchObserver} that the current launch sequence's activity
1042 * has fully finished (successfully).
1043 */
1044 private void launchObserverNotifyActivityLaunchFinished(WindowingModeTransitionInfo info) {
1045 final ActivityRecord launchedActivity = info.launchedActivity;
1046
1047 if (mLaunchObserver != null) {
1048 mLaunchObserver.onActivityLaunchFinished(launchedActivity);
1049 }
1050 }
1051
1052 private static @ActivityMetricsLaunchObserver.Temperature int
1053 convertTransitionTypeToLaunchObserverTemperature(int transitionType) {
1054 switch (transitionType) {
1055 case TYPE_TRANSITION_WARM_LAUNCH:
1056 return ActivityMetricsLaunchObserver.TEMPERATURE_WARM;
1057 case TYPE_TRANSITION_HOT_LAUNCH:
1058 return ActivityMetricsLaunchObserver.TEMPERATURE_HOT;
1059 case TYPE_TRANSITION_COLD_LAUNCH:
1060 return ActivityMetricsLaunchObserver.TEMPERATURE_COLD;
1061 default:
1062 return -1;
1063 }
1064 }
Filip Gruszczynski77d94482015-12-11 13:59:52 -08001065}