[08:01:20.319] New invocation is queued and will start shortly
[08:01:21.446] Starting the invocation (attempt 1)
[08:01:21.479] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:01:21.479] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:01:21.479] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018320818698798448",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"862dfeb73c365563088dfd51a32d46026ead575c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018320818698798448",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/862dfeb73c365563088dfd51a32d46026ead575c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/862dfeb73c365563088dfd51a32d46026ead575c",
"gitiles_ref:refs/heads/master"
]
}
[08:01:22.141] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615622481578197",
"id": "8852890756715057264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"862dfeb73c365563088dfd51a32d46026ead575c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615622481952897",
"tags": [
"build_address:luci.chromium.ci/win-asan/17784",
"builder:win-asan",
"buildset:commit/git/862dfeb73c365563088dfd51a32d46026ead575c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/862dfeb73c365563088dfd51a32d46026ead575c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018320818698798448",
"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/8852890756715057264/+/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": "1615622481953008",
"url": "https://ci.chromium.org/b/8852890756715057264",
"utcnow_ts": "1615622482126219"
}
}
[08:01:22.141] Task URL: https://ci.chromium.org/b/8852890756715057264
[08:01:22.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:2:0) after 1m0s
[08:02:39.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:2:0)
[08:02:39.994] Timer tick, asking Buildbucket for the build status
[08:02:40.077] Build 8852890756715057264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:40.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:3:0) after 1m0s
[08:03:12.241] Received PubSub notification, asking Buildbucket for the build status
[08:03:12.367] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:40.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:3:0)
[08:03:40.180] Timer tick, asking Buildbucket for the build status
[08:03:40.305] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:40.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:5:0) after 1m0s
[08:04:40.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:5:0)
[08:04:40.515] Timer tick, asking Buildbucket for the build status
[08:04:40.582] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:40.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:6:0) after 1m0s
[08:05:40.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:6:0)
[08:05:40.789] Timer tick, asking Buildbucket for the build status
[08:05:40.857] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:40.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:7:0) after 1m0s
[08:06:41.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:7:0)
[08:06:41.088] Timer tick, asking Buildbucket for the build status
[08:06:41.206] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:41.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:8:0) after 1m0s
[08:07:41.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:8:0)
[08:07:41.309] Timer tick, asking Buildbucket for the build status
[08:07:41.371] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:41.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:9:0) after 1m0s
[08:08:41.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:9:0)
[08:08:41.379] Timer tick, asking Buildbucket for the build status
[08:08:41.690] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:41.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:10:0) after 1m0s
[08:09:41.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:10:0)
[08:09:41.814] Timer tick, asking Buildbucket for the build status
[08:09:41.972] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:41.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:11:0) after 1m0s
[08:10:42.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:11:0)
[08:10:42.002] Timer tick, asking Buildbucket for the build status
[08:10:42.173] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:42.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:12:0) after 1m0s
[08:11:42.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:12:0)
[08:11:42.190] Timer tick, asking Buildbucket for the build status
[08:11:42.409] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:42.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:13:0) after 1m0s
[08:12:42.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:13:0)
[08:12:42.458] Timer tick, asking Buildbucket for the build status
[08:12:43.229] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:43.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:14:0) after 1m0s
[08:13:43.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:14:0)
[08:13:43.334] Timer tick, asking Buildbucket for the build status
[08:13:43.408] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:43.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:15:0) after 1m0s
[08:14:43.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:15:0)
[08:14:43.503] Timer tick, asking Buildbucket for the build status
[08:14:43.703] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:43.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:16:0) after 1m0s
[08:15:43.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:16:0)
[08:15:43.790] Timer tick, asking Buildbucket for the build status
[08:15:43.860] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:43.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:17:0) after 1m0s
[08:16:44.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:17:0)
[08:16:44.577] Timer tick, asking Buildbucket for the build status
[08:16:44.663] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:44.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:18:0) after 1m0s
[08:17:44.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:18:0)
[08:17:44.730] Timer tick, asking Buildbucket for the build status
[08:17:44.808] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:44.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:19:0) after 1m0s
[08:18:44.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:19:0)
[08:18:44.831] Timer tick, asking Buildbucket for the build status
[08:18:44.900] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:44.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:20:0) after 1m0s
[08:19:45.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:20:0)
[08:19:45.027] Timer tick, asking Buildbucket for the build status
[08:19:45.102] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:45.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:21:0) after 1m0s
[08:20:45.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:21:0)
[08:20:45.076] Timer tick, asking Buildbucket for the build status
[08:20:45.163] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:45.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:22:0) after 1m0s
[08:21:45.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:22:0)
[08:21:45.234] Timer tick, asking Buildbucket for the build status
[08:21:45.331] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:45.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:23:0) after 1m0s
[08:22:45.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:23:0)
[08:22:45.363] Timer tick, asking Buildbucket for the build status
[08:22:45.441] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:45.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:24:0) after 1m0s
[08:23:45.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:24:0)
[08:23:45.429] Timer tick, asking Buildbucket for the build status
[08:23:45.503] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:45.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:25:0) after 1m0s
[08:24:45.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:25:0)
[08:24:45.570] Timer tick, asking Buildbucket for the build status
[08:24:45.755] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:45.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:26:0) after 1m0s
[08:25:45.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:26:0)
[08:25:45.771] Timer tick, asking Buildbucket for the build status
[08:25:46.377] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:46.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:27:0) after 1m0s
[08:26:46.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:27:0)
[08:26:46.392] Timer tick, asking Buildbucket for the build status
[08:26:46.489] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:46.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:28:0) after 1m0s
[08:27:46.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:28:0)
[08:27:46.578] Timer tick, asking Buildbucket for the build status
[08:27:46.962] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:46.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:29:0) after 1m0s
[08:28:46.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:29:0)
[08:28:46.977] Timer tick, asking Buildbucket for the build status
[08:28:47.049] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:47.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:30:0) after 1m0s
[08:29:47.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:30:0)
[08:29:47.037] Timer tick, asking Buildbucket for the build status
[08:29:47.103] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:47.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:31:0) after 1m0s
[08:30:47.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:31:0)
[08:30:47.121] Timer tick, asking Buildbucket for the build status
[08:30:47.196] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:47.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:32:0) after 1m0s
[08:31:47.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:32:0)
[08:31:47.214] Timer tick, asking Buildbucket for the build status
[08:31:47.331] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:47.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:33:0) after 1m0s
[08:32:47.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:33:0)
[08:32:47.379] Timer tick, asking Buildbucket for the build status
[08:32:47.457] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:47.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:34:0) after 1m0s
[08:33:47.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:34:0)
[08:33:47.521] Timer tick, asking Buildbucket for the build status
[08:33:47.669] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:47.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:35:0) after 1m0s
[08:34:47.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:35:0)
[08:34:47.705] Timer tick, asking Buildbucket for the build status
[08:34:47.777] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:47.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:36:0) after 1m0s
[08:35:47.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:36:0)
[08:35:47.796] Timer tick, asking Buildbucket for the build status
[08:35:47.871] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:47.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:37:0) after 1m0s
[08:36:47.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:37:0)
[08:36:47.886] Timer tick, asking Buildbucket for the build status
[08:36:47.990] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:47.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:38:0) after 1m0s
[08:37:48.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:38:0)
[08:37:48.018] Timer tick, asking Buildbucket for the build status
[08:37:48.112] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:48.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:39:0) after 1m0s
[08:38:48.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:39:0)
[08:38:48.129] Timer tick, asking Buildbucket for the build status
[08:38:48.206] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:48.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:40:0) after 1m0s
[08:39:48.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:40:0)
[08:39:48.224] Timer tick, asking Buildbucket for the build status
[08:39:48.435] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:48.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:41:0) after 1m0s
[08:40:48.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:41:0)
[08:40:48.506] Timer tick, asking Buildbucket for the build status
[08:40:48.589] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:48.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:42:0) after 1m0s
[08:41:48.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:42:0)
[08:41:48.658] Timer tick, asking Buildbucket for the build status
[08:41:48.732] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:48.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:43:0) after 1m0s
[08:42:49.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:43:0)
[08:42:49.611] Timer tick, asking Buildbucket for the build status
[08:42:49.689] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:49.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:44:0) after 1m0s
[08:43:51.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:44:0)
[08:43:51.763] Timer tick, asking Buildbucket for the build status
[08:43:51.887] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:51.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:45:0) after 1m0s
[08:44:51.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:45:0)
[08:44:51.999] Timer tick, asking Buildbucket for the build status
[08:44:52.086] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:52.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:46:0) after 1m0s
[08:45:52.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:46:0)
[08:45:52.058] Timer tick, asking Buildbucket for the build status
[08:45:52.141] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:52.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:47:0) after 1m0s
[08:46:52.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:47:0)
[08:46:52.175] Timer tick, asking Buildbucket for the build status
[08:46:52.275] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:52.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:48:0) after 1m0s
[08:47:52.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:48:0)
[08:47:52.329] Timer tick, asking Buildbucket for the build status
[08:47:52.442] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:52.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:49:0) after 1m0s
[08:48:52.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:49:0)
[08:48:52.462] Timer tick, asking Buildbucket for the build status
[08:48:52.701] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:52.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:50:0) after 1m0s
[08:49:52.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:50:0)
[08:49:52.787] Timer tick, asking Buildbucket for the build status
[08:49:52.864] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:52.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:51:0) after 1m0s
[08:50:52.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:51:0)
[08:50:52.878] Timer tick, asking Buildbucket for the build status
[08:50:53.209] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:53.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:52:0) after 1m0s
[08:51:53.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:52:0)
[08:51:53.225] Timer tick, asking Buildbucket for the build status
[08:51:53.615] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:53.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:53:0) after 1m0s
[08:52:53.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:53:0)
[08:52:53.631] Timer tick, asking Buildbucket for the build status
[08:52:53.712] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:53.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:54:0) after 1m0s
[08:53:53.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:54:0)
[08:53:53.729] Timer tick, asking Buildbucket for the build status
[08:53:53.933] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:53.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:55:0) after 1m0s
[08:54:53.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:55:0)
[08:54:53.998] Timer tick, asking Buildbucket for the build status
[08:54:54.234] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:54.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:56:0) after 1m0s
[08:55:54.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:56:0)
[08:55:54.283] Timer tick, asking Buildbucket for the build status
[08:55:54.357] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:54.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:57:0) after 1m0s
[08:56:54.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:57:0)
[08:56:54.392] Timer tick, asking Buildbucket for the build status
[08:56:54.752] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:54.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:58:0) after 1m0s
[08:57:54.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:58:0)
[08:57:54.984] Timer tick, asking Buildbucket for the build status
[08:57:55.133] Build 8852890756715057264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:55.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018320818698798448:59:0) after 1m0s
[08:58:13.448] Received PubSub notification, asking Buildbucket for the build status
[08:58:13.560] Build 8852890756715057264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:58:13.560] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615625892587367",
"created_by": "project:chromium",
"created_ts": "1615622481578197",
"id": "8852890756715057264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"862dfeb73c365563088dfd51a32d46026ead575c\"}}",
"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\": \"862dfeb73c365563088dfd51a32d46026ead575c\", \"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-2-zecp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17784, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"ca5dbef04014dede0beb79aa5a9c71a5b8c6286c\", \"got_dawn_revision\": \"bdc134044932f54ce1a27d92e4c85763d764421e\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"862dfeb73c365563088dfd51a32d46026ead575c\", \"got_revision_cp\": \"refs/heads/master@{#862654}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"314aa755ed3af01dc42d77587752a32a883972ed\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"86473404362e38131203537b8a8c8ec7c1abc31a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33444}\", \"is_cached\": true, \"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\": \"862dfeb73c365563088dfd51a32d46026ead575c\", \"swarm_hashes_refs/heads/master(at){#862654}_without_patch\": {\"absl_hardening_tests\": \"936d0dd7c64c0f1b38033254e100c2b023fec043\", \"accessibility_unittests\": \"4138eecdcef986fac07ba8cf2582a9dd3fb1fd8e\", \"angle_unittests\": \"8764be3c46c95649d86b25e19c010d16a7589bd0\", \"app_shell_unittests\": \"d19538338e551b483cea438ef8d84bab2d84d5e2\", \"aura_unittests\": \"5389ab6344275fe466d2e07f999473174b06cfdc\", \"base_unittests\": \"96c85e3daa0c69ac42dc594ddeceb09ea43a6b27\", \"base_util_unittests\": \"ed3f460df789604e33e7aa33ba0250e65781b775\", \"blink_common_unittests\": \"b5ae0075d62fba85c671408a24aaa9cd9fa07761\", \"blink_fuzzer_unittests\": \"4b6da5308b47bf85819c8587ad03f13851a42c79\", \"blink_heap_unittests\": \"140084029e455b4c463e086358887390522f8485\", \"blink_platform_unittests\": \"a54c5debb1ccb34cbfaac951ab31f7bc59daadd1\", \"blink_unittests\": \"6b1f3eaa7891daa17692b6bac941b3b630263aff\", \"boringssl_crypto_tests\": \"48116f73155803b3adbfbff3cb583975da7f25c8\", \"boringssl_ssl_tests\": \"b03c50f14055d33e112a72355586775436c4a7ff\", \"browser_switcher_bho_unittests\": \"5ef088f8f246dad48142fe7fa207f649a5718e7b\", \"browser_tests\": \"9efb0af63357ddf5480807d143b3c967c46f6bd2\", \"capture_unittests\": \"715e9d3596da77ee3629f631c4216c50ea24e5f0\", \"cast_unittests\": \"d3212fb1fadbd497c269ceaed8e1a7d1fc461b82\", \"cc_unittests\": \"034fa0fb44991be805904f777add30b870e92e50\", \"chrome_app_unittests\": \"4c276c0d5242215a7c697e342586a535b55d56a9\", \"chrome_cleaner_unittests\": \"5103463888a4405c8e49a9f75df3a2254d2a7464\", \"chrome_elf_unittests\": \"b378586c66ab3923ce7df4557730d0bcc866c77e\", \"chromedriver_unittests\": \"3799a7dc3d6428e57bf1778e5a471f94d80a1d61\", \"color_unittests\": \"805729671c916fffb1b6905e7d957d06bf8be1c9\", \"components_browsertests\": \"81ca1f9fed03e636a7073ed31279ff7a0abd72b0\", \"components_unittests\": \"4777df56e6b631efc6b406095d6a9686d1ff1d94\", \"compositor_unittests\": \"3a6c0998fba6506c165a34d7eb51b35448ef0dbf\", \"content_browsertests\": \"b9c528204ece63388911b6e14169d29df1a6b6d5\", \"content_unittests\": \"94db8f66fd9dedec9ea063caad033d0d158f7351\", \"courgette_unittests\": \"49d75bdf43f6b751ea90f397254ec6783366377f\", \"crashpad_tests\": \"6336ac76ecdb5e5d4cd05c23b132afd718cfcb70\", \"cronet_tests\": \"b24f103e6a70251ad8e0417b1dfd5e572ae28523\", \"cronet_unittests\": \"fb6b27e897eaed8f04b09115b7ff2231e16e7853\", \"crypto_unittests\": \"bf2e08c4e92bc611b47d57881f522a6f2c5b6d7d\", \"delayloads_unittests\": \"5aaf1d3d53a87145265c54f419b482ee3f179d04\", \"device_unittests\": \"05b6596c8479b3dd9111f3c8e5a43cd3655d54b8\", \"display_unittests\": \"4b05095fdc81d8bc4d0aaf8e2a291fea3042fe09\", \"elevation_service_unittests\": \"05b0b6291a879335c407a438e5eaf365940ad91d\", \"events_unittests\": \"026b84a85c2672641fe3970f30399b726805f657\", \"extensions_browsertests\": \"495ab18803e747f97f28abbbab26b4a33b897605\", \"extensions_unittests\": \"34e6bf6313441077f868dd89ecd9c34d651e41fc\", \"filesystem_service_unittests\": \"bfc4cedda9b01fd84962ca7edb8f50b7e8c7e968\", \"gcm_unit_tests\": \"6dd440a1f55e8f65255106c92f55e00b162b7b5b\", \"gcp_unittests\": \"a591835d365b6bc1f6218c411b18e0ef34283fd0\", \"gfx_unittests\": \"5a9c38d48f4491e32cdfa361f281ed3e6c3f5d80\", \"gin_unittests\": \"440c36d64641528ccd8360a8655759ac332db5db\", \"google_apis_unittests\": \"8a96d66b056e64da387d8169ac68af8e8dbe54f9\", \"gpu_unittests\": \"812a35e66f02307d01a70442bb92d70aea0603b8\", \"gwp_asan_unittests\": \"4a234ce89939697e2b86eb806c2dd073f71d4ed0\", \"headless_browsertests\": \"09dd7cb501e5177487cd56c557b0d72c1f1d4fa4\", \"headless_unittests\": \"399df708517c69131355bef3397e6ee5289e1800\", \"install_static_unittests\": \"d3566c577dad0a05b5828d2d147a69056b119d37\", \"installer_util_unittests\": \"52519a33b99fa812462604363b9e56f9281ede94\", \"interactive_ui_tests\": \"96a52aaec815c2d27bee40f2fe088a265677fff9\", \"ipc_tests\": \"52776360bdaea2637ad440b21ff0a70518870595\", \"jingle_unittests\": \"02605fac6517602c6c3b2baf364b6698254a1fa2\", \"latency_unittests\": \"d31cbcdec56200fa4d67a3c5e4c02af2579ba6cb\", \"libjingle_xmpp_unittests\": \"da5bfe15b48afb2d81b0cf5789c4b5370316902b\", \"liburlpattern_unittests\": \"6433462f30a880c19bd0e8c45e9d1dccc2884be4\", \"media_blink_unittests\": \"106e2e25963b3d938e19bc1d5bc0b7bc4b90c38b\", \"media_unittests\": \"3e0fa03e782f65ff3d88d729aabf060a92784018\", \"message_center_unittests\": \"02b71895e3e7bd7fed0aa30ff8eb96e5bc8deb1e\", \"midi_unittests\": \"10208db65237ce546de576288f05f7cadafbcaa3\", \"mojo_core_unittests\": \"de06a3440909647503e9d40caef1ec81e7bc1e26\", \"mojo_unittests\": \"7c465f4282b3f4cd22dd5568ace8cf54d1dec619\", \"nacl_loader_unittests\": \"f253e8ce651d5f6fffbfd21f9215cf8e7f29801f\", \"native_theme_unittests\": \"5e8df0760743803aa07cc90e04db8a9cbf80f49d\", \"net_unittests\": \"6b263664feb0ef84ae62440589e58191fabe8326\", \"notification_helper_unittests\": \"2158eb88de68872e700b742a02567143190f5b8f\", \"pdf_unittests\": \"f40c2e0cb79d8ada020caad35242f880229e32c2\", \"perfetto_unittests\": \"c71a85c5f9c575bd063d6d79c285d1e95947cbdb\", \"ppapi_unittests\": \"96032d18c5c029b01a17a8ae4de856c648f0370a\", \"printing_unittests\": \"2a66a33d9720bf3f29e5ca7e8d7273a2fd9dd920\", \"remoting_unittests\": \"c38a862f10b0ce7cb3319c8fbf8efb4e6836c316\", \"sbox_integration_tests\": \"48f038212544dcd93c2535d3937c73358a5430e0\", \"sbox_unittests\": \"58c41596f7257d3377087ecb2586f36894edfe73\", \"sbox_validation_tests\": \"fde24e102bd7947044e113ef1f6515bc5700ac37\", \"service_manager_unittests\": \"43b217995cc8161baa82dd9454e1f481a3e14a98\", \"services_unittests\": \"b6a112bf04c05814ce1bb2f211ff34fa796f5890\", \"setup_unittests\": \"53fe8e03548edfb5ecdfc3c001bcddbd31492d15\", \"shell_dialogs_unittests\": \"2e6f866df5128fcc99b4efc5f996e0adf0e2650c\", \"skia_unittests\": \"0d37c7fc3478f26e64a04bfaadb931c0405ff922\", \"snapshot_unittests\": \"07f4af13168a39b6b2552a30594197528ee251ed\", \"sql_unittests\": \"551259d0d96367b3b08401703f2a07c22a3fcff9\", \"storage_unittests\": \"c91095f30b706d720fe3eb2cb6663a6977c6d507\", \"sync_integration_tests\": \"260ee1bb1c17515882e22e64113323f7be3df078\", \"traffic_annotation_auditor_unittests\": \"c5f097a5f3f47eeb7f5205b580eb2d1240e56884\", \"ui_base_unittests\": \"f7a5b1fddd729d14452992d59db9634f65c87a30\", \"ui_touch_selection_unittests\": \"0e98979015b66041d5acfe54f47784954bf7b0c3\", \"unit_tests\": \"c1281ae4bb85b191cac21e8fa534acd72b607ff2\", \"updater_tests\": \"e2c6ce5b4845c190f5f1151f51db42f5f6aeaf74\", \"url_unittests\": \"035b7e30bea267c27e4321e6e5efa29f50a6c3d8\", \"views_unittests\": \"827dfdeeb14f7b68a6c02174ee9c2f8b66a4f58a\", \"viz_unittests\": \"1fee6d59d2411a163d5e9c0c75ac82a81db286b7\", \"vr_common_unittests\": \"51966bbb4a5e23ae3aa362bc44b0725259ebd916\", \"vr_pixeltests\": \"1c48367db2c8083c51809f32ab5fcba51757f147\", \"weblayer_browsertests\": \"329e01b6d0ea06865ac7c16ea5d1657fd04b71dc\", \"weblayer_unittests\": \"13ee5ac35d9d7bb4a0a1e429acb9fa6a1b23d294\", \"wm_unittests\": \"6406372283135c84b048201ea6e9f39c0b02f357\", \"wtf_unittests\": \"6944f8468d8dbf3f4428d88d769e0b460331e4e4\", \"zlib_unittests\": \"c340da81e5fb0a6f815974026c10b56fa5c153d6\", \"zucchini_unittests\": \"25004f2a1b8e10536ea7709d8efd7d2007db9cac\"}}, \"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-2-zecp\"], \"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\": [\"5754-8c4f760\"], \"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": "1615622590745703",
"status": "COMPLETED",
"status_changed_ts": "1615625892966026",
"tags": [
"build_address:luci.chromium.ci/win-asan/17784",
"builder:win-asan",
"buildset:commit/git/862dfeb73c365563088dfd51a32d46026ead575c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/862dfeb73c365563088dfd51a32d46026ead575c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018320818698798448",
"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/8852890756715057264/+/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:52436c30d7ce2c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615625892970478",
"url": "https://ci.chromium.org/b/8852890756715057264",
"utcnow_ts": "1615625893552470"
}
}
[08:58:13.560] Invocation finished in 56m53.257358942s with status SUCCEEDED