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 base_name: 'debug log' cmd: ['cmd.exe', '/c', 'type', '.debug.log'] cwd: 'C:\\b\\s\\w\\ir\\cache\\builder\\sdk' env: {} env_prefixes: {} env_suffixes: {} infra_step: False name: 'debug log' nest_level: 0 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: WIN10-DART-FEDC 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: English_United States.UTF-8 LC_COLLATE: English_United States.UTF-8 LC_CTYPE: English_United States.UTF-8 LC_MONETARY: English_United States.UTF-8 LC_NUMERIC: English_United States.UTF-8 LC_TIME: English_United States.UTF-8 LOCALAPPDATA: C:\Users\chrome-bot\AppData\Local LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8927972307800450144 LOGDOG_STREAM_PROJECT: dart LOGDOG_STREAM_SERVER_PATH: net.pipe:LUCILogDogKitchen_8028 LOGONSERVER: \\WIN10-DART-FEDC LUCI_CONTEXT: C:\b\s\w\itwdkglg\luci_context.050101542 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;C:\b\s\w\ir\cache\vpython\691c07\lib\site-packages\pywin32_system32 PATHEXT: .COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE: AMD64 PROCESSOR_IDENTIFIER: Intel64 Family 6 Model 63 Stepping 0, GenuineIntel PROCESSOR_LEVEL: 6 PROCESSOR_REVISION: 3f00 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: win10-dart-fedce810-us-central1-c-j1g0 SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 419787dae7a23c11 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: WIN10-DART-FEDC USERDOMAIN_ROAMINGPROFILE: WIN10-DART-FEDC USERNAME: chrome-bot USERPROFILE: C:\Users\chrome-bot VPYTHON_VIRTUALENV_ROOT: C:\b\s\w\ir\cache\vpython WINDIR: C:\Windows 2018-12-05 06:33:08.272167 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) 2018-12-05 06:33:38.318979 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) 2018-12-05 06:34:08.350269 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) 2018-12-05 06:36:55.256581 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) 2018-12-05 06:37:25.287819 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) 2018-12-05 06:37:55.319087 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) 2018-12-05 06:38:25.350327 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) 2018-12-05 06:38:55.381597 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) 2018-12-05 06:39:25.412829 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) 2018-12-05 06:39:55.444095 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) 2018-12-05 06:40:25.472407 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) 2018-12-05 06:40:25.477277 Warning: Double reporting tests: 2018-12-05 06:40:25.477277 Warning: http://127.0.0.1:50410/root_build/generated_tests/dart2js-chrome-sdk/short1_llocation_profile_element_test/test.html?crossOriginPort=50411, output: 2018-12-05 06:40:25.477277 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},{"type":"print","value":"dart-calling-main","timestamp":0.252},{"type":"print","value":"dart-main-done","timestamp":0.291},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation\u001b[0m","timestamp":0.363},{"type":"message_received","value":"dart-calling-main","timestamp":0.378},{"type":"message_received","value":"dart-main-done","timestamp":0.378},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.378},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created\u001b[0m","timestamp":0.398},{"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.482},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":0.483},{"type":"print","value":" element_test.js 2896:17 Object.wrapException\n element_test.js 3376:15 Object.propertyTypeError\n element_test.js 3386:9 Object.interceptedTypeCheck\n element_test.js 25891:30 AllocationProfileRepositoryMock.$get$4$combine$gc$reset\n element_test.js 25897:21 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 19650:86 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 22749:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n ===== asynchronous gap ===========================\n element_test.js 6571:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 6134:31 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22711:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5815:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8182:176 _CustomZone.registerCallback$1$1\n element_test.js 8118:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5732:33 Object.scheduleMicrotask\n element_test.js 6121:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5478:57 Object._asyncRethrow\n element_test.js 19640:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 19638:64 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 19662:21 HtmlElement._allocation_profile$_refresh$0\n element_test.js 19258:14 HtmlElement.attached$0\n element_test.js 26196:41 Object.J.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 25439:31 main_closure0.call$0\n element_test.js 23167:55 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23155:31 Declarer_test___closure.call$0\n element_test.js 23501:53 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23490:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5819:16 StaticClosure.dart._rootRun\n element_test.js 8155:160 _CustomZone.run$1$1\n element_test.js 6004:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23101:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 23648:118 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23639:31 Invoker__onRun____closure.call$0\n element_test.js 6441:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22711:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8182:176 _CustomZone.registerCallback$1$1\n element_test.js 8118:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5795:44 Object.Timer_Timer\n element_test.js 5520:9 Object.Future_Future\n element_test.js 23599:19 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23588:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5819:16 StaticClosure.dart._rootRun\n element_test.js 8155:160 _CustomZone.run$1$1\n element_test.js 6004:89 Object._runZoned\n ","timestamp":0.771},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: reacts to refresh\u001b[0m","timestamp":0.91},{"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":0.951},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":0.951},{"type":"print","value":" element_test.js 2896:17 Object.wrapException\n element_test.js 3376:15 Object.propertyTypeError\n element_test.js 3386:9 Object.interceptedTypeCheck\n element_test.js 25891:30 AllocationProfileRepositoryMock.$get$4$combine$gc$reset\n element_test.js 25897:21 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 19650:86 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 22749:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n ===== asynchronous gap ===========================\n element_test.js 6571:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 6134:31 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22711:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5815:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8182:176 _CustomZone.registerCallback$1$1\n element_test.js 8118:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5732:33 Object.scheduleMicrotask\n element_test.js 6121:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5478:57 Object._asyncRethrow\n element_test.js 19640:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 19638:64 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 19662:21 HtmlElement._allocation_profile$_refresh$0\n element_test.js 19258:14 HtmlElement.attached$0\n element_test.js 26196:41 Object.J.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 25524:31 main__closure.call$0\n element_test.js 23167:55 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23155:31 Declarer_test___closure.call$0\n element_test.js 23501:53 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23490:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5819:16 StaticClosure.dart._rootRun\n element_test.js 8155:160 _CustomZone.run$1$1\n element_test.js 6004:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23101:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 23648:118 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23639:31 Invoker__onRun____closure.call$0\n element_test.js 6441:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22711:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8182:176 _CustomZone.registerCallback$1$1\n element_test.js 8118:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5795:44 Object.Timer_Timer\n element_test.js 5520:9 Object.Future_Future\n element_test.js 23599:19 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23588:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5819:16 StaticClosure.dart._rootRun\n element_test.js 8155:160 _CustomZone.run$1$1\n element_test.js 6004:89 Object._runZoned\n ","timestamp":1.174},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -2\u001b[0m: reacts to gc\u001b[0m","timestamp":1.193},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -3\u001b[0m: reacts to gc \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":1.242},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":1.242},{"type":"print","value":" element_test.js 2896:17 Object.wrapException\n element_test.js 3376:15 Object.propertyTypeError\n element_test.js 3386:9 Object.interceptedTypeCheck\n element_test.js 25891:30 AllocationProfileRepositoryMock.$get$4$combine$gc$reset\n element_test.js 25897:21 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 19650:86 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 22749:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n ===== asynchronous gap ===========================\n element_test.js 6571:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 6134:31 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22711:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5815:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8182:176 _CustomZone.registerCallback$1$1\n element_test.js 8118:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5732:33 Object.scheduleMicrotask\n element_test.js 6121:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5478:57 Object._asyncRethrow\n element_test.js 19640:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 19638:64 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 19662:21 HtmlElement._allocation_profile$_refresh$0\n element_test.js 19258:14 HtmlElement.attached$0\n element_test.js 26196:41 Object.J.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 25619:31 main__closure0.call$0\n element_test.js 23167:55 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23155:31 Declarer_test___closure.call$0\n element_test.js 23501:53 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23490:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5819:16 StaticClosure.dart._rootRun\n element_test.js 8155:160 _CustomZone.run$1$1\n element_test.js 6004:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23101:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 23648:118 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23639:31 Invoker__onRun____closure.call$0\n element_test.js 6441:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22711:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8182:176 _CustomZone.registerCallback$1$1\n element_test.js 8118:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5795:44 Object.Timer_Timer\n element_test.js 5520:9 Object.Future_Future\n element_test.js 23599:19 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23588:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5819:16 StaticClosure.dart._rootRun\n element_test.js 8155:160 _CustomZone.run$1$1\n element_test.js 6004:89 Object._runZoned\n ","timestamp":1.517},{"type":"print","value":"00:01 \u001b[32m+1\u001b[0m\u001b[31m -3\u001b[0m: reacts to sort change\u001b[0m","timestamp":1.527},{"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":1.545},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":1.545},{"type":"print","value":" element_test.js 2896:17 Object.wrapException\n element_test.js 3376:15 Object.propertyTypeError\n element_test.js 3386:9 Object.interceptedTypeCheck\n element_test.js 25891:30 AllocationProfileRepositoryMock.$get$4$combine$gc$reset\n element_test.js 25897:21 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 19650:86 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 22749:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n ===== asynchronous gap ===========================\n element_test.js 6571:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 6134:31 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22711:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5815:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8182:176 _CustomZone.registerCallback$1$1\n element_test.js 8118:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5732:33 Object.scheduleMicrotask\n element_test.js 6121:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5478:57 Object._asyncRethrow\n element_test.js 19640:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 19638:64 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 19662:21 HtmlElement._allocation_profile$_refresh$0\n element_test.js 19258:14 HtmlElement.attached$0\n element_test.js 26196:41 Object.J.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 25747:31 main__closure1.call$0\n element_test.js 23167:55 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23155:31 Declarer_test___closure.call$0\n element_test.js 23501:53 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23490:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5819:16 StaticClosure.dart._rootRun\n element_test.js 8155:160 _CustomZone.run$1$1\n element_test.js 6004:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23101:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 23648:118 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6152:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23639:31 Invoker__onRun____closure.call$0\n element_test.js 6441:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 22670:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 22711:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8182:176 _CustomZone.registerCallback$1$1\n element_test.js 8118:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5795:44 Object.Timer_Timer\n element_test.js 5520:9 Object.Future_Future\n element_test.js 23599:19 <fn>\n element_test.js 5506:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8198:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5514:30 Object._wrapJsFunctionForAsync\n element_test.js 23588:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5819:16 StaticClosure.dart._rootRun\n element_test.js 8155:160 _CustomZone.run$1$1\n element_test.js 6004:89 Object._runZoned\n ","timestamp":1.855},{"type":"print","value":"00:01 \u001b[32m+1\u001b[0m\u001b[31m -4\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":1.875},{"type":"print","value":"","timestamp":1.876},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:50410/root_build/generated_tests/dart2js-chrome-sdk/short1_llocation_profile_element_test/element_test.js:8283:\nUncaught \n\n","timestamp":1.877},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":1.877},{"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":1.878}] 2018-12-05 06:40:25.477277 Warning: 2018-12-05 06:40:25.477277 Warning: 2018-12-05 06:40:25.477277 Warning: http://127.0.0.1:50410/root_build/generated_tests/dart2js-chrome-sdk/short33__ui_heap_snapshot_element_test/test.html?crossOriginPort=50411, output: 2018-12-05 06:40:25.477277 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.255},{"type":"print","value":"dart-main-done","timestamp":0.344},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation\u001b[0m","timestamp":0.491},{"type":"message_received","value":"dart-calling-main","timestamp":0.513},{"type":"message_received","value":"dart-main-done","timestamp":0.513},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.513},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created\u001b[0m","timestamp":0.532},{"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.585},{"type":"print","value":" Expected: false\n Actual: <true>\n ","timestamp":0.586},{"type":"print","value":" element_test.js 2734:17 Object.wrapException\n element_test.js 2747:15 Object.throwExpression\n element_test.js 22119:16 Object.fail\n element_test.js 22116:9 Object._expect\n element_test.js 22094:9 Object.expect\n element_test.js 23354:11 main__closure.call$2\n element_test.js 23394:21 HeapSnapshotRepositoryMock.$get$3$gc$roots\n element_test.js 17234:101 <fn>\n element_test.js 5090:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5734:14 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 6153:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 5716:31 _AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 20707:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 20748:27 StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5399:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 7805:176 _CustomZone.registerCallback$1$1\n element_test.js 7741:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5316:33 Object.scheduleMicrotask\n element_test.js 5703:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5062:57 Object._asyncRethrow\n element_test.js 17228:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 7821:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5098:30 Object._wrapJsFunctionForAsync\n element_test.js 17226:50 HtmlElement._heap_snapshot$_refresh$0\n element_test.js 17146:14 HtmlElement.attached$0\n element_test.js 23693:41 Object.J.attached$0$x\n element_test.js 12924:16 StaticClosure.dart._callAttached\n ===== asynchronous gap ===========================\n element_test.js 7821:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5098:30 Object._wrapJsFunctionForAsync\n element_test.js 23289:31 main_closure0.call$0\n element_test.js 21162:55 <fn>\n element_test.js 5090:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5734:14 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 7821:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5098:30 Object._wrapJsFunctionForAsync\n element_test.js 21150:31 Declarer_test___closure.call$0\n element_test.js 21485:53 <fn>\n element_test.js 5090:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5734:14 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 7821:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5098:30 Object._wrapJsFunctionForAsync\n element_test.js 21474:31 Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5403:16 StaticClosure.dart._rootRun\n element_test.js 7778:160 _CustomZone.run$1$1\n element_test.js 5586:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 7821:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5098:30 Object._wrapJsFunctionForAsync\n element_test.js 21096:31 Declarer_test_closure.call$0\n element_test.js 21632:118 <fn>\n element_test.js 5090:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5734:14 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 7821:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5098:30 Object._wrapJsFunctionForAsync\n element_test.js 21623:31 Invoker__onRun____closure.call$0\n element_test.js 6023:52 Future_Future_closure.call$0\n element_test.js 20707:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 20748:27 StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 7805:176 _CustomZone.registerCallback$1$1\n element_test.js 7741:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5379:44 Object.Timer_Timer\n element_test.js 5104:9 Object.Future_Future\n element_test.js 21583:19 <fn>\n element_test.js 5090:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 7821:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5098:30 Object._wrapJsFunctionForAsync\n element_test.js 21572:31 Invoker__onRun___closure.call$0\n element_test.js 5403:16 StaticClosure.dart._rootRun\n element_test.js 7778:160 _CustomZone.run$1$1\n element_test.js 5586:89 Object._runZoned\n ","timestamp":0.836},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":0.928},{"type":"print","value":"","timestamp":0.93},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:50410/root_build/generated_tests/dart2js-chrome-sdk/short33__ui_heap_snapshot_element_test/element_test.js:7906:\nUncaught \n\n","timestamp":0.931},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.931},{"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":0.931}] 2018-12-05 06:40:25.477277 Warning: 2018-12-05 06:40:25.477277 Warning: 2018-12-05 06:41:53.913797 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" 2018-12-05 06:43:27.506693 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) 2018-12-05 06:43:57.553567 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) 2018-12-05 06:44:27.584815 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) 2018-12-05 06:44:57.616065 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) 2018-12-05 06:45:27.662990 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) 2018-12-05 06:45:57.709861 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) 2018-12-05 06:46:27.741226 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) 2018-12-05 06:46:57.788021 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)