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

cat .debug.log in dir /b/s/w/ir/cache/builder/sdk: allow_subannotations: False base_name: 'debug log' cmd: ['cat', '.debug.log'] cwd: '/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: BOTO_CONFIG: /b/s/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: /b/s/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 45653 DOCKER_CONFIG: /b/s/w/ir/tmp/docker_cfg_task DOCKER_TMPDIR: /b/s/w/ir/tmp/docker_tmp_task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /home/chrome-bot INFRA_GIT_WRAPPER_HOME: /b/s/w/ir/tmp/git_home_task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8927202777858741744 LOGDOG_STREAM_PROJECT: dart LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itsK5gtq/luci_context.534531365 MAC_CHROMIUM_TMPDIR: /b/s/w/ir/tmp/t MAIL: /var/mail/chrome-bot NO_GCE_CHECK: False PATH: /b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin PWD: /b/s/w/ir PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SUDO_COMMAND: /usr/bin/python /b/s/swarming_bot.zip start_bot SUDO_GID: 0 SUDO_UID: 0 SUDO_USER: root SWARMING_BOT_ID: trusty-dart-68765ebb-us-central1-b-wc8f SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 41c345f853750911 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TERM: linux TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot USERNAME: chrome-bot VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython _: /b/s/w/ir/cipd_bin_packages/vpython 2018-12-13 18:11:06.428182 Warning: Double reporting tests: 2018-12-13 18:11:06.430328 Warning: http://127.0.0.1:36275/root_build/generated_tests/dart2js-chrome-minified-sdk/runtime_observatory_tests_observatory_ui_heap_snapshot_element_test/test.html?crossOriginPort=34737, output: 2018-12-13 18:11:06.430352 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.161},{"type":"print","value":"dart-main-done","timestamp":0.188},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation\u001b[0m","timestamp":0.233},{"type":"message_received","value":"dart-calling-main","timestamp":0.244},{"type":"message_received","value":"dart-main-done","timestamp":0.245},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.246},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created\u001b[0m","timestamp":0.281},{"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.356},{"type":"print","value":" Expected: false\n Actual: <true>\n ","timestamp":0.357},{"type":"print","value":" element_test.js 6474:3 Object.d\n element_test.js 6480:23 Object.D\n element_test.js 15976:25 Object.x9\n element_test.js 15975:36 Object.wA\n element_test.js 15965:28 Object.np\n element_test.js 16588:3 mO.$2\n element_test.js 16592:21 e7.hv\n element_test.js 13481:13 <fn>\n element_test.js 7521:72 mD.a\n element_test.js 7870:23 mD.$2\n ===== asynchronous gap ===========================\n element_test.js 8072:7 dL.dart.eL.bo\n element_test.js 7864:24 kV.$0\n element_test.js 15407:9 ca.ee\n element_test.js 15423:29 jS.$0\n element_test.js 7688:18 jX.dart.px\n ===== asynchronous gap ===========================\n element_test.js 8906:92 au.bA\n element_test.js 8860:42 au.d9\n element_test.js 7645:8 Object.nH\n element_test.js 7859:8 eG.bo\n element_test.js 7508:32 Object.av\n element_test.js 13479:52 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8918:122 au.dz\n element_test.js 7524:12 Object.az\n element_test.js 13479:17 w.bM\n element_test.js 13416:6 w.M\n element_test.js 16671:32 Object.J.tW\n element_test.js 11380:26 jX.dart.yX\n ===== asynchronous gap ===========================\n element_test.js 8918:122 au.dz\n element_test.js 7524:12 Object.az\n element_test.js 16545:17 mQ.$0\n element_test.js 15573:16 <fn>\n element_test.js 7521:72 mD.a\n element_test.js 7870:23 mD.$2\n ===== asynchronous gap ===========================\n element_test.js 8918:122 au.dz\n element_test.js 7524:12 Object.az\n element_test.js 15569:17 fJ.$0\n element_test.js 15699:16 <fn>\n element_test.js 7521:72 mD.a\n element_test.js 7870:23 mD.$2\n ===== asynchronous gap ===========================\n element_test.js 8918:122 au.dz\n element_test.js 7524:12 Object.az\n element_test.js 15694:17 ii.$0\n element_test.js 7691:9 jX.dart.px\n element_test.js 8885:79 au.aF\n element_test.js 7804:20 Object.rw\n ===== asynchronous gap ===========================\n element_test.js 8918:122 au.dz\n element_test.js 7524:12 Object.az\n element_test.js 15552:17 fL.$0\n element_test.js 15763:24 <fn>\n element_test.js 7521:72 mD.a\n element_test.js 7870:23 mD.$2\n ===== asynchronous gap ===========================\n element_test.js 8918:122 au.dz\n element_test.js 7524:12 Object.az\n element_test.js 15760:17 i8.$0\n element_test.js 8013:22 hx.$0\n element_test.js 15407:9 ca.ee\n element_test.js 15423:29 jS.$0\n ===== asynchronous gap ===========================\n element_test.js 8906:92 au.bA\n element_test.js 8860:42 au.d9\n element_test.js 7676:17 Object.qW\n element_test.js 7528:3 Object.ql\n element_test.js 15741:3 <fn>\n element_test.js 7521:72 mD.a\n ===== asynchronous gap ===========================\n element_test.js 8918:122 au.dz\n element_test.js 7524:12 Object.az\n element_test.js 15736:17 i9.$0\n element_test.js 7691:9 jX.dart.px\n element_test.js 8885:79 au.aF\n element_test.js 7804:20 Object.rw\n ","timestamp":0.617},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":0.654},{"type":"print","value":"","timestamp":0.655},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:36275/root_build/generated_tests/dart2js-chrome-minified-sdk/runtime_observatory_tests_observatory_ui_heap_snapshot_element_test/element_test.js:8964:\nUncaught \n\n","timestamp":0.656},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.656},{"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.656}] 2018-12-13 18:11:06.430389 Warning: 2018-12-13 18:11:06.430396 Warning: