framework.dart 10.2 KB
Newer Older
Ian Hickson's avatar
Ian Hickson committed
1
// Copyright 2014 The Flutter Authors. All rights reserved.
2 3 4 5 6 7 8 9 10 11
// Use of this source code is governed by a BSD-style license that can be
// found in the LICENSE file.

import 'dart:async';
import 'dart:convert';
import 'dart:developer';
import 'dart:io';
import 'dart:isolate';

import 'package:logging/logging.dart';
12
import 'package:path/path.dart' as path;
13
import 'package:stack_trace/stack_trace.dart';
14

15 16
import 'devices.dart';
import 'host_agent.dart';
17
import 'running_processes.dart';
18
import 'task_result.dart';
19 20
import 'utils.dart';

21 22 23 24 25 26 27 28
/// Identifiers for devices that should never be rebooted.
final Set<String> noRebootForbidList = <String>{
  '822ef7958bba573829d85eef4df6cbdd86593730', // 32bit iPhone requires manual intervention on reboot.
};

/// The maximum number of test runs before a device must be rebooted.
///
/// This number was chosen arbitrarily.
29
const int maximumRuns = 30;
30

31 32
/// Represents a unit of work performed in the CI environment that can
/// succeed, fail and be retried independently of others.
33
typedef TaskFunction = Future<TaskResult> Function();
34 35 36 37 38

bool _isTaskRegistered = false;

/// Registers a [task] to run, returns the result when it is complete.
///
39
/// The task does not run immediately but waits for the request via the
40 41
/// VM service protocol to run it.
///
42
/// It is OK for a [task] to perform many things. However, only one task can be
43
/// registered per Dart VM.
44
Future<TaskResult> task(TaskFunction task) async {
45
  if (_isTaskRegistered)
46
    throw StateError('A task is already registered');
47 48 49

  _isTaskRegistered = true;

50
  // TODO(ianh): allow overriding logging.
51 52 53 54 55
  Logger.root.level = Level.ALL;
  Logger.root.onRecord.listen((LogRecord rec) {
    print('${rec.level.name}: ${rec.time}: ${rec.message}');
  });

56
  final _TaskRunner runner = _TaskRunner(task);
57 58 59 60 61 62
  runner.keepVmAliveUntilTaskRunRequested();
  return runner.whenDone;
}

class _TaskRunner {
  _TaskRunner(this.task) {
63 64
    registerExtension('ext.cocoonRunTask',
        (String method, Map<String, String> parameters) async {
65 66
      final Duration? taskTimeout = parameters.containsKey('timeoutInMinutes')
        ? Duration(minutes: int.parse(parameters['timeoutInMinutes']!))
67
        : null;
68 69 70 71 72
      // This is only expected to be passed in unit test runs so they do not
      // kill the Dart process that is running them and waste time running config.
      final bool runFlutterConfig = parameters['runFlutterConfig'] != 'false';
      final bool runProcessCleanup = parameters['runProcessCleanup'] != 'false';
      final TaskResult result = await run(taskTimeout, runProcessCleanup: runProcessCleanup, runFlutterConfig: runFlutterConfig);
73
      return ServiceExtensionResponse.result(json.encode(result.toJson()));
74
    });
75 76
    registerExtension('ext.cocoonRunnerReady',
        (String method, Map<String, String> parameters) async {
77
      return ServiceExtensionResponse.result('"ready"');
78 79 80
    });
  }

81 82
  final TaskFunction task;

83
  Future<Device?> _getWorkingDeviceIfAvailable() async {
84 85 86 87 88 89 90
    try {
      return await devices.workingDevice;
    } on DeviceException {
      return null;
    }
  }

91
  // TODO(ianh): workaround for https://github.com/dart-lang/sdk/issues/23797
92 93
  RawReceivePort? _keepAlivePort;
  Timer? _startTaskTimeout;
94 95 96 97 98 99
  bool _taskStarted = false;

  final Completer<TaskResult> _completer = Completer<TaskResult>();

  static final Logger logger = Logger('TaskRunner');

100 101 102
  /// Signals that this task runner finished running the task.
  Future<TaskResult> get whenDone => _completer.future;

103
  Future<TaskResult> run(Duration? taskTimeout, {
104 105 106
    bool runFlutterConfig = true,
    bool runProcessCleanup = true,
  }) async {
107 108
    try {
      _taskStarted = true;
109
      print('Running task with a timeout of $taskTimeout.');
110
      final String exe = Platform.isWindows ? '.exe' : '';
111
      late Set<RunningProcessInfo> beforeRunningDartInstances;
112 113 114 115 116 117 118 119 120 121 122
      if (runProcessCleanup) {
        section('Checking running Dart$exe processes');
        beforeRunningDartInstances = await getRunningProcesses(
          processName: 'dart$exe',
        ).toSet();
        final Set<RunningProcessInfo> allProcesses = await getRunningProcesses().toSet();
        beforeRunningDartInstances.forEach(print);
        for (final RunningProcessInfo info in allProcesses) {
          if (info.commandLine.contains('iproxy')) {
            print('[LEAK]: ${info.commandLine} ${info.creationDate} ${info.pid} ');
          }
123
        }
124 125
      } else {
        section('Skipping check running Dart$exe processes');
126
      }
127 128 129 130 131 132 133 134 135 136

      if (runFlutterConfig) {
        print('enabling configs for macOS, Linux, Windows, and Web...');
        final int configResult = await exec(path.join(flutterDirectory.path, 'bin', 'flutter'), <String>[
          'config',
          '-v',
          '--enable-macos-desktop',
          '--enable-windows-desktop',
          '--enable-linux-desktop',
          '--enable-web',
137
          if (localEngine != null) ...<String>['--local-engine', localEngine!],
138 139 140 141 142 143
        ], canFail: true);
        if (configResult != 0) {
          print('Failed to enable configuration, tasks may not run.');
        }
      } else {
        print('Skipping enabling configs for macOS, Linux, Windows, and Web');
144 145
      }

146
      final Device? device = await _getWorkingDeviceIfAvailable();
147 148 149 150

      // Some tests assume the phone is in home
      await device?.home();

151 152
      late TaskResult result;
      IOSink? sink;
153 154
      try {
        if (device != null && device.canStreamLogs && hostAgent.dumpDirectory != null) {
155
          sink = File(path.join(hostAgent.dumpDirectory!.path, '${device.deviceId}.log')).openWrite();
156 157
          await device.startLoggingToSink(sink);
        }
158

159 160 161 162 163 164 165 166
        Future<TaskResult> futureResult = _performTask();
        if (taskTimeout != null)
          futureResult = futureResult.timeout(taskTimeout);

        result = await futureResult;
      } finally {
        if (device != null && device.canStreamLogs) {
          await device.stopLoggingToSink();
167
          await sink?.close();
168 169
        }
      }
170

171 172 173 174 175 176 177 178 179 180 181 182 183 184 185 186 187
      if (runProcessCleanup) {
        section('Checking running Dart$exe processes after task...');
        final List<RunningProcessInfo> afterRunningDartInstances = await getRunningProcesses(
          processName: 'dart$exe',
        ).toList();
        for (final RunningProcessInfo info in afterRunningDartInstances) {
          if (!beforeRunningDartInstances.contains(info)) {
            print('$info was leaked by this test.');
            if (result is TaskResultCheckProcesses) {
              result = TaskResult.failure('This test leaked dart processes');
            }
            final bool killed = await killProcess(info.pid);
            if (!killed) {
              print('Failed to kill process ${info.pid}.');
            } else {
              print('Killed process id ${info.pid}.');
            }
188 189
          }
        }
190 191
      } else {
        print('Skipping check running Dart$exe processes after task');
192
      }
193 194
      _completer.complete(result);
      return result;
195
    } on TimeoutException catch (err, stackTrace) {
196
      print('Task timed out in framework.dart after $taskTimeout.');
197 198
      print(err);
      print(stackTrace);
199
      return TaskResult.failure('Task timed out after $taskTimeout');
200
    } finally {
201 202
      await checkForRebootRequired();
      await forceQuitRunningProcesses();
203 204 205 206
      _closeKeepAlivePort();
    }
  }

207 208 209 210 211 212 213 214 215 216
  Future<void> checkForRebootRequired() async {
    print('Checking for reboot');
    try {
      final Device device = await devices.workingDevice;
      if (noRebootForbidList.contains(device.deviceId)) {
        return;
      }
      final File rebootFile = _rebootFile();
      int runCount;
      if (rebootFile.existsSync()) {
217
        runCount = int.tryParse(rebootFile.readAsStringSync().trim()) ?? 0;
218 219 220
      } else {
        runCount = 0;
      }
221
      if (runCount < maximumRuns) {
222 223 224 225 226 227 228 229 230 231 232 233 234 235 236
        rebootFile
          ..createSync()
          ..writeAsStringSync((runCount + 1).toString());
        return;
      }
      rebootFile.deleteSync();
      print('rebooting');
      await device.reboot();
    } on TimeoutException {
      // Could not find device in order to reboot.
    } on DeviceException {
      // No attached device needed to reboot.
    }
  }

237 238 239
  /// Causes the Dart VM to stay alive until a request to run the task is
  /// received via the VM service protocol.
  void keepVmAliveUntilTaskRunRequested() {
240
    if (_taskStarted)
241
      throw StateError('Task already started.');
242 243 244

    // Merely creating this port object will cause the VM to stay alive and keep
    // the VM service server running until the port is disposed of.
245
    _keepAlivePort = RawReceivePort();
246 247

    // Timeout if nothing bothers to connect and ask us to run the task.
248
    const Duration taskStartTimeout = Duration(seconds: 60);
249
    _startTaskTimeout = Timer(taskStartTimeout, () {
250 251 252 253 254 255 256 257
      if (!_taskStarted) {
        logger.severe('Task did not start in $taskStartTimeout.');
        _closeKeepAlivePort();
        exitCode = 1;
      }
    });
  }

258
  /// Disables the keepalive port, allowing the VM to exit.
259 260 261 262 263
  void _closeKeepAlivePort() {
    _startTaskTimeout?.cancel();
    _keepAlivePort?.close();
  }

264
  Future<TaskResult> _performTask() {
265
    final Completer<TaskResult> completer = Completer<TaskResult>();
266 267 268
    Chain.capture(() async {
      completer.complete(await task());
    }, onError: (dynamic taskError, Chain taskErrorStack) {
269
      final String message = 'Task failed: $taskError';
270 271 272 273
      stderr
        ..writeln(message)
        ..writeln('\nStack trace:')
        ..writeln(taskErrorStack.terse);
274 275 276 277 278
      // IMPORTANT: We're completing the future _successfully_ but with a value
      // that indicates a task failure. This is intentional. At this point we
      // are catching errors coming from arbitrary (and untrustworthy) task
      // code. Our goal is to convert the failure into a readable message.
      // Propagating it further is not useful.
279
      if (!completer.isCompleted)
280
        completer.complete(TaskResult.failure(message));
281 282
    });
    return completer.future;
283 284
  }
}
285 286 287

File _rebootFile() {
  if (Platform.isLinux || Platform.isMacOS) {
288
    return File(path.join(Platform.environment['HOME']!, '.reboot-count'));
289 290 291 292
  }
  if (!Platform.isWindows) {
    throw StateError('Unexpected platform ${Platform.operatingSystem}');
  }
293
  return File(path.join(Platform.environment['USERPROFILE']!, '.reboot-count'));
294
}