Back to build
Raw log | Switch to full mode | Login

cmd.exe /c type .debug.log in dir C:\b\s\w\ir\cache\builder\sdk: allow_subannotations: False cmd: ['cmd.exe', '/c', 'type', '.debug.log'] cwd: 'C:\\b\\s\\w\\ir\\cache\\builder\\sdk' env: FrozenDict([]) env_prefixes: {'mapping': FrozenDict([]), 'pathsep': ';'} env_suffixes: {'mapping': FrozenDict([]), 'pathsep': ';'} infra_step: False name_tokens: ['debug log'] ok_ret: ALL_OK trigger_specs: [] full environment: ALLUSERSPROFILE: C:\ProgramData APPDATA: C:\Users\chrome-bot\AppData\Roaming BOTO_CONFIG: C:\b\s\w\ir\tmp\gsutil-task\.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: C:\b\s\cipd_cache\cache CIPD_EXE_SHIM: "C:\b\s\w\ir\cipd_bin_packages\vpython.exe" CIPD_PROTOCOL: v2 COMMONPROGRAMFILES: C:\Program Files\Common Files COMMONPROGRAMFILES(X86): C:\Program Files (x86)\Common Files COMMONPROGRAMW6432: C:\Program Files\Common Files COMPUTERNAME: LUCI-DART-TRY-W COMSPEC: C:\Windows\system32\cmd.exe DOCKER_CONFIG: C:\b\s\w\ir\tmp\docker-cfg-task DOCKER_TMPDIR: C:\b\s\w\ir\tmp\docker-tmp-task FPS_BROWSER_APP_PROFILE_STRING: Internet Explorer FPS_BROWSER_USER_PROFILE_STRING: Default GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOMEDRIVE: C: HOMEPATH: \Users\chrome-bot INFRA_GIT_WRAPPER_HOME: C:\b\s\w\ir\tmp\git-home-task LC_ALL: en_US.UTF-8 LC_COLLATE: en_US.UTF-8 LC_CTYPE: en_US.UTF-8 LC_MONETARY: en_US.UTF-8 LC_NUMERIC: en_US.UTF-8 LC_TIME: en_US.UTF-8 LOCALAPPDATA: C:\Users\chrome-bot\AppData\Local LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8914009249922132464 LOGDOG_STREAM_PROJECT: dart LOGDOG_STREAM_SERVER_PATH: net.pipe:LUCILogDogKitchen_8788 LOGONSERVER: \\LUCI-DART-TRY-W LUCI_CONTEXT: C:\b\s\w\ir\tmp\luci_context.375671602 MAC_CHROMIUM_TMPDIR: C:\b\s\w\ir\tmp\t NO_GCE_CHECK: False NUMBER_OF_PROCESSORS: 8 ONEDRIVE: C:\Users\chrome-bot\OneDrive OS: Windows_NT PATH: C:\b\s\w\ir\cipd_bin_packages;C:\b\s\w\ir\cipd_bin_packages\bin;C:\b\s\cipd_cache\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files\Puppet Labs\Puppet\bin PATHEXT: .COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE: AMD64 PROCESSOR_IDENTIFIER: Intel64 Family 6 Model 79 Stepping 0, GenuineIntel PROCESSOR_LEVEL: 6 PROCESSOR_REVISION: 4f00 PROGRAMDATA: C:\ProgramData PROGRAMFILES: C:\Program Files PROGRAMFILES(X86): C:\Program Files (x86) PROGRAMW6432: C:\Program Files PROMPT: $P$G PSMODULEPATH: C:\Program Files\WindowsPowerShell\Modules;C:\Windows\system32\WindowsPowerShell\v1.0\Modules PUBLIC: C:\Users\Public PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SESSIONNAME: Console SWARMING_BOT_ID: luci-dart-try-win10-7-m5eh SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 44b13d200fa10f11 SYSTEMDRIVE: C: SYSTEMROOT: C:\Windows TEMP: C:\b\s\w\ir\tmp\t TEMPDIR: C:\b\s\w\ir\tmp\t TMP: C:\b\s\w\ir\tmp\t TMPDIR: C:\b\s\w\ir\tmp\t USERDOMAIN: LUCI-DART-TRY-W USERDOMAIN_ROAMINGPROFILE: LUCI-DART-TRY-W USERNAME: chrome-bot USERPROFILE: C:\Users\chrome-bot VPYTHON_VIRTUALENV_ROOT: C:\b\s\w\ir\cache\vpython WINDIR: C:\Windows 2019-05-08 10:20:39.433785 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:21:09.496270 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:24:08.246416 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:24:38.324561 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:25:08.355851 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:25:38.402730 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:26:08.449608 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:26:38.496533 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:27:08.543427 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:27:38.590343 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:27:38.590343 Warning: Double reporting tests: 2019-05-08 10:27:38.590343 Warning: http://127.0.0.1:57176/root_build/generated_tests/dart2js-chrome-sdk/short1_llocation_profile_element_test/test.html?crossOriginPort=57177, output: 2019-05-08 10:27:38.590343 Warning: [{"type":"debug","value":"test_controller.js started","timestamp":0},{"type":"debug","value":"Sending events to driver page (isFirstMessage = true, isStatusUpdate = true, isDone = false)","timestamp":0.001},{"type":"print","value":"dart-calling-main","timestamp":0.268},{"type":"print","value":"dart-main-done","timestamp":0.362},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation\u001b[0m","timestamp":0.488},{"type":"message_received","value":"dart-calling-main","timestamp":0.546},{"type":"message_received","value":"dart-main-done","timestamp":0.547},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.548},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created\u001b[0m","timestamp":0.565},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: elements created \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":0.695},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":0.712},{"type":"print","value":" element_test.js 1084:17 Object.wrapException\n element_test.js 1600:15 Object.propertyTypeError\n element_test.js 1610:9 Object.interceptedTypeCheck\n element_test.js 27574:28 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 22625:84 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n element_test.js 24946:51 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24935:26 StackZoneSpecification__registerBinaryCallback_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 12986:37 _SyncCompleter.completeError$2\n element_test.js 12552:29 _AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24905:26 StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 3757:18 StaticClosure._rootRun\n ===== asynchronous gap ===========================\n element_test.js 14393:174 _CustomZone.registerCallback$1$1\n element_test.js 14330:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3670:33 Object.scheduleMicrotask\n element_test.js 12538:11 _AsyncAwaitCompleter.completeError$2\n element_test.js 3304:57 Object._asyncRethrow\n element_test.js 22615:20 <fn>\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 22613:62 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 22637:19 HtmlElement._allocation_profile$_refresh$0\n element_test.js 22231:12 HtmlElement.attached$0\n element_test.js 3101:43 Object.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 27146:29 main_closure0.call$0\n element_test.js 25262:53 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25250:29 Declarer_test___closure.call$0\n element_test.js 25590:51 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25579:29 Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 3761:16 StaticClosure._rootRun\n element_test.js 14366:158 _CustomZone.run$1$1\n element_test.js 3932:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25192:29 Declarer_test_closure.call$0\n element_test.js 25751:107 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25742:29 Invoker__onRun____closure.call$0\n element_test.js 12855:50 Future_Future_closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24905:26 StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 14393:174 _CustomZone.registerCallback$1$1\n element_test.js 14330:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3734:44 Object.Timer_Timer\n element_test.js 3343:9 Object.Future_Future\n element_test.js 25700:17 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25689:29 Invoker__onRun___closure.call$0\n element_test.js 3761:16 StaticClosure._rootRun\n element_test.js 14366:158 _CustomZone.run$1$1\n element_test.js 3932:89 Object._runZoned\n ","timestamp":1.082},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: reacts to refresh\u001b[0m","timestamp":1.174},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -2\u001b[0m: reacts to refresh \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":1.217},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":1.218},{"type":"print","value":" element_test.js 1084:17 Object.wrapException\n element_test.js 1600:15 Object.propertyTypeError\n element_test.js 1610:9 Object.interceptedTypeCheck\n element_test.js 27574:28 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 22625:84 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n element_test.js 24946:51 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24935:26 StackZoneSpecification__registerBinaryCallback_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 12986:37 _SyncCompleter.completeError$2\n element_test.js 12552:29 _AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24905:26 StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 3757:18 StaticClosure._rootRun\n ===== asynchronous gap ===========================\n element_test.js 14393:174 _CustomZone.registerCallback$1$1\n element_test.js 14330:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3670:33 Object.scheduleMicrotask\n element_test.js 12538:11 _AsyncAwaitCompleter.completeError$2\n element_test.js 3304:57 Object._asyncRethrow\n element_test.js 22615:20 <fn>\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 22613:62 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 22637:19 HtmlElement._allocation_profile$_refresh$0\n element_test.js 22231:12 HtmlElement.attached$0\n element_test.js 3101:43 Object.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 27234:29 main__closure.$call$body$main__closure0\n element_test.js 27228:19 main__closure.call$0\n element_test.js 25262:53 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25250:29 Declarer_test___closure.call$0\n element_test.js 25590:51 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25579:29 Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 3761:16 StaticClosure._rootRun\n element_test.js 14366:158 _CustomZone.run$1$1\n element_test.js 3932:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25192:29 Declarer_test_closure.call$0\n element_test.js 25751:107 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25742:29 Invoker__onRun____closure.call$0\n element_test.js 12855:50 Future_Future_closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24905:26 StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 14393:174 _CustomZone.registerCallback$1$1\n element_test.js 14330:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3734:44 Object.Timer_Timer\n element_test.js 3343:9 Object.Future_Future\n element_test.js 25700:17 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25689:29 Invoker__onRun___closure.call$0\n element_test.js 3761:16 StaticClosure._rootRun\n element_test.js 14366:158 _CustomZone.run$1$1\n element_test.js 3932:89 Object._runZoned\n ","timestamp":1.65},{"type":"print","value":"00:01 \u001b[32m+1\u001b[0m\u001b[31m -2\u001b[0m: reacts to gc\u001b[0m","timestamp":1.663},{"type":"print","value":"00:01 \u001b[32m+1\u001b[0m\u001b[31m -3\u001b[0m: reacts to gc \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":1.742},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":1.742},{"type":"print","value":" element_test.js 1084:17 Object.wrapException\n element_test.js 1600:15 Object.propertyTypeError\n element_test.js 1610:9 Object.interceptedTypeCheck\n element_test.js 27574:28 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 22625:84 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n element_test.js 24946:51 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24935:26 StackZoneSpecification__registerBinaryCallback_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 12986:37 _SyncCompleter.completeError$2\n element_test.js 12552:29 _AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24905:26 StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 3757:18 StaticClosure._rootRun\n ===== asynchronous gap ===========================\n element_test.js 14393:174 _CustomZone.registerCallback$1$1\n element_test.js 14330:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3670:33 Object.scheduleMicrotask\n element_test.js 12538:11 _AsyncAwaitCompleter.completeError$2\n element_test.js 3304:57 Object._asyncRethrow\n element_test.js 22615:20 <fn>\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 22613:62 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 22637:19 HtmlElement._allocation_profile$_refresh$0\n element_test.js 22231:12 HtmlElement.attached$0\n element_test.js 3101:43 Object.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 27331:29 main__closure0.$call$body$main__closure\n element_test.js 27325:19 main__closure0.call$0\n element_test.js 25262:53 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25250:29 Declarer_test___closure.call$0\n element_test.js 25590:51 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25579:29 Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 3761:16 StaticClosure._rootRun\n element_test.js 14366:158 _CustomZone.run$1$1\n element_test.js 3932:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25192:29 Declarer_test_closure.call$0\n element_test.js 25751:107 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25742:29 Invoker__onRun____closure.call$0\n element_test.js 12855:50 Future_Future_closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24905:26 StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 14393:174 _CustomZone.registerCallback$1$1\n element_test.js 14330:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3734:44 Object.Timer_Timer\n element_test.js 3343:9 Object.Future_Future\n element_test.js 25700:17 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25689:29 Invoker__onRun___closure.call$0\n element_test.js 3761:16 StaticClosure._rootRun\n element_test.js 14366:158 _CustomZone.run$1$1\n element_test.js 3932:89 Object._runZoned\n ","timestamp":2.123},{"type":"print","value":"00:01 \u001b[32m+1\u001b[0m\u001b[31m -3\u001b[0m: reacts to sort change\u001b[0m","timestamp":2.136},{"type":"print","value":"00:01 \u001b[32m+1\u001b[0m\u001b[31m -4\u001b[0m: reacts to sort change \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":2.173},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":2.173},{"type":"print","value":" element_test.js 1084:17 Object.wrapException\n element_test.js 1600:15 Object.propertyTypeError\n element_test.js 1610:9 Object.interceptedTypeCheck\n element_test.js 27574:28 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 22625:84 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n element_test.js 24946:51 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24935:26 StackZoneSpecification__registerBinaryCallback_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 12986:37 _SyncCompleter.completeError$2\n element_test.js 12552:29 _AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24905:26 StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 3757:18 StaticClosure._rootRun\n ===== asynchronous gap ===========================\n element_test.js 14393:174 _CustomZone.registerCallback$1$1\n element_test.js 14330:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3670:33 Object.scheduleMicrotask\n element_test.js 12538:11 _AsyncAwaitCompleter.completeError$2\n element_test.js 3304:57 Object._asyncRethrow\n element_test.js 22615:20 <fn>\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 22613:62 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 22637:19 HtmlElement._allocation_profile$_refresh$0\n element_test.js 22231:12 HtmlElement.attached$0\n element_test.js 3101:43 Object.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 27460:29 main__closure1.call$0\n element_test.js 25262:53 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25250:29 Declarer_test___closure.call$0\n element_test.js 25590:51 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25579:29 Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 3761:16 StaticClosure._rootRun\n element_test.js 14366:158 _CustomZone.run$1$1\n element_test.js 3932:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25192:29 Declarer_test_closure.call$0\n element_test.js 25751:107 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 12574:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25742:29 Invoker__onRun____closure.call$0\n element_test.js 12855:50 Future_Future_closure.call$0\n element_test.js 24865:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 24905:26 StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 14393:174 _CustomZone.registerCallback$1$1\n element_test.js 14330:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3734:44 Object.Timer_Timer\n element_test.js 3343:9 Object.Future_Future\n element_test.js 25700:17 <fn>\n element_test.js 3329:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 14409:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3337:30 Object._wrapJsFunctionForAsync\n element_test.js 25689:29 Invoker__onRun___closure.call$0\n element_test.js 3761:16 StaticClosure._rootRun\n element_test.js 14366:158 _CustomZone.run$1$1\n element_test.js 3932:89 Object._runZoned\n ","timestamp":2.489},{"type":"print","value":"00:02 \u001b[32m+1\u001b[0m\u001b[31m -4\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":2.508},{"type":"print","value":"","timestamp":2.534},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:57176/root_build/generated_tests/dart2js-chrome-sdk/short1_llocation_profile_element_test/element_test.js:14571:\nUncaught \n\n","timestamp":2.536},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":2.536},{"type":"dom","value":"<head>\n <meta http-equiv=\"X-UA-Compatible\" content=\"IE=edge\">\n <meta name=\"dart.unittest\" content=\"full-stack-traces\">\n <style>\n .unittest-table { font-family:monospace; border:1px; }\n .unittest-pass { background: #6b3;}\n .unittest-fail { background: #d55;}\n .unittest-error { background: #a11;}\n </style>\n</head>\n<body>\n <script type=\"text/javascript\" src=\"/root_dart/tools/testing/dart/test_controller.js\"></script>\n <script src=\"element_test.js\"></script>\n\n\n<allocation-profile><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>allocation profile</a><ul></ul></span></li><nav-refresh><li><button disabled=\"\">Download</button></li></nav-refresh><nav-refresh><li><button>Reset Accumulator</button></li></nav-refresh><nav-refresh><li><button>GC</button></li></nav-refresh><nav-refresh><li><button>Refresh</button></li></nav-refresh><div class=\"nav-option\"><input type=\"checkbox\" id=\"allocation-profile-auto-refresh\"><label for=\"allocation-profile-auto-refresh\">Auto-refresh on GC</label></div><nav-notify><div><div></div></div></nav-notify></ul></nav><div class=\"content-centered-big\"><h2>Allocation Profile</h2><hr></div><div class=\"content-centered-big\"><h2>Loading...</h2></div></allocation-profile><allocation-profile><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>allocation profile</a><ul></ul></span></li><nav-refresh><li><button disabled=\"\">Download</button></li></nav-refresh><nav-refresh><li><button>Reset Accumulator</button></li></nav-refresh><nav-refresh><li><button>GC</button></li></nav-refresh><nav-refresh><li><button>Refresh</button></li></nav-refresh><div class=\"nav-option\"><input type=\"checkbox\" id=\"allocation-profile-auto-refresh\"><label for=\"allocation-profile-auto-refresh\">Auto-refresh on GC</label></div><nav-notify><div><div></div></div></nav-notify></ul></nav><div class=\"content-centered-big\"><h2>Allocation Profile</h2><hr></div><div class=\"content-centered-big\"><h2>Loading...</h2></div></allocation-profile><allocation-profile><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>allocation profile</a><ul></ul></span></li><nav-refresh><li><button disabled=\"\">Download</button></li></nav-refresh><nav-refresh><li><button>Reset Accumulator</button></li></nav-refresh><nav-refresh><li><button>GC</button></li></nav-refresh><nav-refresh><li><button>Refresh</button></li></nav-refresh><div class=\"nav-option\"><input type=\"checkbox\" id=\"allocation-profile-auto-refresh\"><label for=\"allocation-profile-auto-refresh\">Auto-refresh on GC</label></div><nav-notify><div><div></div></div></nav-notify></ul></nav><div class=\"content-centered-big\"><h2>Allocation Profile</h2><hr></div><div class=\"content-centered-big\"><h2>Loading...</h2></div></allocation-profile><allocation-profile><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>allocation profile</a><ul></ul></span></li><nav-refresh><li><button disabled=\"\">Download</button></li></nav-refresh><nav-refresh><li><button>Reset Accumulator</button></li></nav-refresh><nav-refresh><li><button>GC</button></li></nav-refresh><nav-refresh><li><button>Refresh</button></li></nav-refresh><div class=\"nav-option\"><input type=\"checkbox\" id=\"allocation-profile-auto-refresh\"><label for=\"allocation-profile-auto-refresh\">Auto-refresh on GC</label></div><nav-notify><div><div></div></div></nav-notify></ul></nav><div class=\"content-centered-big\"><h2>Allocation Profile</h2><hr></div><div class=\"content-centered-big\"><h2>Loading...</h2></div></allocation-profile></body>","timestamp":2.536}] 2019-05-08 10:27:38.590343 Warning: 2019-05-08 10:27:38.590343 Warning: 2019-05-08 10:27:38.590343 Warning: http://127.0.0.1:57176/root_build/generated_tests/dart2js-chrome-sdk/short33__ui_heap_snapshot_element_test/test.html?crossOriginPort=57177, output: 2019-05-08 10:27:38.590343 Warning: [{"type":"debug","value":"test_controller.js started","timestamp":0},{"type":"debug","value":"Sending events to driver page (isFirstMessage = true, isStatusUpdate = true, isDone = false)","timestamp":0.001},{"type":"print","value":"dart-calling-main","timestamp":0.853},{"type":"print","value":"dart-main-done","timestamp":0.9},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation\u001b[0m","timestamp":0.97},{"type":"message_received","value":"dart-calling-main","timestamp":0.985},{"type":"message_received","value":"dart-main-done","timestamp":0.985},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.985},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created\u001b[0m","timestamp":1.005},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: elements created \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":1.066},{"type":"print","value":" Expected: false\n Actual: <true>\n ","timestamp":1.068},{"type":"print","value":" element_test.js 992:17 Object.wrapException\n element_test.js 1005:15 Object.throwExpression\n element_test.js 8322:16 Object.fail\n element_test.js 8319:9 Object._expect\n element_test.js 8297:9 Object.expect\n element_test.js 25005:9 main__closure.call$2\n element_test.js 25030:19 HeapSnapshotRepositoryMock.$get$3$gc$roots\n element_test.js 20186:99 <fn>\n element_test.js 3149:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 11850:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 12254:37 _SyncCompleter.completeError$2\n element_test.js 11832:29 _AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 22841:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22881:26 StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 3577:18 StaticClosure._rootRun\n ===== asynchronous gap ===========================\n element_test.js 13665:174 _CustomZone.registerCallback$1$1\n element_test.js 13602:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3490:33 Object.scheduleMicrotask\n element_test.js 11820:11 _AsyncAwaitCompleter.completeError$2\n element_test.js 3124:57 Object._asyncRethrow\n element_test.js 20180:20 <fn>\n ===== asynchronous gap ===========================\n element_test.js 13681:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3157:30 Object._wrapJsFunctionForAsync\n element_test.js 20178:48 HtmlElement._heap_snapshot$_refresh$0\n element_test.js 20098:12 HtmlElement.attached$0\n element_test.js 2936:43 Object.attached$0$x\n element_test.js 6584:16 StaticClosure._callAttached\n ===== asynchronous gap ===========================\n element_test.js 13681:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3157:30 Object._wrapJsFunctionForAsync\n element_test.js 24942:29 main_closure0.call$0\n element_test.js 23187:53 <fn>\n element_test.js 3149:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 11850:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 13681:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3157:30 Object._wrapJsFunctionForAsync\n element_test.js 23175:29 Declarer_test___closure.call$0\n element_test.js 23494:51 <fn>\n element_test.js 3149:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 11850:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 13681:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3157:30 Object._wrapJsFunctionForAsync\n element_test.js 23483:29 Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 3581:16 StaticClosure._rootRun\n element_test.js 13638:158 _CustomZone.run$1$1\n element_test.js 3752:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 13681:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3157:30 Object._wrapJsFunctionForAsync\n element_test.js 23121:29 Declarer_test_closure.call$0\n element_test.js 23639:116 <fn>\n element_test.js 3149:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 11850:12 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 13681:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3157:30 Object._wrapJsFunctionForAsync\n element_test.js 23630:29 Invoker__onRun____closure.call$0\n element_test.js 12129:50 Future_Future_closure.call$0\n element_test.js 22841:16 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22881:26 StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 13665:174 _CustomZone.registerCallback$1$1\n element_test.js 13602:71 _CustomZone.bindCallbackGuarded$1\n element_test.js 3554:44 Object.Timer_Timer\n element_test.js 3163:9 Object.Future_Future\n element_test.js 23590:17 <fn>\n element_test.js 3149:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 13681:222 _CustomZone.registerBinaryCallback$3$1\n element_test.js 3157:30 Object._wrapJsFunctionForAsync\n element_test.js 23579:29 Invoker__onRun___closure.call$0\n element_test.js 3581:16 StaticClosure._rootRun\n element_test.js 13638:158 _CustomZone.run$1$1\n element_test.js 3752:89 Object._runZoned\n ","timestamp":1.417},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":1.472},{"type":"print","value":"","timestamp":1.472},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:57176/root_build/generated_tests/dart2js-chrome-sdk/short33__ui_heap_snapshot_element_test/element_test.js:13841:\nUncaught \n\n","timestamp":1.474},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":1.474},{"type":"dom","value":"<head>\n <meta http-equiv=\"X-UA-Compatible\" content=\"IE=edge\">\n <meta name=\"dart.unittest\" content=\"full-stack-traces\">\n <style>\n .unittest-table { font-family:monospace; border:1px; }\n .unittest-pass { background: #6b3;}\n .unittest-fail { background: #d55;}\n .unittest-error { background: #a11;}\n </style>\n</head>\n<body>\n <script type=\"text/javascript\" src=\"/root_dart/tools/testing/dart/test_controller.js\"></script>\n <script src=\"element_test.js\"></script>\n\n\n<heap-snapshot><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>heap snapshot</a><ul></ul></span></li><nav-refresh><li><button>Refresh</button></li></nav-refresh><nav-notify><div><div></div></div></nav-notify></ul></nav></heap-snapshot></body>","timestamp":1.474}] 2019-05-08 10:27:38.590343 Warning: 2019-05-08 10:27:38.590343 Warning: 2019-05-08 10:29:15.557843 Warning: HttpServer: could not find file for request path: "/root_build/generated_tests/dart2js-chrome-sdk/tests_compiler_dart2js_extra_deferred_fail_and_retry_test/non_existing.js" 2019-05-08 10:31:00.652981 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:31:30.699861 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:32:00.746729 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:32:30.778065 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:33:00.824956 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:33:30.871846 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:34:00.918754 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2019-05-08 10:34:30.965612 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome)