// Copyright 2014 The Flutter Authors. All rights reserved. // Use of this source code is governed by a BSD-style license that can be // found in the LICENSE file. import 'package:file/memory.dart'; import 'package:file_testing/file_testing.dart'; import 'package:flutter_tools/src/base/file_system.dart'; import 'package:flutter_tools/src/base/logger.dart'; import 'package:flutter_tools/src/convert.dart'; import 'package:flutter_tools/src/tracing.dart'; import 'package:flutter_tools/src/vmservice.dart'; import 'package:vm_service/vm_service.dart' as vm_service; import '../src/common.dart'; final vm_service.Isolate fakeUnpausedIsolate = vm_service.Isolate( id: '1', pauseEvent: vm_service.Event( kind: vm_service.EventKind.kResume, timestamp: 0 ), breakpoints: [], exceptionPauseMode: null, libraries: [ vm_service.LibraryRef( id: '1', uri: 'file:///hello_world/main.dart', name: '', ), ], livePorts: 0, name: 'test', number: '1', pauseOnExit: false, runnable: true, startTime: 0, isSystemIsolate: false, ); final FlutterView fakeFlutterView = FlutterView( id: 'a', uiIsolate: fakeUnpausedIsolate, ); final FakeVmServiceRequest listViews = FakeVmServiceRequest( method: kListViewsMethod, jsonResponse: { 'views': [ fakeFlutterView.toJson(), ], }, ); final List vmServiceSetup = [ const FakeVmServiceRequest( method: 'streamListen', args: { 'streamId': vm_service.EventKind.kExtension, } ), listViews, // Satisfies didAwaitFirstFrame const FakeVmServiceRequest( method: 'ext.flutter.didSendFirstFrameRasterizedEvent', args: { 'isolateId': '1', }, jsonResponse: { 'enabled': 'true' }, ), ]; void main() { testWithoutContext('Can trace application startup', () async { final BufferLogger logger = BufferLogger.test(); final FileSystem fileSystem = MemoryFileSystem.test(); final FakeVmServiceHost fakeVmServiceHost = FakeVmServiceHost(requests: [ ...vmServiceSetup, FakeVmServiceRequest( method: 'getVMTimeline', jsonResponse: vm_service.Timeline( timeExtentMicros: 4, timeOriginMicros: 0, traceEvents: [ vm_service.TimelineEvent.parse({ 'name': kFlutterEngineMainEnterEventName, 'ts': 0, }), vm_service.TimelineEvent.parse({ 'name': kFrameworkInitEventName, 'ts': 1, }), vm_service.TimelineEvent.parse({ 'name': kFirstFrameBuiltEventName, 'ts': 2, }), vm_service.TimelineEvent.parse({ 'name': kFirstFrameRasterizedEventName, 'ts': 3, }), ], ).toJson(), ), const FakeVmServiceRequest( method: 'setVMTimelineFlags', args: { 'recordedStreams': [], }, ), ]); // Validate that old tracing data is deleted. final File outFile = fileSystem.currentDirectory.childFile('start_up_info.json') ..writeAsStringSync('stale'); await downloadStartupTrace(fakeVmServiceHost.vmService, output: fileSystem.currentDirectory, logger: logger, ); expect(outFile, exists); expect(json.decode(outFile.readAsStringSync()), { 'engineEnterTimestampMicros': 0, 'timeToFrameworkInitMicros': 1, 'timeToFirstFrameRasterizedMicros': 3, 'timeToFirstFrameMicros': 2, 'timeAfterFrameworkInitMicros': 1, }); }); testWithoutContext('throws tool exit if timeline is missing the engine start event', () async { final BufferLogger logger = BufferLogger.test(); final FileSystem fileSystem = MemoryFileSystem.test(); final FakeVmServiceHost fakeVmServiceHost = FakeVmServiceHost(requests: [ ...vmServiceSetup, FakeVmServiceRequest( method: 'getVMTimeline', jsonResponse: vm_service.Timeline( timeExtentMicros: 4, timeOriginMicros: 0, traceEvents: [], ).toJson(), ), const FakeVmServiceRequest( method: 'setVMTimelineFlags', args: { 'recordedStreams': [], }, ), ]); await expectLater(() async => await downloadStartupTrace(fakeVmServiceHost.vmService, output: fileSystem.currentDirectory, logger: logger, ), throwsToolExit(message: 'Engine start event is missing in the timeline')); }); testWithoutContext('throws tool exit if first frame events are missing', () async { final BufferLogger logger = BufferLogger.test(); final FileSystem fileSystem = MemoryFileSystem.test(); final FakeVmServiceHost fakeVmServiceHost = FakeVmServiceHost(requests: [ ...vmServiceSetup, FakeVmServiceRequest( method: 'getVMTimeline', jsonResponse: vm_service.Timeline( timeExtentMicros: 4, timeOriginMicros: 0, traceEvents: [ vm_service.TimelineEvent.parse({ 'name': kFlutterEngineMainEnterEventName, 'ts': 0, }), vm_service.TimelineEvent.parse({ 'name': kFrameworkInitEventName, 'ts': 1, }), ], ).toJson(), ), const FakeVmServiceRequest( method: 'setVMTimelineFlags', args: { 'recordedStreams': [], }, ), ]); await expectLater(() async => await downloadStartupTrace(fakeVmServiceHost.vmService, output: fileSystem.currentDirectory, logger: logger, ), throwsToolExit(message: 'First frame events are missing in the timeline')); }); testWithoutContext('Can trace application startup without awaiting for first frame', () async { final BufferLogger logger = BufferLogger.test(); final FileSystem fileSystem = MemoryFileSystem.test(); final FakeVmServiceHost fakeVmServiceHost = FakeVmServiceHost(requests: [ FakeVmServiceRequest( method: 'getVMTimeline', jsonResponse: vm_service.Timeline( timeExtentMicros: 4, timeOriginMicros: 0, traceEvents: [ vm_service.TimelineEvent.parse({ 'name': kFlutterEngineMainEnterEventName, 'ts': 0, }), vm_service.TimelineEvent.parse({ 'name': kFrameworkInitEventName, 'ts': 1, }), ], ).toJson(), ), const FakeVmServiceRequest( method: 'setVMTimelineFlags', args: { 'recordedStreams': [], }, ), ]); final File outFile = fileSystem.currentDirectory.childFile('start_up_info.json'); await downloadStartupTrace(fakeVmServiceHost.vmService, output: fileSystem.currentDirectory, logger: logger, awaitFirstFrame: false, ); expect(outFile, exists); expect(json.decode(outFile.readAsStringSync()), { 'engineEnterTimestampMicros': 0, 'timeToFrameworkInitMicros': 1, }); }); testWithoutContext('downloadStartupTrace also downloads the timeline', () async { final BufferLogger logger = BufferLogger.test(); final FileSystem fileSystem = MemoryFileSystem.test(); final FakeVmServiceHost fakeVmServiceHost = FakeVmServiceHost(requests: [ ...vmServiceSetup, FakeVmServiceRequest( method: 'getVMTimeline', jsonResponse: vm_service.Timeline( timeExtentMicros: 4, timeOriginMicros: 0, traceEvents: [ vm_service.TimelineEvent.parse({ 'name': kFlutterEngineMainEnterEventName, 'ts': 0, }), vm_service.TimelineEvent.parse({ 'name': kFrameworkInitEventName, 'ts': 1, }), vm_service.TimelineEvent.parse({ 'name': kFirstFrameBuiltEventName, 'ts': 2, }), vm_service.TimelineEvent.parse({ 'name': kFirstFrameRasterizedEventName, 'ts': 3, }), ], ).toJson(), ), const FakeVmServiceRequest( method: 'setVMTimelineFlags', args: { 'recordedStreams': [], }, ), ]); // Validate that old tracing data is deleted. final File timelineFile = fileSystem.currentDirectory.childFile('start_up_timeline.json') ..writeAsStringSync('stale'); await downloadStartupTrace(fakeVmServiceHost.vmService, output: fileSystem.currentDirectory, logger: logger, ); final Map expectedTimeline = { 'type': 'Timeline', 'traceEvents': [ { 'name': 'FlutterEngineMainEnter', 'ts': 0, 'type': 'TimelineEvent', }, { 'name': 'Framework initialization', 'ts': 1, 'type': 'TimelineEvent', }, { 'name': 'Widgets built first useful frame', 'ts': 2, 'type': 'TimelineEvent', }, { 'name': 'Rasterized first useful frame', 'ts': 3, 'type': 'TimelineEvent', }, ], 'timeOriginMicros': 0, 'timeExtentMicros': 4, }; expect(timelineFile, exists); expect(json.decode(timelineFile.readAsStringSync()), expectedTimeline); }); }