[04:58:23.326] New invocation is queued and will start shortly
[04:58:24.472] Starting the invocation (attempt 1)
[04:58:24.525] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:58:24.525] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:58:24.525] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023224565112946304",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"acc31c0c0ac22113b5fd61667a20dce2147fc68a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023224565112946304",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/acc31c0c0ac22113b5fd61667a20dce2147fc68a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acc31c0c0ac22113b5fd61667a20dce2147fc68a",
"gitiles_ref:refs/heads/master"
]
}
[04:58:25.342] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610945904620546",
"id": "8857794503075588224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"acc31c0c0ac22113b5fd61667a20dce2147fc68a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610945905036867",
"tags": [
"build_address:luci.chromium.ci/win-asan/16865",
"builder:win-asan",
"buildset:commit/git/acc31c0c0ac22113b5fd61667a20dce2147fc68a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acc31c0c0ac22113b5fd61667a20dce2147fc68a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023224565112946304",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857794503075588224/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1610945905036986",
"url": "https://ci.chromium.org/b/8857794503075588224",
"utcnow_ts": "1610945905335253"
}
}
[04:58:25.342] Task URL: https://ci.chromium.org/b/8857794503075588224
[04:58:25.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:2:0) after 1m0s
[04:58:34.413] Received PubSub notification, asking Buildbucket for the build status
[04:58:34.480] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:25.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:2:0)
[04:59:25.521] Timer tick, asking Buildbucket for the build status
[04:59:25.587] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:25.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:4:0) after 1m0s
[05:00:25.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:4:0)
[05:00:25.614] Timer tick, asking Buildbucket for the build status
[05:00:25.831] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:25.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:5:0) after 1m0s
[05:01:25.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:5:0)
[05:01:25.926] Timer tick, asking Buildbucket for the build status
[05:01:26.032] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:26.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:6:0) after 1m0s
[05:02:26.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:6:0)
[05:02:26.294] Timer tick, asking Buildbucket for the build status
[05:02:26.429] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:26.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:7:0) after 1m0s
[05:03:26.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:7:0)
[05:03:26.646] Timer tick, asking Buildbucket for the build status
[05:03:26.927] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:26.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:8:0) after 1m0s
[05:04:26.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:8:0)
[05:04:26.948] Timer tick, asking Buildbucket for the build status
[05:04:27.075] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:27.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:9:0) after 1m0s
[05:05:27.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:9:0)
[05:05:27.199] Timer tick, asking Buildbucket for the build status
[05:05:27.390] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:27.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:10:0) after 1m0s
[05:06:27.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:10:0)
[05:06:27.410] Timer tick, asking Buildbucket for the build status
[05:06:27.707] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:27.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:11:0) after 1m0s
[05:07:27.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:11:0)
[05:07:27.760] Timer tick, asking Buildbucket for the build status
[05:07:27.873] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:27.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:12:0) after 1m0s
[05:08:27.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:12:0)
[05:08:27.884] Timer tick, asking Buildbucket for the build status
[05:08:28.005] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:28.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:13:0) after 1m0s
[05:09:28.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:13:0)
[05:09:28.047] Timer tick, asking Buildbucket for the build status
[05:09:28.604] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:28.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:14:0) after 1m0s
[05:10:28.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:14:0)
[05:10:28.591] Timer tick, asking Buildbucket for the build status
[05:10:28.919] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:28.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:15:0) after 1m0s
[05:11:28.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:15:0)
[05:11:28.911] Timer tick, asking Buildbucket for the build status
[05:11:29.382] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:29.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:16:0) after 1m0s
[05:12:29.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:16:0)
[05:12:29.439] Timer tick, asking Buildbucket for the build status
[05:12:29.739] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:29.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:17:0) after 1m0s
[05:13:29.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:17:0)
[05:13:29.773] Timer tick, asking Buildbucket for the build status
[05:13:29.907] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:29.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:18:0) after 1m0s
[05:14:29.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:18:0)
[05:14:29.918] Timer tick, asking Buildbucket for the build status
[05:14:30.030] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:30.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:19:0) after 1m0s
[05:15:30.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:19:0)
[05:15:30.050] Timer tick, asking Buildbucket for the build status
[05:15:30.188] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:30.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:20:0) after 1m0s
[05:16:30.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:20:0)
[05:16:30.211] Timer tick, asking Buildbucket for the build status
[05:16:30.318] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:30.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:21:0) after 1m0s
[05:17:30.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:21:0)
[05:17:30.396] Timer tick, asking Buildbucket for the build status
[05:17:30.535] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:30.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:22:0) after 1m0s
[05:18:30.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:22:0)
[05:18:30.658] Timer tick, asking Buildbucket for the build status
[05:18:31.607] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:31.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:23:0) after 1m0s
[05:19:31.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:23:0)
[05:19:31.681] Timer tick, asking Buildbucket for the build status
[05:19:31.778] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:31.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:24:0) after 1m0s
[05:20:31.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:24:0)
[05:20:31.851] Timer tick, asking Buildbucket for the build status
[05:20:31.982] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:31.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:25:0) after 1m0s
[05:21:32.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:25:0)
[05:21:32.035] Timer tick, asking Buildbucket for the build status
[05:21:32.173] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:32.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:26:0) after 1m0s
[05:22:32.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:26:0)
[05:22:32.294] Timer tick, asking Buildbucket for the build status
[05:22:32.608] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:32.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:27:0) after 1m0s
[05:23:32.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:27:0)
[05:23:32.659] Timer tick, asking Buildbucket for the build status
[05:23:32.794] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:32.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:28:0) after 1m0s
[05:24:32.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:28:0)
[05:24:32.845] Timer tick, asking Buildbucket for the build status
[05:24:33.352] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:33.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:29:0) after 1m0s
[05:25:33.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:29:0)
[05:25:33.408] Timer tick, asking Buildbucket for the build status
[05:25:33.483] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:33.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:30:0) after 1m0s
[05:26:33.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:30:0)
[05:26:33.526] Timer tick, asking Buildbucket for the build status
[05:26:33.790] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:33.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:31:0) after 1m0s
[05:27:33.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:31:0)
[05:27:33.868] Timer tick, asking Buildbucket for the build status
[05:27:34.180] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:34.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:32:0) after 1m0s
[05:28:34.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:32:0)
[05:28:34.172] Timer tick, asking Buildbucket for the build status
[05:28:34.307] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:34.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:33:0) after 1m0s
[05:29:34.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:33:0)
[05:29:34.326] Timer tick, asking Buildbucket for the build status
[05:29:34.616] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:34.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:34:0) after 1m0s
[05:30:34.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:34:0)
[05:30:34.635] Timer tick, asking Buildbucket for the build status
[05:30:34.699] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:34.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:35:0) after 1m0s
[05:31:34.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:35:0)
[05:31:34.717] Timer tick, asking Buildbucket for the build status
[05:31:34.821] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:34.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:36:0) after 1m0s
[05:32:34.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:36:0)
[05:32:34.842] Timer tick, asking Buildbucket for the build status
[05:32:35.082] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:35.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:37:0) after 1m0s
[05:33:35.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:37:0)
[05:33:35.087] Timer tick, asking Buildbucket for the build status
[05:33:35.250] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:35.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:38:0) after 1m0s
[05:34:35.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:38:0)
[05:34:35.314] Timer tick, asking Buildbucket for the build status
[05:34:35.382] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:35.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:39:0) after 1m0s
[05:35:35.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:39:0)
[05:35:35.454] Timer tick, asking Buildbucket for the build status
[05:35:35.705] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:35.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:40:0) after 1m0s
[05:36:35.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:40:0)
[05:36:35.740] Timer tick, asking Buildbucket for the build status
[05:36:36.183] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:36.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:41:0) after 1m0s
[05:37:36.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:41:0)
[05:37:36.218] Timer tick, asking Buildbucket for the build status
[05:37:36.350] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:36.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:42:0) after 1m0s
[05:38:36.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:42:0)
[05:38:36.350] Timer tick, asking Buildbucket for the build status
[05:38:36.455] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:36.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:43:0) after 1m0s
[05:39:36.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:43:0)
[05:39:36.474] Timer tick, asking Buildbucket for the build status
[05:39:36.601] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:36.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:44:0) after 1m0s
[05:40:36.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:44:0)
[05:40:36.670] Timer tick, asking Buildbucket for the build status
[05:40:36.798] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:36.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:45:0) after 1m0s
[05:41:36.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:45:0)
[05:41:36.917] Timer tick, asking Buildbucket for the build status
[05:41:37.021] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:37.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:46:0) after 1m0s
[05:42:37.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:46:0)
[05:42:37.127] Timer tick, asking Buildbucket for the build status
[05:42:37.218] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:37.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:47:0) after 1m0s
[05:43:37.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:47:0)
[05:43:37.261] Timer tick, asking Buildbucket for the build status
[05:43:37.430] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:37.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:48:0) after 1m0s
[05:44:37.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:48:0)
[05:44:37.462] Timer tick, asking Buildbucket for the build status
[05:44:37.529] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:37.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:49:0) after 1m0s
[05:45:37.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:49:0)
[05:45:37.571] Timer tick, asking Buildbucket for the build status
[05:45:37.670] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:37.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:50:0) after 1m0s
[05:46:37.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:50:0)
[05:46:37.739] Timer tick, asking Buildbucket for the build status
[05:46:37.914] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:37.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:51:0) after 1m0s
[05:47:37.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:51:0)
[05:47:37.944] Timer tick, asking Buildbucket for the build status
[05:47:38.026] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:38.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:52:0) after 1m0s
[05:48:38.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:52:0)
[05:48:38.089] Timer tick, asking Buildbucket for the build status
[05:48:38.250] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:38.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:53:0) after 1m0s
[05:49:38.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:53:0)
[05:49:38.281] Timer tick, asking Buildbucket for the build status
[05:49:38.395] Build 8857794503075588224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:38.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023224565112946304:54:0) after 1m0s
[05:50:01.306] Received PubSub notification, asking Buildbucket for the build status
[05:50:01.376] Build 8857794503075588224: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:50:01.376] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610948998914612",
"created_by": "project:chromium",
"created_ts": "1610945904620546",
"failure_reason": "BUILD_FAILURE",
"id": "8857794503075588224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"acc31c0c0ac22113b5fd61667a20dce2147fc68a\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SRC_ExternalClearKey/EncryptedMediaTest.Playback_VideoAudio_WebM_Opus/0\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"acc31c0c0ac22113b5fd61667a20dce2147fc68a\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-9-kl3f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16865, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"dfd9bdfd656762297dbdb8cc6f9e28b84990ea1c\", \"got_dawn_revision\": \"04772515a8b613150b28bbed19b4c261e0b50e96\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"acc31c0c0ac22113b5fd61667a20dce2147fc68a\", \"got_revision_cp\": \"refs/heads/master@{#844435}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"09729d2ecf49d256f6648494b59076b01786dc51\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33013}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"acc31c0c0ac22113b5fd61667a20dce2147fc68a\", \"swarm_hashes_refs/heads/master(at){#844435}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"efd4001617c9c806d8b19b6614fafff461d97be6\", \"angle_unittests\": \"154d70262a5e4781b0c80ec82d6ec6e04fcd7c9d\", \"app_shell_unittests\": \"2d05b427152d05fe567c8b0388eed696b66de6e7\", \"aura_unittests\": \"b7fc4eedb5e7488a4519d07905f9420f6a8cc9b8\", \"base_unittests\": \"37a1d7978705d5e766f6f4a4a7fdd516807120c6\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"76f519c32cb88c647434298af66731048ca90f24\", \"blink_fuzzer_unittests\": \"57a490c04f7ed084f897a5e87fd5854e174f49b5\", \"blink_heap_unittests\": \"7d8a30fffe3e52f69e35f08b9ee33a7eb289a7bd\", \"blink_platform_unittests\": \"9c55d541c7790650f6c35d4aa1976309996e8827\", \"blink_unittests\": \"56d519aa6c7c587831ce7547817ee4bbede4d954\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"dadabac68187b23e8c2940c46eedf0f090e71c9c\", \"capture_unittests\": \"3765370ed44f22f0642e83cde419f13a4809a838\", \"cast_unittests\": \"9587ef78dc2cfc364d4c7b29febbd1b91ffa6b32\", \"cc_unittests\": \"f42ece28561d24bd1e9bce1e76dec741ef6c1fd9\", \"chrome_app_unittests\": \"4f2e979f327bb9b7d01c8d08759a701403a2d4b7\", \"chrome_cleaner_unittests\": \"fedec7fc875a5b73a9dae5c1aac293fce2696bc6\", \"chrome_elf_unittests\": \"b35905415824af61a84c35dcb83b770c6576a1a6\", \"chromedriver_unittests\": \"d26d60cda798ca5705bb8495d308e70ae4cc4af4\", \"components_browsertests\": \"441a99d88c91c55605d7d6ed033ab022c9f2653f\", \"components_unittests\": \"97ae5273d23bd0a6c61e7f93c8c87c0db01b22cd\", \"compositor_unittests\": \"4ffd54c423b0b9d280f85e81ad7e045ee3c1ecfb\", \"content_browsertests\": \"28f6198e347ae2d5895da24054542341cfa78641\", \"content_unittests\": \"380ba4519b448d5b54a2257e112af89d4809bbc2\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"3247b1874d8a4310d09ca7bd4648f6445777c751\", \"cronet_unittests\": \"ee78c425f4b53f53ea3d27892506493111355852\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"569cc4c6451f3fdf16ed988387d2d7bd0a7a8976\", \"device_unittests\": \"eb2c611181ae0584abccb859b0be81ef5d57f1d0\", \"display_unittests\": \"1826b4556f79e085bd7b2e7be4b6d8ee335f51b6\", \"elevation_service_unittests\": \"93f5a6fa431958115f20f8cf2f760cab93508700\", \"events_unittests\": \"561dadf6f4eadd69b205c02900392e390f7937cb\", \"extensions_browsertests\": \"884012361c8fb229aa303797da48797418847fed\", \"extensions_unittests\": \"cb176ba7e74062a1d8e087cce4bfbab3c3f382b2\", \"filesystem_service_unittests\": \"4c8009f62a585a6506f7c5f118de9f941c7da22d\", \"gcm_unit_tests\": \"b1470241b07aab8bd8bedf79a197d76fba4070e9\", \"gcp_unittests\": \"5999978b6c9185bb5f351305a66acbe6094be7fa\", \"gfx_unittests\": \"b0c02bbe3a6ce220c4646a7c03913fc7dc3da909\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"08d52d75f6bb4bc9474e48f8623d53f9eee92aa1\", \"gpu_unittests\": \"5e097a0f3950bf258bf37ed4f4e9089a6316be93\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"cf221bc98acd74e34bca960fe95f9d89d7cf7116\", \"headless_unittests\": \"25a1b29923c0c7955a55f7ffa0f76c2d586fde84\", \"install_static_unittests\": \"dffc0f8910b6f1e0053aba9e175658832d2ced89\", \"installer_util_unittests\": \"40e8dea9fad69a97c450c5564b6beefdcbbd1c92\", \"interactive_ui_tests\": \"322e76912fd4a3e4ea6cb8ededc1714b393ce55c\", \"ipc_tests\": \"525c866f3ecabe86b4dd97d58a81a118070210c9\", \"jingle_unittests\": \"a82c2ab84e46f601cc5da5b4c000f62054ce54fa\", \"latency_unittests\": \"a68772f7a1f2f079cfcdc3c11e3be245eb0d0b2b\", \"libjingle_xmpp_unittests\": \"0b0302d46712da050f472870ac9aa96b45de8542\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"ce9495780fc86ef76f37173b73dfc10d34825261\", \"media_unittests\": \"4d818d2f38e2f0a607684fdc7760bbde8bebaadc\", \"message_center_unittests\": \"1d20bdbd26ee9933b553242a296de8d071fdeaf0\", \"midi_unittests\": \"651630e50d988d933b278285479cc4975b421bdd\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"97a45c89390bee43b8080aad8506fec82109526f\", \"nacl_loader_unittests\": \"99282e493ff0008633ca8e89e02951e6ddb1e446\", \"native_theme_unittests\": \"ede3b5feb2d66ee8e2cd332fd672c07dd4e273df\", \"net_unittests\": \"821e8ac99aec65694c866a8192ba24b283ba4881\", \"notification_helper_unittests\": \"f1abd173916c72360a03a30bb05d9d1f4af95bf8\", \"pdf_unittests\": \"7e051e40815272f18ee22e432c535d3f74939cd6\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"07139b01b7828e8ac55113ed2795326350528c26\", \"printing_unittests\": \"93591827be3c496b971e4c146a7e0f17df6e3abc\", \"remoting_unittests\": \"62adda0310e6dc09e4ad7ff3371d6c4e78222108\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"7cadbab3b938f73ad33497c79df0fd4c42dafe2d\", \"services_unittests\": \"607b4236fdb28f5a996d03e4ff185aff78b1bf74\", \"setup_unittests\": \"88d2883c546aefb43b8fe13d8b643d43822211de\", \"shell_dialogs_unittests\": \"16510222d523a87f3a500015a0ee4df4aba47e38\", \"skia_unittests\": \"5b792aaed3e32ae343ca3a52781eb59115fb5e02\", \"snapshot_unittests\": \"fcde2a7ba1337e6811aa9732ff8c7d1135ee3794\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"8a1e998ad0b287532bf8d19d94ed064284126268\", \"sync_integration_tests\": \"622a485fa6604c323ac30267dba71442e4db533b\", \"traffic_annotation_auditor_unittests\": \"29cd7f6b25179b6c773a8f5b74be646c234ce191\", \"ui_base_unittests\": \"71481da7e84c498444ca1bb2b159243007905f1e\", \"ui_touch_selection_unittests\": \"c96204d7db9bbb8d2cebc84c5ac5adcfbbbba3de\", \"unit_tests\": \"c632074bc26889b6f435176fb804038a7002f66c\", \"updater_tests\": \"478212a2c553260b0cdff0906a7655ecbd4ca979\", \"url_unittests\": \"a1d165870417d9db1872099dcf5b32cfc96497fe\", \"views_unittests\": \"540b71d2d7507246d6cfe03c6cc2e5d516d2fb4b\", \"viz_unittests\": \"455eef972253246929c84b67ff086926a057431d\", \"vr_common_unittests\": \"8664e80ff81eeae353af335937ba02e6d82517c8\", \"vr_pixeltests\": \"fea8272fc63f2c17008250d58ba48c00906962d8\", \"weblayer_browsertests\": \"4c8eed1cba43978bb2ee7369e90a43871b060f0f\", \"weblayer_unittests\": \"852d6f59e88e99e8b6aed4fb3e9db410ae3f76a2\", \"wm_unittests\": \"2e88c458433a6c62e4ce0ccaba8ed48d84d511e1\", \"wtf_unittests\": \"f7e274ea5029c0c034dc639413b78d232259abd3\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-9-kl3f\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SRC_ExternalClearKey/EncryptedMediaTest.Playback_VideoAudio_WebM_Opus/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610945913223154",
"status": "COMPLETED",
"status_changed_ts": "1610945913871723",
"tags": [
"build_address:luci.chromium.ci/win-asan/16865",
"builder:win-asan",
"buildset:commit/git/acc31c0c0ac22113b5fd61667a20dce2147fc68a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acc31c0c0ac22113b5fd61667a20dce2147fc68a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023224565112946304",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857794503075588224/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:512cad5d80bf7110",
"user_agent:luci-scheduler"
],
"updated_ts": "1610949000729339",
"url": "https://ci.chromium.org/b/8857794503075588224",
"utcnow_ts": "1610949001368457"
}
}
[05:50:01.376] Invocation finished in 51m38.082430857s with status FAILED