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

python -u /b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmp4qhQj0.json --task-output-dir /b/s/w/ir/tmp/t/tmpDD382d --merge-script /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpiw4echmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpSGT7Kv.json -- swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpeVbPH9.json in dir /b/s/w/ir/k: allow_subannotations: False cmd: ['python', '-u', '/b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmp4qhQj0.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpDD382d', '--merge-script', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpiw4echmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpSGT7Kv.json', '--', 'swarming', 'collect', '-server', 'https://chromium-swarm.appspot.com', '-worker', '50', '-task-summary-python', '-task-output-stdout', 'all', '-verbose', '-requests-json', '/b/s/w/ir/tmp/t/tmpeVbPH9.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('vm tests_shard_3',) ok_ret: ALL_OK step_test_data: gen_default_step_test_data(...) 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: 34197 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 LANG: en_US.UTF-8 LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8915706999700380896 LOGDOG_STREAM_PROJECT: dart LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itqyG_un/luci_context.484064539 MAC_CHROMIUM_TMPDIR: /b/s/w/ir/tmp/t NO_GCE_CHECK: False PATH: /b/s/w/ir/cache/swarming_client:/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 PWD: /b/s/w/ir/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SWARMING_BOT_ID: luci-dart-try-xenial-28-acwm SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 4450bb8f2e4e5a11 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython _: /b/s/w/ir/cipd_bin_packages/vpython Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-04-19 16:55:44,241 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmpDD382d' 2019-04-19 16:55:44,242 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmpDD382d' already exists! 2019-04-19 16:55:44,242 - root: [WARNING] task_output_dir existing content: [] 2019-04-19 16:55:44,242 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpeVbPH9.json -output-dir /b/s/w/ir/tmp/t/tmpDD382d -task-summary-json /b/s/w/ir/tmp/t/tmpSGT7Kv.json [D2019-04-19T16:55:44.249390-07:00 9174 0 auth.go:1265] Minting a new token {"key":"luci_ctx/4172c388cce33498e9d9cc63daee495a5c74f0f1f76d595284b62dba34d2e77e", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-19T16:55:44.249473-07:00 9174 0 luci_ctx.go:138] POST http://127.0.0.1:42835/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/4172c388cce33498e9d9cc63daee495a5c74f0f1f76d595284b62dba34d2e77e", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-19T16:55:44.251615-07:00 9174 0 auth.go:1222] Token expires in 36m22.748423518s {"key":"luci_ctx/4172c388cce33498e9d9cc63daee495a5c74f0f1f76d595284b62dba34d2e77e", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 4450c5eefc78d010: exit 0 /b/s/swarming_bot.1.zip/third_party/requests/__init__.py:83: RequestsDependencyWarning: Old version of cryptography ([1, 2, 3]) may cause slowdown. 15200 files remaining... 12260 files remaining... 9344 files remaining... 6402 files remaining... 3312 files remaining... 271 files remaining... tools/testing/dart/main.dart:1: Warning: Interpreting this as package URI, 'package:test_dart/main.dart'. Test configurations: dartk-optcounter-linux-release-ia32(architecture: ia32, compiler: dartk, mode: release, runtime: vm, system: linux, builder-tag: optimization_counter_threshold, vm-options: [--optimization-counter-threshold=5, --random-seed=__RANDOM__], dart2js-options: [], timeout: null, preview-dart-2) Suites tested: samples, standalone, standalone_2, corelib_2, language_2, vm, benchmark_smoke, utils, lib_2, analyze_library, service, kernel, ffi dartk-optcounter-linux-release-ia32(architecture: ia32, compiler: dartk, mode: release, runtime: vm, system: linux, builder-tag: optimization_counter_threshold, vm-options: [--optimization-counter-threshold=5, --random-seed=__RANDOM__], dart2js-options: [], timeout: null, preview-dart-2) Suites tested: observatory_ui Total: 863 tests * 87 tests will be skipped (40 skipped by design) * 3 tests are expected to be flaky but not crash * 0 tests are expected to flaky crash * 719 tests are expected to pass * 0 tests are expected to fail that we won't fix * 23 tests are expected to fail that we should fix * 0 tests are expected to crash that we should fix * 0 tests are allowed to timeout * 31 could not be categorized or are in multiple categories 340 out of 776 completed Tests running for 5 minutes 751 out of 776 completed Tests running for 10 minutes FAILED: dartk-vm release_ia32 standalone_2/io/dart_std_io_pipe_test/05/1 Expected: Timeout Pass Actual: RuntimeError --- Command "vm" (took 33.000232s): DART_CONFIGURATION=ReleaseIA32 out/ReleaseIA32/dart --short_socket_read --optimization-counter-threshold=5 --random-seed=1207318055 --ignore-unrecognized-flags --packages=/b/s/w/ir/.packages /b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart exit code: 255 stderr: Unhandled exception: Expect.equals(expected: <Hello\x0AHello\x0A>, actual: <HelHellolo\x0A\x0A>) fails. #0 Expect._fail (package:expect/expect.dart:655:5) #1 Expect.equals (package:expect/expect.dart:123:7) #2 checkFileContent (file:///b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart:28:10) #3 test.<anonymous closure>.<anonymous closure> (file:///b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart:78:11) #4 _RootZone.runUnary (dart:async/zone.dart:1379:54) #5 _FutureListener.handleValue (dart:async/future_impl.dart:126:18) #6 Future._propagateToListeners.handleValueCallback (dart:async/future_impl.dart:639:45) #7 Future._propagateToListeners (dart:async/future_impl.dart:668:32) #8 Future._completeWithValue (dart:async/future_impl.dart:483:5) #9 Future._asyncComplete.<anonymous closure> (dart:async/future_impl.dart:513:7) #10 _microtaskLoop (dart:async/schedule_microtask.dart:41:21) #11 _startMicrotaskLoop (dart:async/schedule_microtask.dart:50:5) #12 _runPendingImmediateCallback (dart:isolate-patch/isolate_patch.dart:115:13) #13 _RawReceivePortImpl._handleMessage (dart:isolate-patch/isolate_patch.dart:172:5) --- Re-run this test: python tools/test.py -n dartk-optcounter-linux-release-ia32 standalone_2/io/dart_std_io_pipe_test/05/1 FAILED: dartk-vm release_ia32 standalone_2/io/dart_std_io_pipe_test/05/3 Expected: Timeout Pass Actual: RuntimeError --- Command "vm" (took 32.000099s): DART_CONFIGURATION=ReleaseIA32 out/ReleaseIA32/dart --short_socket_read --short_socket_write --optimization-counter-threshold=5 --random-seed=26767700 --ignore-unrecognized-flags --packages=/b/s/w/ir/.packages /b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart exit code: 255 stderr: Unhandled exception: Expect.equals(expected: <Hello\x0AHello\x0A>, actual: <HelHellolo\x0A\x0A>) fails. #0 Expect._fail (package:expect/expect.dart:655:5) #1 Expect.equals (package:expect/expect.dart:123:7) #2 checkFileContent (file:///b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart:28:10) #3 test.<anonymous closure>.<anonymous closure> (file:///b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart:78:11) #4 _RootZone.runUnary (dart:async/zone.dart:1379:54) #5 _FutureListener.handleValue (dart:async/future_impl.dart:126:18) #6 Future._propagateToListeners.handleValueCallback (dart:async/future_impl.dart:639:45) #7 Future._propagateToListeners (dart:async/future_impl.dart:668:32) #8 Future._completeWithValue (dart:async/future_impl.dart:483:5) #9 Future._asyncComplete.<anonymous closure> (dart:async/future_impl.dart:513:7) #10 _microtaskLoop (dart:async/schedule_microtask.dart:41:21) #11 _startMicrotaskLoop (dart:async/schedule_microtask.dart:50:5) #12 _runPendingImmediateCallback (dart:isolate-patch/isolate_patch.dart:115:13) #13 _RawReceivePortImpl._handleMessage (dart:isolate-patch/isolate_patch.dart:172:5) --- Re-run this test: python tools/test.py -n dartk-optcounter-linux-release-ia32 standalone_2/io/dart_std_io_pipe_test/05/3 === Failure summary: FAILED: dartk-vm release_ia32 standalone_2/io/dart_std_io_pipe_test/05/1 Expected: Timeout Pass Actual: RuntimeError --- Command "vm" (took 33.000232s): DART_CONFIGURATION=ReleaseIA32 out/ReleaseIA32/dart --short_socket_read --optimization-counter-threshold=5 --random-seed=1207318055 --ignore-unrecognized-flags --packages=/b/s/w/ir/.packages /b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart exit code: 255 stderr: Unhandled exception: Expect.equals(expected: <Hello\x0AHello\x0A>, actual: <HelHellolo\x0A\x0A>) fails. #0 Expect._fail (package:expect/expect.dart:655:5) #1 Expect.equals (package:expect/expect.dart:123:7) #2 checkFileContent (file:///b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart:28:10) #3 test.<anonymous closure>.<anonymous closure> (file:///b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart:78:11) #4 _RootZone.runUnary (dart:async/zone.dart:1379:54) #5 _FutureListener.handleValue (dart:async/future_impl.dart:126:18) #6 Future._propagateToListeners.handleValueCallback (dart:async/future_impl.dart:639:45) #7 Future._propagateToListeners (dart:async/future_impl.dart:668:32) #8 Future._completeWithValue (dart:async/future_impl.dart:483:5) #9 Future._asyncComplete.<anonymous closure> (dart:async/future_impl.dart:513:7) #10 _microtaskLoop (dart:async/schedule_microtask.dart:41:21) #11 _startMicrotaskLoop (dart:async/schedule_microtask.dart:50:5) #12 _runPendingImmediateCallback (dart:isolate-patch/isolate_patch.dart:115:13) #13 _RawReceivePortImpl._handleMessage (dart:isolate-patch/isolate_patch.dart:172:5) --- Re-run this test: python tools/test.py -n dartk-optcounter-linux-release-ia32 standalone_2/io/dart_std_io_pipe_test/05/1 FAILED: dartk-vm release_ia32 standalone_2/io/dart_std_io_pipe_test/05/3 Expected: Timeout Pass Actual: RuntimeError --- Command "vm" (took 32.000099s): DART_CONFIGURATION=ReleaseIA32 out/ReleaseIA32/dart --short_socket_read --short_socket_write --optimization-counter-threshold=5 --random-seed=26767700 --ignore-unrecognized-flags --packages=/b/s/w/ir/.packages /b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart exit code: 255 stderr: Unhandled exception: Expect.equals(expected: <Hello\x0AHello\x0A>, actual: <HelHellolo\x0A\x0A>) fails. #0 Expect._fail (package:expect/expect.dart:655:5) #1 Expect.equals (package:expect/expect.dart:123:7) #2 checkFileContent (file:///b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart:28:10) #3 test.<anonymous closure>.<anonymous closure> (file:///b/s/w/ir/out/ReleaseIA32/generated_tests/standalone_2/io/dart_std_io_pipe_test_05.dart:78:11) #4 _RootZone.runUnary (dart:async/zone.dart:1379:54) #5 _FutureListener.handleValue (dart:async/future_impl.dart:126:18) #6 Future._propagateToListeners.handleValueCallback (dart:async/future_impl.dart:639:45) #7 Future._propagateToListeners (dart:async/future_impl.dart:668:32) #8 Future._completeWithValue (dart:async/future_impl.dart:483:5) #9 Future._asyncComplete.<anonymous closure> (dart:async/future_impl.dart:513:7) #10 _microtaskLoop (dart:async/schedule_microtask.dart:41:21) #11 _startMicrotaskLoop (dart:async/schedule_microtask.dart:50:5) #12 _runPendingImmediateCallback (dart:isolate-patch/isolate_patch.dart:115:13) #13 _RawReceivePortImpl._handleMessage (dart:isolate-patch/isolate_patch.dart:172:5) --- Re-run this test: python tools/test.py -n dartk-optcounter-linux-release-ia32 standalone_2/io/dart_std_io_pipe_test/05/3 === === 2 tests failed === --- Total time: 11:37 --- 0:01:28.540067 - vm - dartk-vm release_ia32/service/coverage_optimized_function_test 0:01:18.607365 - vm - dartk-vm release_ia32/standalone_2/io/process_sync_test 0:00:40.774557 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/02/2 0:00:40.424377 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/01/3 0:00:40.024478 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/02/1 0:00:39.791608 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/01/0 0:00:39.694585 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/02/0 0:00:39.369784 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/01/2 0:00:38.999395 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/01/1 0:00:33.823067 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/04/1 0:00:33.698964 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/03/3 0:00:33.561555 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/05/0 0:00:33.364302 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/05/2 0:00:33.351202 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/02/3 0:00:33.232642 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/05/1 0:00:33.209131 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/03/1 0:00:33.098234 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/04/0 0:00:32.997396 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/03/2 0:00:32.973857 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/03/0 0:00:32.893549 - vm - dartk-vm release_ia32/standalone_2/io/dart_std_io_pipe_test/04/3 INFO: Core dump archiving is activated INFO: No unexpected crashes recorded 2019-04-19 16:55:44,772 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmpDD382d/4450c5eefc78d010'] 2019-04-19 16:55:44,772 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/tmp/t/tmpDD382d/4450c5eefc78d010/output.json']) Found: [] Expected: ['/b/s/w/ir/tmp/t/tmpDD382d/4450c5eefc78d010/output.json'] 2019-04-19 16:55:44,772 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/tmp/t/tmpDD382d' Found ['/b/s/w/ir/tmp/t/tmpDD382d/4450c5eefc78d010'] 2019-04-19 16:55:44,772 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmpDD382d/4450c5eefc78d010/output.json'] 2019-04-19 16:55:44,772 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/f6423e/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmpSGT7Kv.json --task-output-dir /b/s/w/ir/tmp/t/tmpDD382d -o /b/s/w/ir/tmp/t/tmp4qhQj0.json Running ['/b/s/w/ir/cache/vpython/f6423e/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpSGT7Kv.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpDD382d', '-o', '/b/s/w/ir/tmp/t/tmp4qhQj0.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/f6423e/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpSGT7Kv.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpDD382d', '-o', '/b/s/w/ir/tmp/t/tmp4qhQj0.json'] returned exit code 0