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 cmd: ['cat', '.debug.log'] cwd: '/b/s/w/ir/cache/builder/sdk' env: {} env_prefixes: {} env_suffixes: {} infra_step: False name_tokens: ('debug log',) 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: 43874 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/8919004283462791712 LOGDOG_STREAM_PROJECT: dart LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itfrwmQF/luci_context.504398748 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/k 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-dmnz SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43954dc0bdf83811 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 2019-03-14 07:01:31.266073 Warning: Double reporting tests: 2019-03-14 07:01:31.268693 Warning: http://127.0.0.1:39972/root_build/generated_tests/dart2js-chrome-minified-sdk/runtime_observatory_tests_observatory_ui_heap_snapshot_element_test/test.html?crossOriginPort=43861, output: 2019-03-14 07:01:31.268745 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.003},{"type":"print","value":"dart-calling-main","timestamp":0.15},{"type":"print","value":"dart-main-done","timestamp":0.18},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation\u001b[0m","timestamp":0.253},{"type":"message_received","value":"dart-calling-main","timestamp":0.267},{"type":"message_received","value":"dart-main-done","timestamp":0.267},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.269},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created\u001b[0m","timestamp":0.314},{"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.371},{"type":"print","value":" Expected: false\n Actual: <true>\n ","timestamp":0.372},{"type":"print","value":" element_test.js 422:3 Object.b\n element_test.js 428:23 Object.K\n element_test.js 4057:25 Object.xi\n element_test.js 4056:36 Object.wH\n element_test.js 4046:22 Object.cP\n element_test.js 12658:3 oo.$2\n element_test.js 12675:21 f5.hh\n element_test.js 10022:13 <fn>\n element_test.js 1305:72 o7.a\n element_test.js 5848:23 o7.$2\n ===== asynchronous gap ===========================\n element_test.js 6055:7 dt.b8\n element_test.js 5839:24 mu.$0\n element_test.js 11547:9 cl.dV\n element_test.js 11567:12 lh.$0\n element_test.js 1532:18 lm.o3\n ===== asynchronous gap ===========================\n element_test.js 6782:92 mF.bt\n element_test.js 6741:42 mF.cZ\n element_test.js 1487:8 Object.dB\n element_test.js 5833:8 fG.b8\n element_test.js 1292:32 Object.ag\n element_test.js 10020:52 <fn>\n ===== asynchronous gap ===========================\n element_test.js 6794:122 mF.de\n element_test.js 1308:12 Object.ak\n element_test.js 10020:17 w.bB\n element_test.js 9960:6 w.J\n element_test.js 1188:30 Object.tQ\n element_test.js 3087:25 lm.wx\n ===== asynchronous gap ===========================\n element_test.js 6794:122 mF.de\n element_test.js 1308:12 Object.ak\n element_test.js 12615:17 oq.$0\n element_test.js 11700:16 <fn>\n element_test.js 1305:72 o7.a\n element_test.js 5848:23 o7.$2\n ===== asynchronous gap ===========================\n element_test.js 6794:122 mF.de\n element_test.js 1308:12 Object.ak\n element_test.js 11696:17 i6.$0\n element_test.js 11845:16 <fn>\n element_test.js 1305:72 o7.a\n element_test.js 5848:23 o7.$2\n ===== asynchronous gap ===========================\n element_test.js 6794:122 mF.de\n element_test.js 1308:12 Object.ak\n element_test.js 11840:17 jI.$0\n element_test.js 1535:9 lm.o3\n element_test.js 6761:79 mF.aD\n element_test.js 1646:20 Object.ro\n ===== asynchronous gap ===========================\n element_test.js 6794:122 mF.de\n element_test.js 1308:12 Object.ak\n element_test.js 11675:17 i8.$0\n element_test.js 11918:24 <fn>\n element_test.js 1305:72 o7.a\n element_test.js 5848:23 o7.$2\n ===== asynchronous gap ===========================\n element_test.js 6794:122 mF.de\n element_test.js 1308:12 Object.ak\n element_test.js 11915:17 jz.$0\n element_test.js 5993:22 iW.$0\n element_test.js 11547:9 cl.dV\n element_test.js 11567:12 lh.$0\n ===== asynchronous gap ===========================\n element_test.js 6782:92 mF.bt\n element_test.js 6741:42 mF.cZ\n element_test.js 1519:17 Object.qK\n element_test.js 1312:3 Object.qb\n element_test.js 11894:3 <fn>\n element_test.js 1305:72 o7.a\n ===== asynchronous gap ===========================\n element_test.js 6794:122 mF.de\n element_test.js 1308:12 Object.ak\n element_test.js 11889:17 jA.$0\n element_test.js 1535:9 lm.o3\n element_test.js 6761:79 mF.aD\n element_test.js 1646:20 Object.ro\n ","timestamp":0.664},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":0.698},{"type":"print","value":"","timestamp":0.699},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:39972/root_build/generated_tests/dart2js-chrome-minified-sdk/runtime_observatory_tests_observatory_ui_heap_snapshot_element_test/element_test.js:6862:\nUncaught \n\n","timestamp":0.7},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.701},{"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.701}] 2019-03-14 07:01:31.268798 Warning: 2019-03-14 07:01:31.268810 Warning: