Performance: infinite import of file

[polina-c]

1. Connect to counter app on android emulator in debug mode
2. Open profile page
3. Increase counter app number of times
4. Explore frames on perfetto tab
5. Export screen
6. Drag the saved file to screen
7. Get infinite progress indicator

json: go/dart-devtools-2023-01-26-14-16-31.516.json

Flutter 3.7.0-24.0.pre • channel flutter-3.7-candidate.24 • https://github.com/flutter/flutter.git
Framework • revision 332aed9c88 (3 days ago) • 2023-01-23 13:45:07 -0800
Engine • revision 94fc0728f3
Tools • Dart 3.0.0 (build 3.0.0-154.0.dev) • DevTools 2.20.1

[polina-c]

Chrome console:

It's safe to ignore the ERR_CONNECTION_REFUSED on http://127.0.0.1:9001/ below. That might happen while probing the external native accelerator. The error is non-fatal and unlikely to be the culprit for any UI bug.
127.0.0.1:9001/status:1          Failed to load resource: net::ERR_CONNECTION_REFUSED
accounts.google.com/v3/signin/identifier?dsh=S-2046193855%3A1674762245190100&continue=https%3A%2F%2Fstorage.cloud.google.com%2Fperfetto-ui-internal%2Fis_internal_user.js&followup=https%3A%2F%2Fstorage.cloud.google.com%2Fperfetto-ui-internal%2Fis_internal_user.js&passive=1209600&service=cds&flowName=GlifWebSignIn&flowEntry=ServiceLogin&ifkv=AWnogHciFJhHd-sz3b1na0ETmo9mJ929te7J445BnHV9YtAL-_VxSKo8G4DU97-3Js3CmLDToEPd:1          Failed to load resource: the server responded with a status of 403 ()
​ It's safe to ignore the ERR_CONNECTION_REFUSED on http://127.0.0.1:9001/ below. That might happen while probing the external native accelerator. The error is non-fatal and unlikely to be the culprit for any UI bug.
127.0.0.1:9001/status:1          Failed to load resource: net::ERR_CONNECTION_REFUSED
​ Opening trace using built-in WASM engine
​ 
Loading trace 1.16 MB (19.0 MB/s)
appendAndLogErr @ :60085/assets/packag…gine_bundle.js:6704
​ b/186265930: Bad pxToSec suppressed Infinity
getCurResolution @ :60085/assets/packag…tend_bundle.js:5257
updateResolution @ :60085/assets/packag…tend_bundle.js:4114
updateLocalTime @ :60085/assets/packag…tend_bundle.js:4110
mergeState @ :60085/assets/packag…tend_bundle.js:4088
dispatchMultiple @ :60085/assets/packag…nd_bundle.js:368316
dispatch @ :60085/assets/packag…nd_bundle.js:368450
dispatch @ :60085/assets/packag…nd_bundle.js:326822
dispatchMultiple @ :60085/assets/packag…nd_bundle.js:326829
(anonymous) @ :60085/assets/packag…nd_bundle.js:350038
fulfilled @ :60085/assets/packag…tend_bundle.js:4526
Promise.then (async)
step @ :60085/assets/packag…tend_bundle.js:4528
fulfilled @ :60085/assets/packag…tend_bundle.js:4526
Promise.then (async)
step @ :60085/assets/packag…tend_bundle.js:4528
fulfilled @ :60085/assets/packag…tend_bundle.js:4526
Promise.then (async)
step @ :60085/assets/packag…tend_bundle.js:4528
fulfilled @ :60085/assets/packag…tend_bundle.js:4526
Promise.then (async)
step @ :60085/assets/packag…tend_bundle.js:4528
fulfilled @ :60085/assets/packag…tend_bundle.js:4526
Promise.then (async)
step @ :60085/assets/packag…tend_bundle.js:4528
fulfilled @ :60085/assets/packag…tend_bundle.js:4526
Promise.then (async)
step @ :60085/assets/packag…tend_bundle.js:4528
fulfilled @ :60085/assets/packag…tend_bundle.js:4526
Promise.then (async)
step @ :60085/assets/packag…tend_bundle.js:4528
fulfilled @ :60085/assets/packag…tend_bundle.js:4526
Promise.then (async)
step @ :60085/assets/packag…tend_bundle.js:4528
(anonymous) @ :60085/assets/packag…tend_bundle.js:4529
__awaiter @ :60085/assets/packag…tend_bundle.js:4525
loadTrace @ :60085/assets/packag…nd_bundle.js:349904
run @ :60085/assets/packag…nd_bundle.js:349810
invoke @ :60085/assets/packag…nd_bundle.js:326736
(anonymous) @ :60085/assets/packag…nd_bundle.js:326763
invoke @ :60085/assets/packag…nd_bundle.js:326769
runControllers @ :60085/assets/packag…nd_bundle.js:326848
patchState @ :60085/assets/packag…nd_bundle.js:326836
controllerPort.onmessage @ :60085/assets/packag…nd_bundle.js:350569
html_dart2js.dart:39931 Assertion failed: file:///Users/polinach/_/devtools/packages/devtools_app/lib/src/screens/performance/panes/timeline_events/timeline_event_processor.dart:236:18
current != null && event.name != current.name
is not true
error @ html_dart2js.dart:39931
log @ logger_html.dart:18
_reportError @ app_error_handling.dart:50
(anonymous) @ app_error_handling.dart:42
runBinary @ zone.dart:1665
(anonymous) @ zone.dart:1775
[_processUncaughtError] @ zone.dart:1080
handleUncaughtError @ zone.dart:1284
_propagateToListeners @ future_impl.dart:680
_propagateToListeners @ future_impl.dart:692
[_completeError] @ future_impl.dart:575
(anonymous) @ future_impl.dart:666
_rootRun @ zone.dart:1398
run @ zone.dart:1300
runGuarded @ zone.dart:1208
(anonymous) @ zone.dart:1248
_microtaskLoop @ schedule_microtask.dart:40
_startMicrotaskLoop @ schedule_microtask.dart:49
(anonymous) @ async_patch.dart:177
Promise.then (async)
_scheduleImmediateWithPromise @ async_patch.dart:175
_scheduleImmediate @ async_patch.dart:147
_scheduleAsyncCallback @ schedule_microtask.dart:69
_rootScheduleMicrotask @ zone.dart:1465
scheduleMicrotask @ zone.dart:1353
[_asyncCompleteError] @ future_impl.dart:665
_asyncCompleteWithErrorCallback @ future.dart:1304
runBody @ async_patch.dart:117
_async @ async_patch.dart:123
processData @ timeline_event_processor.dart:73
_processAllTraceEvents @ timeline_events_controller.dart:281
runBody @ async_patch.dart:84
_async @ async_patch.dart:123
[_processAllTraceEvents] @ timeline_events_controller.dart:279
track @ future_work_tracker.dart:43
(anonymous) @ async_patch.dart:45
_rootRunUnary @ zone.dart:1406
runUnary @ zone.dart:1307
handleValue @ future_impl.dart:147
handleValueCallback @ future_impl.dart:767
_propagateToListeners @ future_impl.dart:796
[_complete] @ future_impl.dart:558
(anonymous) @ future.dart:421
_rootRun @ zone.dart:1390
run @ zone.dart:1300
runGuarded @ zone.dart:1208
(anonymous) @ zone.dart:1248
_rootRun @ zone.dart:1398
run @ zone.dart:1300
(anonymous) @ zone.dart:1232
internalCallback @ isolate_helper.dart:48
setTimeout (async)
_isolate_helper.TimerImpl.new @ isolate_helper.dart:55
_createTimer @ async_patch.dart:188
_rootCreateTimer @ zone.dart:1473
createTimer @ zone.dart:1360
new @ timer.dart:54
delayed @ future.dart:419
delayToReleaseUiThread @ utils.dart:230
runBody @ async_patch.dart:84
_async @ async_patch.dart:123
delayToReleaseUiThread @ utils.dart:227
track @ future_work_tracker.dart:41
runBody @ async_patch.dart:84
_async @ async_patch.dart:123
track @ future_work_tracker.dart:36
processAllTraceEvents @ timeline_events_controller.dart:276
runBody @ async_patch.dart:84
_async @ async_patch.dart:123
processAllTraceEvents @ timeline_events_controller.dart:275
setOfflineData @ timeline_events_controller.dart:531
runBody @ async_patch.dart:84
_async @ async_patch.dart:123
setOfflineData @ timeline_events_controller.dart:516
(anonymous) @ performance_controller.dart:244
_helper @ performance_controller.dart:217
runBody @ async_patch.dart:84
_async @ async_patch.dart:123
_helper @ performance_controller.dart:213
_applyToFeatureControllersAsync @ performance_controller.dart:222
runBody @ async_patch.dart:84
_async @ async_patch.dart:123
[_applyToFeatureControllersAsync] @ performance_controller.dart:210
processOfflineData @ performance_controller.dart:243
(anonymous) @ async_patch.dart:45
_rootRunUnary @ zone.dart:1406
runUnary @ zone.dart:1307
handleValue @ future_impl.dart:147
handleValueCallback @ future_impl.dart:767
_propagateToListeners @ future_impl.dart:796
[_completeWithValue] @ future_impl.dart:567
(anonymous) @ future.dart:528
_rootRunUnary @ zone.dart:1406
runUnary @ zone.dart:1307
handleValue @ future_impl.dart:147
handleValueCallback @ future_impl.dart:767
_propagateToListeners @ future_impl.dart:796
(anonymous) @ future_impl.dart:431
_rootRun @ zone.dart:1398
run @ zone.dart:1300
runGuarded @ zone.dart:1208
(anonymous) @ zone.dart:1248
_microtaskLoop @ schedule_microtask.dart:40
_startMicrotaskLoop @ schedule_microtask.dart:49
(anonymous) @ async_patch.dart:177
Promise.then (async)
_scheduleImmediateWithPromise @ async_patch.dart:175
_scheduleImmediate @ async_patch.dart:147
_scheduleAsyncCallback @ schedule_microtask.dart:69
_rootScheduleMicrotask @ zone.dart:1465
scheduleMicrotask @ zone.dart:1680
[_asyncCompleteWithValue] @ future_impl.dart:639
[_asyncComplete] @ future_impl.dart:599
complete @ future_impl.dart:36
[_complete] @ ticker.dart:402
stop @ ticker.dart:218
stop @ animation_controller.dart:777
[_tick] @ animation_controller.dart:827
[_tick] @ ticker.dart:249
[_invokeFrameCallback] @ binding.dart:1286
(anonymous) @ binding.dart:1138
forEach @ linked_hash_map.dart:21
handleBeginFrame @ binding.dart:1136
[_handleBeginFrame] @ binding.dart:1053
_rootRunUnary @ zone.dart:1414
runUnary @ zone.dart:1307
runUnaryGuarded @ zone.dart:1216
invoke1 @ platform_dispatcher.dart:1209
invokeOnBeginFrame @ platform_dispatcher.dart:198
(anonymous) @ initialization.dart:181
_checkAndCall @ operations.dart:367
dcall @ operations.dart:372
ret @ js_patch.dart:415
requestAnimationFrame (async)
callMethod$ @ js_util_patch.dart:89
DomWindowExtension$124requestAnimationFrame @ dom.dart:50
(anonymous) @ initialization.dart:160
scheduleFrame @ platform_dispatcher.dart:617
scheduleFrame @ binding.dart:870
scheduleFrameCallback @ binding.dart:568
scheduleTick @ ticker.dart:265
[_tick] @ ticker.dart:254
[_invokeFrameCallback] @ binding.dart:1286
(anonymous) @ binding.dart:1138
forEach @ linked_hash_map.dart:21
handleBeginFrame @ binding.dart:1136
[_handleBeginFrame] @ binding.dart:1053
_rootRunUnary @ zone.dart:1414
runUnary @ zone.dart:1307
runUnaryGuarded @ zone.dart:1216
invoke1 @ platform_dispatcher.dart:1209
invokeOnBeginFrame @ platform_dispatcher.dart:198
(anonymous) @ initialization.dart:181
_checkAndCall @ operations.dart:367
dcall @ operations.dart:372
ret @ js_patch.dart:415
requestAnimationFrame (async)
callMethod$ @ js_util_patch.dart:89
DomWindowExtension$124requestAnimationFrame @ dom.dart:50
(anonymous) @ initialization.dart:160
scheduleFrame @ platform_dispatcher.dart:617
scheduleFrame @ binding.dart:870
scheduleFrameCallback @ binding.dart:568
scheduleTick @ ticker.dart:265
[_tick] @ ticker.dart:254
[_invokeFrameCallback] @ binding.dart:1286
(anonymous) @ binding.dart:1138
forEach @ linked_hash_map.dart:21
handleBeginFrame @ binding.dart:1136
[_handleBeginFrame] @ binding.dart:1053
_rootRunUnary @ zone.dart:1414
runUnary @ zone.dart:1307
runUnaryGuarded @ zone.dart:1216
invoke1 @ platform_dispatcher.dart:1209
invokeOnBeginFrame @ platform_dispatcher.dart:198
(anonymous) @ initialization.dart:181
_checkAndCall @ operations.dart:367
dcall @ operations.dart:372
ret @ js_patch.dart:415
requestAnimationFrame (async)
callMethod$ @ js_util_patch.dart:89
DomWindowExtension$124requestAnimationFrame @ dom.dart:50
(anonymous) @ initialization.dart:160
scheduleFrame @ platform_dispatcher.dart:617
scheduleFrame @ binding.dart:870
ensureVisualUpdate @ binding.dart:823
[_handleBuildScheduled] @ binding.dart:753
scheduleBuildFor @ framework.dart:2590
markNeedsBuild @ framework.dart:4675
setState @ framework.dart:1153
handleStatesControllerChange @ ink_well.dart:810
notifyListeners @ change_notifier.dart:381
update @ material_state.dart:751
updateHighlight @ ink_well.dart:910
handleHoverChange @ ink_well.dart:1172
handleMouseEnter @ ink_well.dart:1160
_handleDeviceUpdateMouseEvents @ mouse_tracker.dart:410
[_handleDeviceUpdate] @ mouse_tracker.dart:271
(anonymous) @ mouse_tracker.dart:331
[_deviceUpdatePhase] @ mouse_tracker.dart:200
(anonymous) @ mouse_tracker.dart:308
[_monitorMouseConnection] @ mouse_tracker.dart:183
updateWithEvent @ mouse_tracker.dart:307
dispatchEvent @ binding.dart:325
[_handlePointerEventImmediately] @ binding.dart:391
handlePointerEvent @ binding.dart:354
[_flushPointerEventQueue] @ binding.dart:311
[_handlePointerDataPacket] @ binding.dart:292
invoke1 @ platform_dispatcher.dart:1207
invokeOnPointerDataPacket @ platform_dispatcher.dart:244
[_onPointerData] @ pointer_binding.dart:167
(anonymous) @ pointer_binding.dart:755
(anonymous) @ pointer_binding.dart:707
loggedHandler @ pointer_binding.dart:317
(anonymous) @ pointer_binding.dart:199
_checkAndCall @ operations.dart:367
dcall @ operations.dart:372
ret @ js_patch.dart:415
html_dart2js.dart:39931 Assertion failed: file:///Users/polinach/_/devtools/packages/devtools_app/lib/src/screens/performance/panes/timeline_events/timeline_event_processor.dart:236:18
current != null && event.name != current.name
is not true

[polina-c]

[kenzieschmoll]

Still digging into this to find the problem. It appears that we are duplicating the trace when we record it, and then our processing code is having to de-dupe and is failing. The solution to this will be to figure out where we are duplicating recording the trace for export.