[20:12:25.082] New invocation is queued and will start shortly
[20:12:26.521] Starting the invocation (attempt 1)
[20:12:26.574] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:12:26.574] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:12:26.574] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026700341039305120",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"55698904703f945c8ca06b0aaeb1fe014c5cb3b1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026700341039305120",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/55698904703f945c8ca06b0aaeb1fe014c5cb3b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/55698904703f945c8ca06b0aaeb1fe014c5cb3b1",
"gitiles_ref:refs/heads/master"
]
}
[20:12:27.751] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607631146704966",
"id": "8861270278672018432",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"55698904703f945c8ca06b0aaeb1fe014c5cb3b1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607631147429727",
"tags": [
"build_address:luci.chromium.ci/win-asan/16146",
"builder:win-asan",
"buildset:commit/git/55698904703f945c8ca06b0aaeb1fe014c5cb3b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/55698904703f945c8ca06b0aaeb1fe014c5cb3b1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026700341039305120",
"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/8861270278672018432/+/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": "1607631147429847",
"url": "https://ci.chromium.org/b/8861270278672018432",
"utcnow_ts": "1607631147738707"
}
}
[20:12:27.751] Task URL: https://ci.chromium.org/b/8861270278672018432
[20:12:27.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:2:0) after 1m0s
[20:12:29.453] Received PubSub notification, asking Buildbucket for the build status
[20:12:29.535] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:27.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:2:0)
[20:13:27.746] Timer tick, asking Buildbucket for the build status
[20:13:27.844] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:27.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:4:0) after 1m0s
[20:14:27.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:4:0)
[20:14:27.890] Timer tick, asking Buildbucket for the build status
[20:14:28.074] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:28.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:5:0) after 1m0s
[20:15:28.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:5:0)
[20:15:28.097] Timer tick, asking Buildbucket for the build status
[20:15:28.249] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:28.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:6:0) after 1m0s
[20:16:28.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:6:0)
[20:16:28.296] Timer tick, asking Buildbucket for the build status
[20:16:28.536] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:28.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:7:0) after 1m0s
[20:17:28.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:7:0)
[20:17:28.618] Timer tick, asking Buildbucket for the build status
[20:17:28.732] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:28.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:8:0) after 1m0s
[20:18:28.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:8:0)
[20:18:28.750] Timer tick, asking Buildbucket for the build status
[20:18:28.875] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:28.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:9:0) after 1m0s
[20:19:28.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:9:0)
[20:19:28.923] Timer tick, asking Buildbucket for the build status
[20:19:28.992] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:28.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:10:0) after 1m0s
[20:20:29.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:10:0)
[20:20:29.073] Timer tick, asking Buildbucket for the build status
[20:20:29.341] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:29.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:11:0) after 1m0s
[20:21:29.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:11:0)
[20:21:29.375] Timer tick, asking Buildbucket for the build status
[20:21:29.601] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:29.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:12:0) after 1m0s
[20:22:29.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:12:0)
[20:22:29.704] Timer tick, asking Buildbucket for the build status
[20:22:29.808] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:29.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:13:0) after 1m0s
[20:23:29.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:13:0)
[20:23:29.861] Timer tick, asking Buildbucket for the build status
[20:23:29.940] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:29.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:14:0) after 1m0s
[20:24:30.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:14:0)
[20:24:30.016] Timer tick, asking Buildbucket for the build status
[20:24:30.106] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:30.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:15:0) after 1m0s
[20:25:30.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:15:0)
[20:25:30.169] Timer tick, asking Buildbucket for the build status
[20:25:30.403] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:30.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:16:0) after 1m0s
[20:26:30.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:16:0)
[20:26:30.452] Timer tick, asking Buildbucket for the build status
[20:26:30.723] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:30.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:17:0) after 1m0s
[20:27:30.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:17:0)
[20:27:30.779] Timer tick, asking Buildbucket for the build status
[20:27:30.895] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:30.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:18:0) after 1m0s
[20:28:30.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:18:0)
[20:28:30.918] Timer tick, asking Buildbucket for the build status
[20:28:31.119] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:31.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:19:0) after 1m0s
[20:29:31.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:19:0)
[20:29:31.156] Timer tick, asking Buildbucket for the build status
[20:29:31.255] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:31.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:20:0) after 1m0s
[20:30:31.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:20:0)
[20:30:31.290] Timer tick, asking Buildbucket for the build status
[20:30:31.743] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:31.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:21:0) after 1m0s
[20:31:31.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:21:0)
[20:31:31.848] Timer tick, asking Buildbucket for the build status
[20:31:31.987] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:31.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:22:0) after 1m0s
[20:32:32.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:22:0)
[20:32:32.052] Timer tick, asking Buildbucket for the build status
[20:32:32.738] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:32.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:23:0) after 1m0s
[20:33:32.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:23:0)
[20:33:32.843] Timer tick, asking Buildbucket for the build status
[20:33:32.980] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:32.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:24:0) after 1m0s
[20:34:33.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:24:0)
[20:34:33.029] Timer tick, asking Buildbucket for the build status
[20:34:33.351] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:33.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:25:0) after 1m0s
[20:35:33.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:25:0)
[20:35:33.386] Timer tick, asking Buildbucket for the build status
[20:35:33.538] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:33.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:26:0) after 1m0s
[20:36:33.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:26:0)
[20:36:33.579] Timer tick, asking Buildbucket for the build status
[20:36:33.930] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:33.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:27:0) after 1m0s
[20:37:34.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:27:0)
[20:37:34.048] Timer tick, asking Buildbucket for the build status
[20:37:34.200] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:34.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:28:0) after 1m0s
[20:38:34.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:28:0)
[20:38:34.275] Timer tick, asking Buildbucket for the build status
[20:38:34.644] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:34.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:29:0) after 1m0s
[20:39:34.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:29:0)
[20:39:34.664] Timer tick, asking Buildbucket for the build status
[20:39:34.810] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:34.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:30:0) after 1m0s
[20:40:34.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:30:0)
[20:40:34.787] Timer tick, asking Buildbucket for the build status
[20:40:34.855] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:34.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:31:0) after 1m0s
[20:41:34.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:31:0)
[20:41:34.845] Timer tick, asking Buildbucket for the build status
[20:41:35.223] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:35.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:32:0) after 1m0s
[20:42:35.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:32:0)
[20:42:35.310] Timer tick, asking Buildbucket for the build status
[20:42:35.653] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:35.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:33:0) after 1m0s
[20:43:35.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:33:0)
[20:43:35.675] Timer tick, asking Buildbucket for the build status
[20:43:35.840] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:35.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:34:0) after 1m0s
[20:44:35.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:34:0)
[20:44:35.877] Timer tick, asking Buildbucket for the build status
[20:44:36.026] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:36.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:35:0) after 1m0s
[20:45:36.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:35:0)
[20:45:36.051] Timer tick, asking Buildbucket for the build status
[20:45:36.131] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:36.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:36:0) after 1m0s
[20:46:36.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:36:0)
[20:46:36.430] Timer tick, asking Buildbucket for the build status
[20:46:36.593] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:36.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:37:0) after 1m0s
[20:47:36.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:37:0)
[20:47:36.800] Timer tick, asking Buildbucket for the build status
[20:47:36.925] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:36.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:38:0) after 1m0s
[20:48:37.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:38:0)
[20:48:37.106] Timer tick, asking Buildbucket for the build status
[20:48:37.359] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:37.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:39:0) after 1m0s
[20:49:37.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:39:0)
[20:49:37.390] Timer tick, asking Buildbucket for the build status
[20:49:37.679] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:37.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:40:0) after 1m0s
[20:50:37.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:40:0)
[20:50:37.806] Timer tick, asking Buildbucket for the build status
[20:50:38.178] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:38.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:41:0) after 1m0s
[20:51:38.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:41:0)
[20:51:38.193] Timer tick, asking Buildbucket for the build status
[20:51:38.278] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:38.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:42:0) after 1m0s
[20:52:38.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:42:0)
[20:52:38.316] Timer tick, asking Buildbucket for the build status
[20:52:38.470] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:38.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:43:0) after 1m0s
[20:53:38.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:43:0)
[20:53:38.508] Timer tick, asking Buildbucket for the build status
[20:53:38.694] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:38.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:44:0) after 1m0s
[20:54:38.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:44:0)
[20:54:38.750] Timer tick, asking Buildbucket for the build status
[20:54:38.873] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:38.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:45:0) after 1m0s
[20:55:38.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:45:0)
[20:55:38.913] Timer tick, asking Buildbucket for the build status
[20:55:39.051] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:39.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:46:0) after 1m0s
[20:56:39.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:46:0)
[20:56:39.067] Timer tick, asking Buildbucket for the build status
[20:56:39.233] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:39.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:47:0) after 1m0s
[20:57:39.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:47:0)
[20:57:39.277] Timer tick, asking Buildbucket for the build status
[20:57:39.441] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:39.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:48:0) after 1m0s
[20:58:39.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:48:0)
[20:58:39.501] Timer tick, asking Buildbucket for the build status
[20:58:39.621] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:39.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:49:0) after 1m0s
[20:59:39.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:49:0)
[20:59:39.708] Timer tick, asking Buildbucket for the build status
[20:59:39.913] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:39.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:50:0) after 1m0s
[21:00:40.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:50:0)
[21:00:40.538] Timer tick, asking Buildbucket for the build status
[21:00:41.024] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:41.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:51:0) after 1m0s
[21:01:41.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:51:0)
[21:01:41.299] Timer tick, asking Buildbucket for the build status
[21:01:41.405] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:41.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:52:0) after 1m0s
[21:02:41.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:52:0)
[21:02:41.516] Timer tick, asking Buildbucket for the build status
[21:02:41.801] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:41.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:53:0) after 1m0s
[21:03:41.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:53:0)
[21:03:41.858] Timer tick, asking Buildbucket for the build status
[21:03:42.009] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:42.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:54:0) after 1m0s
[21:04:42.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:54:0)
[21:04:42.070] Timer tick, asking Buildbucket for the build status
[21:04:42.145] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:42.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:55:0) after 1m0s
[21:05:42.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:55:0)
[21:05:42.182] Timer tick, asking Buildbucket for the build status
[21:05:42.282] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:42.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:56:0) after 1m0s
[21:06:42.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:56:0)
[21:06:42.409] Timer tick, asking Buildbucket for the build status
[21:06:42.536] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:42.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:57:0) after 1m0s
[21:07:42.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:57:0)
[21:07:42.560] Timer tick, asking Buildbucket for the build status
[21:07:42.716] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:42.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:58:0) after 1m0s
[21:08:42.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:58:0)
[21:08:42.740] Timer tick, asking Buildbucket for the build status
[21:08:43.223] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:43.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:59:0) after 1m0s
[21:09:43.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:59:0)
[21:09:43.425] Timer tick, asking Buildbucket for the build status
[21:09:43.604] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:43.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:60:0) after 1m0s
[21:10:43.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:60:0)
[21:10:43.702] Timer tick, asking Buildbucket for the build status
[21:10:44.197] Build 8861270278672018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:44.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026700341039305120:61:0) after 1m0s
[21:11:38.570] Received PubSub notification, asking Buildbucket for the build status
[21:11:38.637] Build 8861270278672018432: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:11:38.637] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607634696968381",
"created_by": "project:chromium",
"created_ts": "1607631146704966",
"id": "8861270278672018432",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"55698904703f945c8ca06b0aaeb1fe014c5cb3b1\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"55698904703f945c8ca06b0aaeb1fe014c5cb3b1\", \"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-6-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16146, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"108b759ef91bd51e76e3d9ca025fc27e4067fea8\", \"got_dawn_revision\": \"6bcd4aceef100810284d61bd9dad0e9bd05e4c92\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"55698904703f945c8ca06b0aaeb1fe014c5cb3b1\", \"got_revision_cp\": \"refs/heads/master@{#835786}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"35f077086ef34ae8d0cabfba346b602d08c49160\", \"got_v8_revision\": \"73cdeec67c6c85946bb91a2b74c63f93acd4cbe7\", \"got_v8_revision_cp\": \"refs/heads/8.9.129@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"55698904703f945c8ca06b0aaeb1fe014c5cb3b1\", \"swarm_hashes_refs/heads/master(at){#835786}_without_patch\": {\"absl_hardening_tests\": \"6c27b86726df8319fdf5cb6cb424ba3a82ec18c0\", \"accessibility_unittests\": \"cc9cce199d457c6521502ccb8c8b435760e3e3a0\", \"angle_unittests\": \"2a699a847d42b5ed98063a9dfdc4146e7cfaee26\", \"app_shell_unittests\": \"92e90ee76f91e8c3b7917c9f55ac9a6d8f649ef9\", \"aura_unittests\": \"c0bd40bfe2f4458d4cb50f3829082dcab45b86c4\", \"base_unittests\": \"5209ac005dd0610b58b063c3c519837686513edc\", \"base_util_unittests\": \"43d9cf5299bf7a42f67649c233d8c914242bafa0\", \"blink_common_unittests\": \"9246061f72f86550af95cc2f4e3e4774ebcbe4e6\", \"blink_fuzzer_unittests\": \"e145f6d956932459af9ff7285cd002643cdefbf2\", \"blink_heap_unittests\": \"8bd48a8c739d6eb4a7d73301b6ea3f4e653d534d\", \"blink_platform_unittests\": \"3e8bc9707ccfd51d76fd317ac17e6da528721f80\", \"blink_unittests\": \"250ba7a0b441cf5c3d8ef3f7f739ce387f25d901\", \"boringssl_crypto_tests\": \"b1f640b797b903f15c9343dbe97aeaf84571b95a\", \"boringssl_ssl_tests\": \"f7624b3da64775e1738ba5737e45dcb5800b9126\", \"browser_switcher_bho_unittests\": \"a4768a4b79fb2db0a76f1935fd5ee65a4746fafb\", \"browser_tests\": \"e461528819630b159731d53db11d54ae2ad79029\", \"capture_unittests\": \"6e61d8f954b50ec29005c9f6192b87d0f93aa534\", \"cast_unittests\": \"62e93631e199a0160455a6ca25668a1d85fc3abf\", \"cc_unittests\": \"5ede7f3cd2524e6811c5dab5dd98b9ca494fd416\", \"chrome_app_unittests\": \"d42be4e8fd416d9f3c5a32d16dd6f9da34df0841\", \"chrome_cleaner_unittests\": \"27b31ffe9f74559da2d125719b91008a4d7b1f35\", \"chrome_elf_unittests\": \"207bc7ba8a711a1e0d96fe7da9de779c7f97b7db\", \"chromedriver_unittests\": \"93d4412376a1bb140c6e02904ab49c57dd4c4ad0\", \"components_browsertests\": \"e7047c8a8d04f2f5fbc0f8ab8bfec99a062bb551\", \"components_unittests\": \"3cb1f59ee20116ecf71fc4d33b0077820bfe2fdb\", \"compositor_unittests\": \"ee1ced1fc88b5e3d394bed1debf65fb4552dad4c\", \"content_browsertests\": \"8d7e3139b5d0cd8f7e66c539eb268ab090f88e99\", \"content_unittests\": \"370bc46308ff576b2256aed5625fcedcabe1c7e3\", \"courgette_unittests\": \"0dd9fa74f3b9b00387bf12ca62559d6a4368bfd4\", \"crashpad_tests\": \"c90541a7b8171b13377fab8b3ab2416fda25d368\", \"cronet_tests\": \"0e3302f1c9716cd3afc548342c3236a6106c8dbb\", \"cronet_unittests\": \"ff887b64b86abbb995dc34acd5749b143e5b5771\", \"crypto_unittests\": \"9ee6a4af669a53559246dfec3c46bd32cc62ad5b\", \"delayloads_unittests\": \"586aae50ec597ed80d3feaef9092fda310963c28\", \"device_unittests\": \"1c5100bfe4723b992c9570abcfb03f86eeb9bc46\", \"display_unittests\": \"1a2f3e8bad3928fcc1dd97cd0aeb027cc460eb5f\", \"elevation_service_unittests\": \"c2eb63c6167f4c4483588d0ecceef71ed369449f\", \"events_unittests\": \"c4e0ec56d1153ec30b0f420288de1ede37a7deba\", \"extensions_browsertests\": \"df5e431bac09bb4663aeec80aa99f5fbfa41d3b0\", \"extensions_unittests\": \"800e7e468deb83e78ba640a8ffd16a2012e4afab\", \"filesystem_service_unittests\": \"289943c0db76c7c8cbe5d1896a3e21b18016fd7f\", \"gcm_unit_tests\": \"f4f702dcbad79f06133c4462d21aa6755c317576\", \"gcp_unittests\": \"1ac3804eef7bfed3b6d4aed74eba18de9399037a\", \"gfx_unittests\": \"65a99e1878422a95e35e14b6ff3918888ada555d\", \"gin_unittests\": \"be3db495a3b939375650e63478fc03f4d54d9e35\", \"google_apis_unittests\": \"f6a24fe020f55a303c913f3c56d907217a1ecd79\", \"gpu_unittests\": \"7c5eee4a21586aee06d783d3b52341e5c0f7fb5e\", \"gwp_asan_unittests\": \"fca4f94eb1ce96b472bdbb0a6c0e7d1ed9c43757\", \"headless_browsertests\": \"9e81434644a0e7323409ab7ea6f1d1b53911ed8d\", \"headless_unittests\": \"b154465a13b2bc2a7a20674e77dddd5db858eab2\", \"install_static_unittests\": \"a11c2174bc647d8aa9c6ef6a9ed34bf3a558456c\", \"installer_util_unittests\": \"a40776f2fc3c82bb6986e3ebac630f1e8ce5e419\", \"interactive_ui_tests\": \"0eb11688e5545893ec930444c9e264e1d81b8713\", \"ipc_tests\": \"c51f81e333ace655df0ab1596007e4b002adc886\", \"jingle_unittests\": \"e82b832cec969ee578a3813482533c6b9c78992f\", \"latency_unittests\": \"510f168ba0e7d1224938e31e5532c49fda763aea\", \"libjingle_xmpp_unittests\": \"35db9eeea32c33ef94d40a0abeaf4d71e35da711\", \"liburlpattern_unittests\": \"622afe121d620f6e722e26be9b699315a60556f6\", \"media_blink_unittests\": \"614e3db36332cf516d8a539b4b5f6108b9b62857\", \"media_unittests\": \"6c3eb7576561609d8ac4af57f55ea96765a823ea\", \"message_center_unittests\": \"a3beb245a874e79fa62d1b0581bed0eaceda6322\", \"midi_unittests\": \"c37ca1a6f9ff4b1f3155aedb8469825fd5271abf\", \"mojo_core_unittests\": \"af5519769d3b934ff37b67e3378ac532ccdbaedb\", \"mojo_unittests\": \"55baac10cecedcda387cd624da02f4fd9de1cb73\", \"nacl_loader_unittests\": \"e3b33cefe1964bf6a082822c44d8e36cb6696a47\", \"native_theme_unittests\": \"2d541ae6054e888a13d1c17e35f27bb4af4fa2b8\", \"net_unittests\": \"6ca83e2239e2c3154915416f75075fbb3e4c2ba0\", \"notification_helper_unittests\": \"748cc9aa40c9979e28ceb4cfc95b84a0e88911fc\", \"pdf_unittests\": \"7bec160be60838658b434a59b783b58a1b3826a6\", \"perfetto_unittests\": \"6f0846dabf51a282de462842d984f49a10a2c855\", \"ppapi_unittests\": \"c695e15e285fad59b2bb76764baab904344f6f62\", \"printing_unittests\": \"ec9d266568b3fac018fbc418efc5124860243d45\", \"remoting_unittests\": \"20f771e1710d9492abffbd774140657842e26ef2\", \"sbox_integration_tests\": \"8ba7ba856de5f227f97b3783b00cd7da0800f148\", \"sbox_unittests\": \"c9d8243d273e5efea89439a849a2451a2928c81c\", \"sbox_validation_tests\": \"98229c42467f68c702a9e99c6caaa1396af2a74c\", \"service_manager_unittests\": \"d6e430d95c0f2eccdcc971c47adaa3616bae8b5b\", \"services_unittests\": \"af87631461e07e23b741d028febcc2bcffb5d62c\", \"setup_unittests\": \"036a4f6e67d0c4ed70f84feb4c0c76b270917d3e\", \"shell_dialogs_unittests\": \"05b6ce8b260c70fb743269d36e37d3b1a3a3edd4\", \"skia_unittests\": \"cab5db2a3939738fb5f3c825ca2bff6ede8a6109\", \"snapshot_unittests\": \"ec784eafeab2053ea0baf1c8cd2e11252a854307\", \"sql_unittests\": \"5b9786bdab74bfa3ba61e46061d065bf001b6080\", \"storage_unittests\": \"ac521d6f1ec51f8c71d1654a1f28198923e14823\", \"sync_integration_tests\": \"037cdd17115ad4f897953b84f02906c970d2fa49\", \"traffic_annotation_auditor_unittests\": \"25d906209ac8d1bcfa51a58f7035528fe0dc1c50\", \"ui_base_unittests\": \"d1fc2e0adc09789e695e13a96f76440a37d812ec\", \"ui_touch_selection_unittests\": \"a886f7f34f516298bd55190daafa507f0fc47f8b\", \"unit_tests\": \"432131a1115bf674e5124d1f1af9d464e6a31f9d\", \"updater_tests\": \"ee3c3e5c5708d9669c8e73ac088b2f056f43867e\", \"url_unittests\": \"fd8c4611c2abc46f2c667a23ecb4b9cad9667e29\", \"views_unittests\": \"163d0a3c5df026291a477864fcc237d656ae3be1\", \"viz_unittests\": \"c80bc1db8fb83c9c5254624603c5e7dd20c4ca20\", \"vr_common_unittests\": \"1d80809149d33cfda3c563e209cab02cbdeee618\", \"vr_pixeltests\": \"ecd3d009db8c1ede89d6b44893174f24a48a9b42\", \"weblayer_browsertests\": \"1be11d17fb8b8480de7f5c4d83de548788831243\", \"weblayer_unittests\": \"cc9e9bc7ae5f97037ed4b5d7282022157230b69e\", \"wm_unittests\": \"8b7f4ea95d1202a5ac1050cc742d968474bcdbf0\", \"wtf_unittests\": \"ca0c4bb5d1d442e6e554159fe9936ba0821c4799\", \"zlib_unittests\": \"27070dfdb2cf8678e4dd0d4ef3cf66225bdfb272\", \"zucchini_unittests\": \"53800f340e96fb80bb53de2caa236486359fbab4\"}}, \"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-6-3vmi\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607631148582698",
"status": "COMPLETED",
"status_changed_ts": "1607634697338156",
"tags": [
"build_address:luci.chromium.ci/win-asan/16146",
"builder:win-asan",
"buildset:commit/git/55698904703f945c8ca06b0aaeb1fe014c5cb3b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/55698904703f945c8ca06b0aaeb1fe014c5cb3b1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026700341039305120",
"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/8861270278672018432/+/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:50671a1bc802c210",
"user_agent:luci-scheduler"
],
"updated_ts": "1607634697343230",
"url": "https://ci.chromium.org/b/8861270278672018432",
"utcnow_ts": "1607634698628018"
}
}
[21:11:38.637] Invocation finished in 59m13.574317944s with status SUCCEEDED