[11:58:15.358] New invocation is queued and will start shortly
[11:58:16.451] Starting the invocation (attempt 1)
[11:58:16.491] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:58:16.493] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:58:16.493] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026097252264510640",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9ee8fcb2c04904eb694a2c998191493426257a72\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026097252264510640",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9ee8fcb2c04904eb694a2c998191493426257a72",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ee8fcb2c04904eb694a2c998191493426257a72",
"gitiles_ref:refs/heads/master"
]
}
[11:58:17.626] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608206296567345",
"id": "8860667190329181712",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9ee8fcb2c04904eb694a2c998191493426257a72\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608206297356605",
"tags": [
"build_address:luci.chromium.ci/win-asan/16280",
"builder:win-asan",
"buildset:commit/git/9ee8fcb2c04904eb694a2c998191493426257a72",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ee8fcb2c04904eb694a2c998191493426257a72",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026097252264510640",
"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/8860667190329181712/+/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": "1608206297357120",
"url": "https://ci.chromium.org/b/8860667190329181712",
"utcnow_ts": "1608206297605125"
}
}
[11:58:17.626] Task URL: https://ci.chromium.org/b/8860667190329181712
[11:58:17.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:2:0) after 1m0s
[11:58:20.107] Received PubSub notification, asking Buildbucket for the build status
[11:58:20.178] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:17.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:2:0)
[11:59:17.687] Timer tick, asking Buildbucket for the build status
[11:59:17.774] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:17.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:4:0) after 1m0s
[12:00:17.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:4:0)
[12:00:17.752] Timer tick, asking Buildbucket for the build status
[12:00:18.125] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:18.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:5:0) after 1m0s
[12:01:18.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:5:0)
[12:01:18.152] Timer tick, asking Buildbucket for the build status
[12:01:18.272] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:18.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:6:0) after 1m0s
[12:02:18.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:6:0)
[12:02:18.313] Timer tick, asking Buildbucket for the build status
[12:02:18.456] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:18.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:7:0) after 1m0s
[12:03:18.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:7:0)
[12:03:18.535] Timer tick, asking Buildbucket for the build status
[12:03:18.754] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:18.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:8:0) after 1m0s
[12:04:18.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:8:0)
[12:04:18.777] Timer tick, asking Buildbucket for the build status
[12:04:18.925] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:18.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:9:0) after 1m0s
[12:05:18.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:9:0)
[12:05:18.956] Timer tick, asking Buildbucket for the build status
[12:05:19.108] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:19.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:10:0) after 1m0s
[12:06:19.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:10:0)
[12:06:19.117] Timer tick, asking Buildbucket for the build status
[12:06:19.171] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:19.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:11:0) after 1m0s
[12:07:19.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:11:0)
[12:07:19.161] Timer tick, asking Buildbucket for the build status
[12:07:19.230] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:19.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:12:0) after 1m0s
[12:08:19.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:12:0)
[12:08:19.257] Timer tick, asking Buildbucket for the build status
[12:08:19.402] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:19.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:13:0) after 1m0s
[12:09:19.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:13:0)
[12:09:19.426] Timer tick, asking Buildbucket for the build status
[12:09:19.486] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:19.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:14:0) after 1m0s
[12:10:19.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:14:0)
[12:10:19.528] Timer tick, asking Buildbucket for the build status
[12:10:19.606] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:19.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:15:0) after 1m0s
[12:11:19.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:15:0)
[12:11:19.625] Timer tick, asking Buildbucket for the build status
[12:11:19.680] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:19.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:16:0) after 1m0s
[12:12:19.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:16:0)
[12:12:19.768] Timer tick, asking Buildbucket for the build status
[12:12:19.906] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:19.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:17:0) after 1m0s
[12:13:19.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:17:0)
[12:13:19.921] Timer tick, asking Buildbucket for the build status
[12:13:20.030] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:20.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:18:0) after 1m0s
[12:14:20.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:18:0)
[12:14:20.055] Timer tick, asking Buildbucket for the build status
[12:14:20.130] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:20.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:19:0) after 1m0s
[12:15:20.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:19:0)
[12:15:20.157] Timer tick, asking Buildbucket for the build status
[12:15:20.344] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:20.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:20:0) after 1m0s
[12:16:20.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:20:0)
[12:16:20.396] Timer tick, asking Buildbucket for the build status
[12:16:20.457] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:20.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:21:0) after 1m0s
[12:17:20.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:21:0)
[12:17:20.476] Timer tick, asking Buildbucket for the build status
[12:17:20.540] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:20.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:22:0) after 1m0s
[12:18:20.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:22:0)
[12:18:20.670] Timer tick, asking Buildbucket for the build status
[12:18:20.800] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:20.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:23:0) after 1m0s
[12:19:20.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:23:0)
[12:19:20.849] Timer tick, asking Buildbucket for the build status
[12:19:20.920] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:20.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:24:0) after 1m0s
[12:20:20.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:24:0)
[12:20:20.942] Timer tick, asking Buildbucket for the build status
[12:20:21.043] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:21.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:25:0) after 1m0s
[12:21:21.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:25:0)
[12:21:21.083] Timer tick, asking Buildbucket for the build status
[12:21:21.216] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:21.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:26:0) after 1m0s
[12:22:21.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:26:0)
[12:22:21.246] Timer tick, asking Buildbucket for the build status
[12:22:21.317] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:21.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:27:0) after 1m0s
[12:23:21.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:27:0)
[12:23:21.426] Timer tick, asking Buildbucket for the build status
[12:23:21.553] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:21.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:28:0) after 1m0s
[12:24:21.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:28:0)
[12:24:21.581] Timer tick, asking Buildbucket for the build status
[12:24:21.716] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:21.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:29:0) after 1m0s
[12:25:21.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:29:0)
[12:25:21.877] Timer tick, asking Buildbucket for the build status
[12:25:22.035] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:22.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:30:0) after 1m0s
[12:26:22.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:30:0)
[12:26:22.130] Timer tick, asking Buildbucket for the build status
[12:26:22.311] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:22.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:31:0) after 1m0s
[12:27:22.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:31:0)
[12:27:22.352] Timer tick, asking Buildbucket for the build status
[12:27:22.478] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:22.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:32:0) after 1m0s
[12:28:22.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:32:0)
[12:28:22.511] Timer tick, asking Buildbucket for the build status
[12:28:22.892] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:22.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:33:0) after 1m0s
[12:29:22.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:33:0)
[12:29:22.916] Timer tick, asking Buildbucket for the build status
[12:29:23.503] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:23.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:34:0) after 1m0s
[12:30:23.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:34:0)
[12:30:23.604] Timer tick, asking Buildbucket for the build status
[12:30:23.743] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:23.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:35:0) after 1m0s
[12:31:23.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:35:0)
[12:31:23.763] Timer tick, asking Buildbucket for the build status
[12:31:23.867] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:23.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:36:0) after 1m0s
[12:32:23.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:36:0)
[12:32:23.897] Timer tick, asking Buildbucket for the build status
[12:32:24.069] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:24.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:37:0) after 1m0s
[12:33:24.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:37:0)
[12:33:24.164] Timer tick, asking Buildbucket for the build status
[12:33:24.273] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:24.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:38:0) after 1m0s
[12:34:24.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:38:0)
[12:34:24.546] Timer tick, asking Buildbucket for the build status
[12:34:24.923] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:24.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:39:0) after 1m0s
[12:35:24.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:39:0)
[12:35:24.945] Timer tick, asking Buildbucket for the build status
[12:35:25.288] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:25.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:40:0) after 1m0s
[12:36:25.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:40:0)
[12:36:25.309] Timer tick, asking Buildbucket for the build status
[12:36:25.416] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:25.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:41:0) after 1m0s
[12:37:25.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:41:0)
[12:37:25.448] Timer tick, asking Buildbucket for the build status
[12:37:25.542] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:25.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:42:0) after 1m0s
[12:38:25.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:42:0)
[12:38:25.538] Timer tick, asking Buildbucket for the build status
[12:38:25.649] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:25.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:43:0) after 1m0s
[12:39:25.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:43:0)
[12:39:25.755] Timer tick, asking Buildbucket for the build status
[12:39:25.867] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:25.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:44:0) after 1m0s
[12:40:26.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:44:0)
[12:40:26.031] Timer tick, asking Buildbucket for the build status
[12:40:26.139] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:26.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:45:0) after 1m0s
[12:41:26.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:45:0)
[12:41:26.367] Timer tick, asking Buildbucket for the build status
[12:41:26.462] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:26.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:46:0) after 1m0s
[12:42:26.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:46:0)
[12:42:26.530] Timer tick, asking Buildbucket for the build status
[12:42:26.848] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:26.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:47:0) after 1m0s
[12:43:26.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:47:0)
[12:43:26.947] Timer tick, asking Buildbucket for the build status
[12:43:27.075] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:27.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:48:0) after 1m0s
[12:44:27.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:48:0)
[12:44:27.161] Timer tick, asking Buildbucket for the build status
[12:44:27.309] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:27.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:49:0) after 1m0s
[12:45:27.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:49:0)
[12:45:27.379] Timer tick, asking Buildbucket for the build status
[12:45:27.455] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:27.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:50:0) after 1m0s
[12:46:27.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:50:0)
[12:46:27.509] Timer tick, asking Buildbucket for the build status
[12:46:27.703] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:27.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:51:0) after 1m0s
[12:47:27.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:51:0)
[12:47:27.810] Timer tick, asking Buildbucket for the build status
[12:47:27.904] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:27.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:52:0) after 1m0s
[12:48:28.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:52:0)
[12:48:28.036] Timer tick, asking Buildbucket for the build status
[12:48:28.147] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:28.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:53:0) after 1m0s
[12:49:28.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:53:0)
[12:49:28.259] Timer tick, asking Buildbucket for the build status
[12:49:28.362] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:28.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:54:0) after 1m0s
[12:50:28.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:54:0)
[12:50:28.503] Timer tick, asking Buildbucket for the build status
[12:50:28.619] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:28.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:55:0) after 1m0s
[12:51:28.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:55:0)
[12:51:28.749] Timer tick, asking Buildbucket for the build status
[12:51:28.826] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:28.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:56:0) after 1m0s
[12:52:28.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:56:0)
[12:52:28.820] Timer tick, asking Buildbucket for the build status
[12:52:28.939] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:28.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:57:0) after 1m0s
[12:53:29.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:57:0)
[12:53:29.054] Timer tick, asking Buildbucket for the build status
[12:53:29.156] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:29.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:58:0) after 1m0s
[12:54:29.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:58:0)
[12:54:29.144] Timer tick, asking Buildbucket for the build status
[12:54:29.207] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:29.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:59:0) after 1m0s
[12:55:29.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:59:0)
[12:55:29.266] Timer tick, asking Buildbucket for the build status
[12:55:29.347] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:29.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:60:0) after 1m0s
[12:56:29.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:60:0)
[12:56:29.492] Timer tick, asking Buildbucket for the build status
[12:56:29.588] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:29.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:61:0) after 1m0s
[12:57:30.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:61:0)
[12:57:30.549] Timer tick, asking Buildbucket for the build status
[12:57:31.033] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:31.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:62:0) after 1m0s
[12:58:31.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:62:0)
[12:58:31.186] Timer tick, asking Buildbucket for the build status
[12:58:31.349] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:31.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:63:0) after 1m0s
[12:59:31.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:63:0)
[12:59:31.515] Timer tick, asking Buildbucket for the build status
[12:59:31.590] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:31.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:64:0) after 1m0s
[13:00:31.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:64:0)
[13:00:31.846] Timer tick, asking Buildbucket for the build status
[13:00:31.932] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:31.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:65:0) after 1m0s
[13:01:32.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:65:0)
[13:01:32.223] Timer tick, asking Buildbucket for the build status
[13:01:32.313] Build 8860667190329181712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:32.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:66:0) after 1m0s
[13:02:32.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026097252264510640:66:0)
[13:02:32.514] Timer tick, asking Buildbucket for the build status
[13:02:32.814] Build 8860667190329181712: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:02:32.814] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608210151587388",
"created_by": "project:chromium",
"created_ts": "1608206296567345",
"failure_reason": "BUILD_FAILURE",
"id": "8860667190329181712",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9ee8fcb2c04904eb694a2c998191493426257a72\"}}",
"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.FrameSizeChangeVideo/0\\n\\n- MSE_ExternalClearKey/EncryptedMediaTest.ConfigChangeVideo_ClearToEncrypted/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\": \"9ee8fcb2c04904eb694a2c998191493426257a72\", \"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-5-miap\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16280, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"b31cd871ad1000a56302409b62d76e079f8a0b46\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"9ee8fcb2c04904eb694a2c998191493426257a72\", \"got_revision_cp\": \"refs/heads/master@{#838029}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"faaf95add83bc517dd2e8fd417a99292aea4db49\", \"got_v8_revision_cp\": \"refs/heads/8.9.163@{#1}\", \"got_webrtc_revision\": \"8592111e458d253e53806db65d23d21bfe9d9db4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32849}\", \"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\": \"9ee8fcb2c04904eb694a2c998191493426257a72\", \"swarm_hashes_refs/heads/master(at){#838029}_without_patch\": {\"absl_hardening_tests\": \"b749955d7adbdc08c77fef20e5524150038b18a0\", \"accessibility_unittests\": \"a3bf7388e0eebc1d686da82964b0a13549f524e7\", \"angle_unittests\": \"33fd423b6c431cf0a99374da20f61a92cb42a707\", \"app_shell_unittests\": \"20350fb086f29d9db278e96523fd842658672047\", \"aura_unittests\": \"00a50412abfdfa9e5803422ed9d846455a2fdc57\", \"base_unittests\": \"c1f22be5d1273d5f347032fb54e810f23229b76c\", \"base_util_unittests\": \"bb1f103958814bd4d466cdcd5e5904e1919a1290\", \"blink_common_unittests\": \"20cfec246be00721c1314ad2b801be4b34e6cfb7\", \"blink_fuzzer_unittests\": \"fdb98391e50d23637d18ad3ef3892a43dce9f1c9\", \"blink_heap_unittests\": \"be80c63ab458f139075b6831e9d3168b94f54ebb\", \"blink_platform_unittests\": \"53f7cb435691c25170e10f2d84671e5f99cfef8e\", \"blink_unittests\": \"79dcec5e0eff71e6d40168fc8b107c0389a5d311\", \"boringssl_crypto_tests\": \"cd611bcb2ee0675c6ad27b52d24d8e3f306519d3\", \"boringssl_ssl_tests\": \"052edddcaca5de9df5e470c8f1ab961bf6065218\", \"browser_switcher_bho_unittests\": \"aa0a7c718463979880d0638b4928253fae4897a2\", \"browser_tests\": \"8f1ef517ac0e0363bb8909ed459ac9a37cca23b4\", \"capture_unittests\": \"9d15b94753e65c057c83bf6cec409b0263ca62f1\", \"cast_unittests\": \"1bab86dba93b51161a8cdb1b48068dcc20a5f6ce\", \"cc_unittests\": \"c4ee74f8ac64df992cecbec9fe08891bedd42ab7\", \"chrome_app_unittests\": \"0c5a00c961efb2a70608d78a1d4959900292ca02\", \"chrome_cleaner_unittests\": \"17ded7af5a2c43b97759969e425af8d478793316\", \"chrome_elf_unittests\": \"81f4fcdbe769744cd1697fef5814ed59d977200a\", \"chromedriver_unittests\": \"d51d29f4a0c9ab0c76223ecebf67a34e182037bf\", \"components_browsertests\": \"7b295d57b051476401f3c7732f988404426845ed\", \"components_unittests\": \"6d3268b47773b1e569ebab0fa1ece4a28e11b4c0\", \"compositor_unittests\": \"917978b114beaddcecc075a7c2f154b837006003\", \"content_browsertests\": \"4119830177f104719ca058cdc80dd284f7bc0f36\", \"content_unittests\": \"a12edebff5026438a4223a5a9b251de991dfd071\", \"courgette_unittests\": \"6016f3cba4969ab9e08e401c49fdbe487a2d62b3\", \"crashpad_tests\": \"b55303625a2f106c8a9d3a9ce40f9a4416954205\", \"cronet_tests\": \"d63539b36b9993750dd2f098b1b453b34d3054cd\", \"cronet_unittests\": \"13f3296f4a03792ee5de88f5e5244312bee67df3\", \"crypto_unittests\": \"f67c2c74b7dfbd06480266014ced75d1f71d92f3\", \"delayloads_unittests\": \"866e702eb07fd885865ab8df4171338ca2688110\", \"device_unittests\": \"166f1e8356eca53e6571fc00a7c720f555024145\", \"display_unittests\": \"e031959bd75b751f707b8e3ac6aed1d8325fefe2\", \"elevation_service_unittests\": \"bf07114ff6a9aaa09e96df2ebd7b35832dd158d2\", \"events_unittests\": \"0cde210fb6497b0814d2715e37319f78b5a7cd6b\", \"extensions_browsertests\": \"a6b795a803b7cb54ab94a4327afbf118cf055a70\", \"extensions_unittests\": \"12973ba5c5ed71ec0412936d11a160e6c607d5c1\", \"filesystem_service_unittests\": \"b50d04ee68d92307d1a64b9cadf82e167ba0d637\", \"gcm_unit_tests\": \"d984d75bf03a99d5e91a94b49c832b5ae7e27d99\", \"gcp_unittests\": \"d1f0110737b244ea99c05915ff763303f13f323e\", \"gfx_unittests\": \"5929b3573dbd532736490a169ae6864f82e9e73e\", \"gin_unittests\": \"e7e3dcfe262d0515eea6c1931e6644b4c85e5e49\", \"google_apis_unittests\": \"11a237e07517b78ffbe1c4dd525d0b87df33e773\", \"gpu_unittests\": \"27115f8e847537bc8ad53343f6ecf77b837e6c9b\", \"gwp_asan_unittests\": \"1c5fd1835301b04075c92fe22468335026a237d1\", \"headless_browsertests\": \"0ee5d6ea1069e5c0769e9fc5e8512ddcb33f4613\", \"headless_unittests\": \"fd3875753426a6c69ad86c16093542b305576c4f\", \"install_static_unittests\": \"1ff0bd34d2eb4547e57d0cc2307d67132185c9be\", \"installer_util_unittests\": \"66de9c8f8e56e7517c5dd882eba2d163e1cf7824\", \"interactive_ui_tests\": \"fb5aa853c4a746365f18e0c9d36e46bb3ccad4a7\", \"ipc_tests\": \"9b51931b235aaa2dc905fcbee29d88313554f927\", \"jingle_unittests\": \"095ab25abb70c76c5bfe48f4a507f183013e89d2\", \"latency_unittests\": \"b5d9a0c3b7512e6d0ce7431668a5bddcc9d3bb01\", \"libjingle_xmpp_unittests\": \"d7463b68a197dae7342cccc3d415e710d42feb75\", \"liburlpattern_unittests\": \"edb7a4a12df6732b1ad27aee7b18ba9cf5246547\", \"media_blink_unittests\": \"aa8f3d4b0296b8a72ac3e221919264b8b999155f\", \"media_unittests\": \"1cc4e7bdf6beb843c42734f81c1469e5e4284a77\", \"message_center_unittests\": \"d0c353327d3884c0db27fd26daa18be898bb5a43\", \"midi_unittests\": \"bd08a41e651652be5bed19ce8c362c297f6358e3\", \"mojo_core_unittests\": \"c8408e456cb6fe579326261506cfa0fb10daa8e3\", \"mojo_unittests\": \"d79789896cb314a39e4b97d3ec53c9db6b77b1a5\", \"nacl_loader_unittests\": \"6fe66910076419f5ff8b8d3de9881f45b8a4c215\", \"native_theme_unittests\": \"d8f2b7c8137da562712ec35929d7639c5d8ae7ec\", \"net_unittests\": \"fdcc2c6bea3bc5a05a59809b1b13e92a32ce9ed6\", \"notification_helper_unittests\": \"892e65bf5d3d2cbf2f01987e60ff1e60d5b8df09\", \"pdf_unittests\": \"9a0733788557e0c058d27e5a1360f47f18ed7400\", \"perfetto_unittests\": \"e3ba731e499c15c25d2e9b6fe2560919e4ee918b\", \"ppapi_unittests\": \"37cad0cb37c3132aa9bb8bccfaf982aac2475739\", \"printing_unittests\": \"ffaca52e03fb8b8a87a7750ca1f7015cb2e1e764\", \"remoting_unittests\": \"38441fba255ca7f3c15df98081980a9ed51aca71\", \"sbox_integration_tests\": \"66a6c7605b732a1e5b664db60644198731813fea\", \"sbox_unittests\": \"fa0491739bac38572f75b771991230031b2b8f56\", \"sbox_validation_tests\": \"74ebc5b7833af9635ceccacdd0656f4424c29838\", \"service_manager_unittests\": \"e5b2bfe909ceffa4d18eced4e7236bb9e796539a\", \"services_unittests\": \"b3047e41c2cc7aa2737d9b52cd0addac325a8cf3\", \"setup_unittests\": \"d5c6aec890ff7748f93651cb781ca47ef56e8985\", \"shell_dialogs_unittests\": \"d0fc538d4be09ee0723d0ecfa072a1bf0eee7a35\", \"skia_unittests\": \"6f1380f4c1f7030d49e093485c89b379799834fc\", \"snapshot_unittests\": \"0c9fd38db6e1ebfbf3e58ea7cb47c975727900be\", \"sql_unittests\": \"41c25d95560d05acda5cc18a1434264ee180ee7a\", \"storage_unittests\": \"9608e9ac1a756b3437def822c893c339c53e3e6e\", \"sync_integration_tests\": \"7982053ea6eccfd9e4b783ff0079131cd0fa1b48\", \"traffic_annotation_auditor_unittests\": \"90415013c6211500d2920a8453baa5d99dbaef24\", \"ui_base_unittests\": \"473c4d5764fa844b89944b5d350f44d7235d36c0\", \"ui_touch_selection_unittests\": \"0249b61151f7ebf53655f748e9a30e02f7b1af2b\", \"unit_tests\": \"1da426ebcbb51192418ceab98fbfc945d440d5c0\", \"updater_tests\": \"c9d84ace3d0bd13c8b5e7006a58f6e9785c41923\", \"url_unittests\": \"e6449b180132a7101845f4a2caf5988cbc0fa870\", \"views_unittests\": \"74feb8c577ccb3c303728748a7bbb7cb37e1b7d0\", \"viz_unittests\": \"af9b5c7f8af44ed8f6c0b534bb063db6fc7753d4\", \"vr_common_unittests\": \"4aedc07adfe776d5d9ea4e3e1ab90d2426760eb3\", \"vr_pixeltests\": \"6c410f8b3ed52beb89bbc05bf947d888d2ea0185\", \"weblayer_browsertests\": \"3659146f03a1715a9d647b933db1a61d27fcbe19\", \"weblayer_unittests\": \"acc1b8237ae014cf0d3685bb431c46a38fcf2281\", \"wm_unittests\": \"54a10bad5c01022083f67011c25a32b89abd45cc\", \"wtf_unittests\": \"60f1f9c48bd3b7da69f8f641a1e7342c54955f98\", \"zlib_unittests\": \"82cddb11b1ca846c88090bf0d2a5f2183759a801\", \"zucchini_unittests\": \"14d6663e15a5047d77463432df2cd4e8392e92d5\"}}, \"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-5-miap\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5657-0417a3f\"], \"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.FrameSizeChangeVideo/0\\n\\n- MSE_ExternalClearKey/EncryptedMediaTest.ConfigChangeVideo_ClearToEncrypted/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608206299704820",
"status": "COMPLETED",
"status_changed_ts": "1608206299977335",
"tags": [
"build_address:luci.chromium.ci/win-asan/16280",
"builder:win-asan",
"buildset:commit/git/9ee8fcb2c04904eb694a2c998191493426257a72",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ee8fcb2c04904eb694a2c998191493426257a72",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026097252264510640",
"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/8860667190329181712/+/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:50896231f2fd3410",
"user_agent:luci-scheduler"
],
"updated_ts": "1608210151867788",
"url": "https://ci.chromium.org/b/8860667190329181712",
"utcnow_ts": "1608210152801816"
}
}
[13:02:32.814] Invocation finished in 1h4m17.47636074s with status FAILED