[04:04:15.601] New invocation is queued and will start shortly
[04:04:16.666] Starting the invocation (attempt 1)
[04:04:16.698] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:04:16.698] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:04:16.698] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023137373617842944",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1fb49019410544c1183dbf3f15c541fdec7b3929\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023137373617842944",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1fb49019410544c1183dbf3f15c541fdec7b3929",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fb49019410544c1183dbf3f15c541fdec7b3929",
"gitiles_ref:refs/heads/master"
]
}
[04:04:18.550] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611029056857640",
"id": "8857707311634987824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1fb49019410544c1183dbf3f15c541fdec7b3929\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611029057977793",
"tags": [
"build_address:luci.chromium.ci/win-asan/16884",
"builder:win-asan",
"buildset:commit/git/1fb49019410544c1183dbf3f15c541fdec7b3929",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fb49019410544c1183dbf3f15c541fdec7b3929",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023137373617842944",
"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/8857707311634987824/+/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": "1611029057978116",
"url": "https://ci.chromium.org/b/8857707311634987824",
"utcnow_ts": "1611029058497097"
}
}
[04:04:18.550] Task URL: https://ci.chromium.org/b/8857707311634987824
[04:04:18.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:2:0) after 1m0s
[04:04:21.201] Received PubSub notification, asking Buildbucket for the build status
[04:04:21.282] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:18.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:2:0)
[04:05:18.609] Timer tick, asking Buildbucket for the build status
[04:05:18.718] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:18.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:4:0) after 1m0s
[04:06:18.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:4:0)
[04:06:18.732] Timer tick, asking Buildbucket for the build status
[04:06:18.842] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:18.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:5:0) after 1m0s
[04:07:18.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:5:0)
[04:07:18.925] Timer tick, asking Buildbucket for the build status
[04:07:19.378] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:19.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:6:0) after 1m0s
[04:08:19.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:6:0)
[04:08:19.509] Timer tick, asking Buildbucket for the build status
[04:08:19.605] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:19.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:7:0) after 1m0s
[04:09:19.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:7:0)
[04:09:19.740] Timer tick, asking Buildbucket for the build status
[04:09:20.092] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:20.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:8:0) after 1m0s
[04:10:20.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:8:0)
[04:10:20.225] Timer tick, asking Buildbucket for the build status
[04:10:20.288] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:20.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:9:0) after 1m0s
[04:11:20.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:9:0)
[04:11:20.325] Timer tick, asking Buildbucket for the build status
[04:11:20.391] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:20.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:10:0) after 1m0s
[04:12:20.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:10:0)
[04:12:20.471] Timer tick, asking Buildbucket for the build status
[04:12:20.642] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:20.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:11:0) after 1m0s
[04:13:20.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:11:0)
[04:13:20.753] Timer tick, asking Buildbucket for the build status
[04:13:21.306] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:21.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:12:0) after 1m0s
[04:14:21.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:12:0)
[04:14:21.330] Timer tick, asking Buildbucket for the build status
[04:14:21.441] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:21.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:13:0) after 1m0s
[04:15:21.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:13:0)
[04:15:21.631] Timer tick, asking Buildbucket for the build status
[04:15:21.691] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:21.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:14:0) after 1m0s
[04:16:21.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:14:0)
[04:16:21.872] Timer tick, asking Buildbucket for the build status
[04:16:22.128] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:22.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:15:0) after 1m0s
[04:17:22.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:15:0)
[04:17:22.241] Timer tick, asking Buildbucket for the build status
[04:17:22.327] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:22.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:16:0) after 1m0s
[04:18:22.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:16:0)
[04:18:22.411] Timer tick, asking Buildbucket for the build status
[04:18:22.615] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:22.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:17:0) after 1m0s
[04:19:22.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:17:0)
[04:19:22.724] Timer tick, asking Buildbucket for the build status
[04:19:22.893] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:22.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:18:0) after 1m0s
[04:20:22.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:18:0)
[04:20:22.986] Timer tick, asking Buildbucket for the build status
[04:20:26.189] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:26.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:19:0) after 1m0s
[04:21:26.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:19:0)
[04:21:26.230] Timer tick, asking Buildbucket for the build status
[04:21:26.376] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:26.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:20:0) after 1m0s
[04:22:26.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:20:0)
[04:22:26.435] Timer tick, asking Buildbucket for the build status
[04:22:26.965] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:26.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:21:0) after 1m0s
[04:23:26.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:21:0)
[04:23:26.978] Timer tick, asking Buildbucket for the build status
[04:23:27.311] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:27.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:22:0) after 1m0s
[04:24:27.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:22:0)
[04:24:27.332] Timer tick, asking Buildbucket for the build status
[04:24:27.433] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:27.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:23:0) after 1m0s
[04:25:27.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:23:0)
[04:25:27.407] Timer tick, asking Buildbucket for the build status
[04:25:27.524] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:27.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:24:0) after 1m0s
[04:26:27.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:24:0)
[04:26:27.555] Timer tick, asking Buildbucket for the build status
[04:26:27.746] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:27.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:25:0) after 1m0s
[04:27:27.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:25:0)
[04:27:27.818] Timer tick, asking Buildbucket for the build status
[04:27:27.946] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:27.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:26:0) after 1m0s
[04:28:28.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:26:0)
[04:28:28.025] Timer tick, asking Buildbucket for the build status
[04:28:28.171] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:28.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:27:0) after 1m0s
[04:29:28.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:27:0)
[04:29:28.187] Timer tick, asking Buildbucket for the build status
[04:29:28.296] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:28.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:28:0) after 1m0s
[04:30:28.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:28:0)
[04:30:28.349] Timer tick, asking Buildbucket for the build status
[04:30:28.597] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:28.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:29:0) after 1m0s
[04:31:28.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:29:0)
[04:31:28.683] Timer tick, asking Buildbucket for the build status
[04:31:28.752] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:28.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:30:0) after 1m0s
[04:32:28.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:30:0)
[04:32:28.735] Timer tick, asking Buildbucket for the build status
[04:32:28.897] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:28.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:31:0) after 1m0s
[04:33:28.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:31:0)
[04:33:28.966] Timer tick, asking Buildbucket for the build status
[04:33:29.079] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:29.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:32:0) after 1m0s
[04:34:29.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:32:0)
[04:34:29.138] Timer tick, asking Buildbucket for the build status
[04:34:29.251] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:29.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:33:0) after 1m0s
[04:35:29.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:33:0)
[04:35:29.283] Timer tick, asking Buildbucket for the build status
[04:35:29.361] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:29.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:34:0) after 1m0s
[04:36:29.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:34:0)
[04:36:29.387] Timer tick, asking Buildbucket for the build status
[04:36:29.664] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:29.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:35:0) after 1m0s
[04:37:29.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:35:0)
[04:37:29.706] Timer tick, asking Buildbucket for the build status
[04:37:29.769] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:29.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:36:0) after 1m0s
[04:38:29.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:36:0)
[04:38:29.758] Timer tick, asking Buildbucket for the build status
[04:38:30.245] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:30.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:37:0) after 1m0s
[04:39:30.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:37:0)
[04:39:30.275] Timer tick, asking Buildbucket for the build status
[04:39:30.611] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:30.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:38:0) after 1m0s
[04:40:30.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:38:0)
[04:40:30.632] Timer tick, asking Buildbucket for the build status
[04:40:30.818] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:30.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:39:0) after 1m0s
[04:41:30.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:39:0)
[04:41:30.794] Timer tick, asking Buildbucket for the build status
[04:41:31.272] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:31.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:40:0) after 1m0s
[04:42:31.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:40:0)
[04:42:31.282] Timer tick, asking Buildbucket for the build status
[04:42:31.395] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:31.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:41:0) after 1m0s
[04:43:31.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:41:0)
[04:43:31.450] Timer tick, asking Buildbucket for the build status
[04:43:31.892] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:31.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:42:0) after 1m0s
[04:44:31.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:42:0)
[04:44:31.913] Timer tick, asking Buildbucket for the build status
[04:44:32.042] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:32.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:43:0) after 1m0s
[04:45:32.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:43:0)
[04:45:32.080] Timer tick, asking Buildbucket for the build status
[04:45:32.147] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:32.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:44:0) after 1m0s
[04:46:32.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:44:0)
[04:46:32.169] Timer tick, asking Buildbucket for the build status
[04:46:32.280] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:32.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:45:0) after 1m0s
[04:47:32.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:45:0)
[04:47:32.299] Timer tick, asking Buildbucket for the build status
[04:47:32.486] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:32.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:46:0) after 1m0s
[04:48:32.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:46:0)
[04:48:32.552] Timer tick, asking Buildbucket for the build status
[04:48:32.859] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:32.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:47:0) after 1m0s
[04:49:32.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:47:0)
[04:49:32.915] Timer tick, asking Buildbucket for the build status
[04:49:33.317] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:33.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:48:0) after 1m0s
[04:50:33.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:48:0)
[04:50:33.339] Timer tick, asking Buildbucket for the build status
[04:50:33.554] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:33.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:49:0) after 1m0s
[04:51:33.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:49:0)
[04:51:33.611] Timer tick, asking Buildbucket for the build status
[04:51:33.951] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:33.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:50:0) after 1m0s
[04:52:33.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:50:0)
[04:52:33.929] Timer tick, asking Buildbucket for the build status
[04:52:34.144] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:34.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:51:0) after 1m0s
[04:53:34.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:51:0)
[04:53:34.145] Timer tick, asking Buildbucket for the build status
[04:53:34.211] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:34.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:52:0) after 1m0s
[04:54:34.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:52:0)
[04:54:34.234] Timer tick, asking Buildbucket for the build status
[04:54:35.256] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:35.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:53:0) after 1m0s
[04:55:35.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:53:0)
[04:55:35.288] Timer tick, asking Buildbucket for the build status
[04:55:35.375] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:35.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:54:0) after 1m0s
[04:56:35.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:54:0)
[04:56:35.400] Timer tick, asking Buildbucket for the build status
[04:56:35.464] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:35.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:55:0) after 1m0s
[04:57:35.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:55:0)
[04:57:35.490] Timer tick, asking Buildbucket for the build status
[04:57:35.590] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:35.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:56:0) after 1m0s
[04:58:35.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:56:0)
[04:58:35.684] Timer tick, asking Buildbucket for the build status
[04:58:35.793] Build 8857707311634987824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:35.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023137373617842944:57:0) after 1m0s
[04:59:24.382] Received PubSub notification, asking Buildbucket for the build status
[04:59:24.456] Build 8857707311634987824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:59:24.456] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611032363197668",
"created_by": "project:chromium",
"created_ts": "1611029056857640",
"id": "8857707311634987824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1fb49019410544c1183dbf3f15c541fdec7b3929\"}}",
"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}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"1fb49019410544c1183dbf3f15c541fdec7b3929\", \"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-0-nszv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16884, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-2\", \"got_angle_revision\": \"5635dbb91641a72f1f713aa7c179e2432674f9c3\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"1fb49019410544c1183dbf3f15c541fdec7b3929\", \"got_revision_cp\": \"refs/heads/master@{#844659}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"ee927aaa58e688ad6aa0256b3929c1eeea0bebd5\", \"got_v8_revision_cp\": \"refs/heads/9.0.8@{#1}\", \"got_webrtc_revision\": \"29bd8638adb0a7294f7b8ae589fdd24077539dbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33030}\", \"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\": \"1fb49019410544c1183dbf3f15c541fdec7b3929\", \"swarm_hashes_refs/heads/master(at){#844659}_without_patch\": {\"absl_hardening_tests\": \"ebfef63da7acc9e3e703c5bddec9f85f6be79f09\", \"accessibility_unittests\": \"dac50e0cfd6767b7cc7ba8d42b3d75a109ada52f\", \"angle_unittests\": \"369849bffdf16ccadb3a52c20a11d84e876f5347\", \"app_shell_unittests\": \"0b1de7f6b5520e4fcd2930452087bc02508e4518\", \"aura_unittests\": \"15a43dd97eb0fc908a7479df336dac038d31d498\", \"base_unittests\": \"e24ade3910a609d5e8deec23b098616e4ac8ed9e\", \"base_util_unittests\": \"a462d329a543e6a92d7fcf6dceaf1faf34cd2bfe\", \"blink_common_unittests\": \"ee99a6a64d4ed9e9c5ee960fa4a1898a128ebff1\", \"blink_fuzzer_unittests\": \"d362882041931d590a00ce82ead71acc967116b3\", \"blink_heap_unittests\": \"d0fdf5d1241e1f862882eb452f9a8cf5c480dd07\", \"blink_platform_unittests\": \"e90008593cd543da142a76418af4d847046889ea\", \"blink_unittests\": \"6b3f01359675dfcfc12a1945b793e9f57c05a8a1\", \"boringssl_crypto_tests\": \"d2a447f10f5e3f52a244c454d67043fe89c342dc\", \"boringssl_ssl_tests\": \"cfe3379698f021f6eaf1b17818fb78585246ab8a\", \"browser_switcher_bho_unittests\": \"90d791d55a314ff4959a27d49625a9b3ab38ec63\", \"browser_tests\": \"5869e883278c544ed5c8c606cb108794d2ee7144\", \"capture_unittests\": \"e2b2534aa5fabea3ad9873048364b86f7c56ceff\", \"cast_unittests\": \"3cdc88c3f5f59112f330a5ccc43e639f2b5a7f6e\", \"cc_unittests\": \"0471f322598a3fad2115176564d0ff35bdf81863\", \"chrome_app_unittests\": \"b5f3324fe913777a4b176cca6255dca047960fa3\", \"chrome_cleaner_unittests\": \"4f38a88082e16d62cf34b6d86562fc25e134a259\", \"chrome_elf_unittests\": \"314d518adeea7fc0f60ffb40e39b48f8c57268db\", \"chromedriver_unittests\": \"64ee7c03a0bb2f7e55182bf9857e1f2e8e8c13c2\", \"components_browsertests\": \"35646aaacb73ce2f1391c10822efab5f50ae6541\", \"components_unittests\": \"64aeebe7c845801eb1101b19deef57083aa64a69\", \"compositor_unittests\": \"e3945ad8d23c19354be9d840a252ff3564d94143\", \"content_browsertests\": \"84ee5dd9063ec1b0eb3be8a07df2fa3c870d449c\", \"content_unittests\": \"6eab47767a761922dd7bab3c0ed6c1fa08f7e8d1\", \"courgette_unittests\": \"5e4d6453fb3b338f3b2d99f6d013538578f95ed2\", \"crashpad_tests\": \"1d9fe6da264ef4f997ecc5f0ef85426ec3bdc073\", \"cronet_tests\": \"0c4c3135158167479c862ebdf8ff85e09fef0758\", \"cronet_unittests\": \"dc9b2e9305a79da62e098bdd935ab1fa20fff6e9\", \"crypto_unittests\": \"7f8137edf4dce3625cd62557beb93484eb10a521\", \"delayloads_unittests\": \"d6c0c3bd38c04402b9ec625b860a0b817f8adedb\", \"device_unittests\": \"053011281bd0fc4d03f4be6b08b2001564a163b3\", \"display_unittests\": \"280fb6d1f1c4303a80c20b9404ab363b6168c484\", \"elevation_service_unittests\": \"50e4aa24ca5de555f34b452c0855c9d387538ad9\", \"events_unittests\": \"14177b8c7cc2e08c5cbc114a39bc12deae5dd976\", \"extensions_browsertests\": \"4f48f60447cd5843e9ff2dc42ae686a5d1459415\", \"extensions_unittests\": \"075d40ece4a68f9c3ccce995fa9df47454845f39\", \"filesystem_service_unittests\": \"3b51f16e846fdf7bb3d79fe5bac397d4fd8a1829\", \"gcm_unit_tests\": \"bc7348bc9c144e420a561099aaf4f70a2133eee7\", \"gcp_unittests\": \"631b973c23f612db14f269455b2d268f162190a2\", \"gfx_unittests\": \"b51a6c1f747d4c8d08c06ad8817b50ae10c65844\", \"gin_unittests\": \"e2a1942615849e70001f4c3b990e587cbef67656\", \"google_apis_unittests\": \"0b6681fbf4d8366e796bb711873bca8c0de923ce\", \"gpu_unittests\": \"9858dbba584d5b332d0ab16b54b73faeea057e91\", \"gwp_asan_unittests\": \"4e0065d1d3388c9198d76ec7432a1768514d776e\", \"headless_browsertests\": \"32c3e2dfd0f7f9a202c4179484ad9b3b4856480f\", \"headless_unittests\": \"a5a83053d2c63f317677ce5c22abd47373810852\", \"install_static_unittests\": \"ff0137418b080e4d92b48f2e9b8818e39154a3a8\", \"installer_util_unittests\": \"fd35c8dc5325b0a219d3139281084994705ea8d3\", \"interactive_ui_tests\": \"cc6a11ddd625bc0c7f1c725e4923ab96230a36ed\", \"ipc_tests\": \"814107703a9a3220107a9e0f048cacb833fe9b95\", \"jingle_unittests\": \"d7b41f1adf067d4d59869ddd20093bb95042bf57\", \"latency_unittests\": \"cfc56b4b0ab6ae35bedaa75a1a168db2cd054ed2\", \"libjingle_xmpp_unittests\": \"b7db71bfa5bd4c75fcc7df17ed97cba3a89ced9a\", \"liburlpattern_unittests\": \"699759f09985b2d557af1ee7ce85bd2a0cdffc00\", \"media_blink_unittests\": \"bcee1a2ee91cf1320145f8bac9fdda807097bc32\", \"media_unittests\": \"bec7c01dd7b15f1a1dab1b8b51b8b9955035be33\", \"message_center_unittests\": \"29a54b43f47d5052814f0542185d7a962ae123e5\", \"midi_unittests\": \"430c4ec8f81023a414437b24dc5214e97aa86fa1\", \"mojo_core_unittests\": \"bd9b9ba70b7260cfca8b696c77e7bae32a232342\", \"mojo_unittests\": \"8a651bc935b442ff4eeb00020dcceb2422c9ea2a\", \"nacl_loader_unittests\": \"68a53aa07740d7729da4ae679dd909f0dc4f94a1\", \"native_theme_unittests\": \"c32b31c52e8e4617816a0c9916c162249b8aa344\", \"net_unittests\": \"ca15341b27ea899c98c131b77b51e23e3d6579e3\", \"notification_helper_unittests\": \"40693743757689467769419f15165ee351d5d97d\", \"pdf_unittests\": \"352de97624b940983d54ffe28862b3ce90e686e0\", \"perfetto_unittests\": \"f08c96f4694893eae621b80abc55a4c6e7d3f1c0\", \"ppapi_unittests\": \"211f9f6b61f1e122ab1d0f5401d2646a90d7a7f7\", \"printing_unittests\": \"ce7032bc5674f06ab14599f0655ccd569c77960e\", \"remoting_unittests\": \"c1daa8b00c215838080e88d72bf8a4a22fc99921\", \"sbox_integration_tests\": \"bed360da394bf45b51aa9969a56f669331c62289\", \"sbox_unittests\": \"7fc8a80df0be42ada78ce8e2d8b32a15d9e45c12\", \"sbox_validation_tests\": \"521932972d2fe2c42b1662f22dfb588080b1ebd5\", \"service_manager_unittests\": \"2c044de81e14f2ad37b8599b9ab3aa79568389f1\", \"services_unittests\": \"585168238b8801a98438df6a9f98e86c3b1bc197\", \"setup_unittests\": \"87fc9ff0d8bb2453b4ac1ca1e6177001f1512817\", \"shell_dialogs_unittests\": \"01b1410c12788bae4de4016cbe987bef658175e3\", \"skia_unittests\": \"bd7ab2e24a4addec2b341c421cae17d371582687\", \"snapshot_unittests\": \"8acca37458e101b0b6854258a636dc3a07d22bbe\", \"sql_unittests\": \"39322d3848faac96bfdaac4035eefe88a09a0b46\", \"storage_unittests\": \"846e9dd9a8417def39b64a802959b81f2e192ff9\", \"sync_integration_tests\": \"c74d957f835d1409461b140293b1bb06c319ff78\", \"traffic_annotation_auditor_unittests\": \"5d2aaa04d0e53de6849a09277c5d80b83b42385c\", \"ui_base_unittests\": \"e74d15995ee7d8fc6e2488ccdacc980bb9b6ae22\", \"ui_touch_selection_unittests\": \"12cc7188d8c0eb1464bfd20dd1d4001c79d2a7a7\", \"unit_tests\": \"2ea3f087fa9a9ce04457381b05f5909e82e4b404\", \"updater_tests\": \"035828670394610c15ebf282e2543db7a34913c7\", \"url_unittests\": \"b446ebeca53f6819bb42b34e3ee4623f4d33f960\", \"views_unittests\": \"e47414112f8bd332895e738c905c64b3afd5581f\", \"viz_unittests\": \"57b610965c1d0028d4cace1455599752b34fc8ed\", \"vr_common_unittests\": \"edbac07a3f7e330969ab297a6411ed70dc566e64\", \"vr_pixeltests\": \"9084a6f8d6bf3853cba1424e5d7a5e293a38cf50\", \"weblayer_browsertests\": \"9d6ca2f533f2e811ef1b8c92f8c1bf6c22062002\", \"weblayer_unittests\": \"f506024e65af9118649d0f9ef91984f26e35f6c2\", \"wm_unittests\": \"8cf7eb80610a3a300586315deb90de0f55d8f74b\", \"wtf_unittests\": \"ccb935d11eb65b2bd6727f1981c77802316b4f68\", \"zlib_unittests\": \"238e463f152dedf6a97cc8a7e9c9c09113900edc\", \"zucchini_unittests\": \"0a14845efc482728a6d8820cac93ff13408402e8\"}}, \"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-0-nszv\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611029060257168",
"status": "COMPLETED",
"status_changed_ts": "1611032363608231",
"tags": [
"build_address:luci.chromium.ci/win-asan/16884",
"builder:win-asan",
"buildset:commit/git/1fb49019410544c1183dbf3f15c541fdec7b3929",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fb49019410544c1183dbf3f15c541fdec7b3929",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023137373617842944",
"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/8857707311634987824/+/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:5131a21b075a4b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611032363623667",
"url": "https://ci.chromium.org/b/8857707311634987824",
"utcnow_ts": "1611032364447514"
}
}
[04:59:24.456] Invocation finished in 55m8.872617807s with status SUCCEEDED