[14:43:02.942] New invocation is queued and will start shortly
[14:43:04.224] Starting the invocation (attempt 1)
[14:43:04.280] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:43:04.280] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:43:04.280] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026086884393339456",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a7361976c2c0be7139f4de8eb8844702a198f0cf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026086884393339456",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a7361976c2c0be7139f4de8eb8844702a198f0cf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7361976c2c0be7139f4de8eb8844702a198f0cf",
"gitiles_ref:refs/heads/master"
]
}
[14:43:05.670] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608216184450831",
"id": "8860656822132816512",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7361976c2c0be7139f4de8eb8844702a198f0cf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608216185392995",
"tags": [
"build_address:luci.chromium.ci/win-asan/16282",
"builder:win-asan",
"buildset:commit/git/a7361976c2c0be7139f4de8eb8844702a198f0cf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7361976c2c0be7139f4de8eb8844702a198f0cf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026086884393339456",
"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/8860656822132816512/+/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": "1608216185393178",
"url": "https://ci.chromium.org/b/8860656822132816512",
"utcnow_ts": "1608216185643391"
}
}
[14:43:05.670] Task URL: https://ci.chromium.org/b/8860656822132816512
[14:43:05.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:2:0) after 1m0s
[14:43:06.599] Received PubSub notification, asking Buildbucket for the build status
[14:43:06.700] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:05.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:2:0)
[14:44:05.677] Timer tick, asking Buildbucket for the build status
[14:44:05.783] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:05.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:4:0) after 1m0s
[14:45:05.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:4:0)
[14:45:05.796] Timer tick, asking Buildbucket for the build status
[14:45:05.913] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:05.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:5:0) after 1m0s
[14:46:05.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:5:0)
[14:46:05.960] Timer tick, asking Buildbucket for the build status
[14:46:06.238] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:06.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:6:0) after 1m0s
[14:47:06.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:6:0)
[14:47:06.287] Timer tick, asking Buildbucket for the build status
[14:47:06.376] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:06.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:7:0) after 1m0s
[14:48:06.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:7:0)
[14:48:06.438] Timer tick, asking Buildbucket for the build status
[14:48:07.603] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:07.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:8:0) after 1m0s
[14:49:07.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:8:0)
[14:49:07.654] Timer tick, asking Buildbucket for the build status
[14:49:07.717] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:07.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:9:0) after 1m0s
[14:50:07.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:9:0)
[14:50:07.760] Timer tick, asking Buildbucket for the build status
[14:50:07.836] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:07.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:10:0) after 1m0s
[14:51:07.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:10:0)
[14:51:07.858] Timer tick, asking Buildbucket for the build status
[14:51:07.935] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:07.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:11:0) after 1m0s
[14:52:07.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:11:0)
[14:52:07.963] Timer tick, asking Buildbucket for the build status
[14:52:08.122] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:08.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:12:0) after 1m0s
[14:53:08.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:12:0)
[14:53:08.199] Timer tick, asking Buildbucket for the build status
[14:53:08.311] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:08.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:13:0) after 1m0s
[14:54:08.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:13:0)
[14:54:08.353] Timer tick, asking Buildbucket for the build status
[14:54:08.518] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:08.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:14:0) after 1m0s
[14:55:08.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:14:0)
[14:55:08.668] Timer tick, asking Buildbucket for the build status
[14:55:08.833] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:08.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:15:0) after 1m0s
[14:56:08.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:15:0)
[14:56:08.948] Timer tick, asking Buildbucket for the build status
[14:56:09.007] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:09.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:16:0) after 1m0s
[14:57:09.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:16:0)
[14:57:09.106] Timer tick, asking Buildbucket for the build status
[14:57:09.190] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:09.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:17:0) after 1m0s
[14:58:09.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:17:0)
[14:58:09.232] Timer tick, asking Buildbucket for the build status
[14:58:09.337] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:09.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:18:0) after 1m0s
[14:59:09.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:18:0)
[14:59:09.453] Timer tick, asking Buildbucket for the build status
[14:59:09.539] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:09.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:19:0) after 1m0s
[15:00:09.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:19:0)
[15:00:09.608] Timer tick, asking Buildbucket for the build status
[15:00:09.734] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:09.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:20:0) after 1m0s
[15:01:09.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:20:0)
[15:01:09.795] Timer tick, asking Buildbucket for the build status
[15:01:09.885] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:09.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:21:0) after 1m0s
[15:02:09.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:21:0)
[15:02:09.906] Timer tick, asking Buildbucket for the build status
[15:02:09.979] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:09.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:22:0) after 1m0s
[15:03:10.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:22:0)
[15:03:10.065] Timer tick, asking Buildbucket for the build status
[15:03:10.267] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:10.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:23:0) after 1m0s
[15:04:10.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:23:0)
[15:04:10.332] Timer tick, asking Buildbucket for the build status
[15:04:10.440] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:10.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:24:0) after 1m0s
[15:05:10.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:24:0)
[15:05:10.530] Timer tick, asking Buildbucket for the build status
[15:05:10.624] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:10.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:25:0) after 1m0s
[15:06:10.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:25:0)
[15:06:10.647] Timer tick, asking Buildbucket for the build status
[15:06:10.740] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:10.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:26:0) after 1m0s
[15:07:10.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:26:0)
[15:07:10.772] Timer tick, asking Buildbucket for the build status
[15:07:10.833] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:10.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:27:0) after 1m0s
[15:08:10.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:27:0)
[15:08:10.964] Timer tick, asking Buildbucket for the build status
[15:08:11.119] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:11.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:28:0) after 1m0s
[15:09:11.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:28:0)
[15:09:11.240] Timer tick, asking Buildbucket for the build status
[15:09:11.350] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:11.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:29:0) after 1m0s
[15:10:11.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:29:0)
[15:10:11.393] Timer tick, asking Buildbucket for the build status
[15:10:11.500] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:11.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:30:0) after 1m0s
[15:11:11.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:30:0)
[15:11:11.532] Timer tick, asking Buildbucket for the build status
[15:11:11.705] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:11.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:31:0) after 1m0s
[15:12:11.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:31:0)
[15:12:11.727] Timer tick, asking Buildbucket for the build status
[15:12:11.827] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:11.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:32:0) after 1m0s
[15:13:11.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:32:0)
[15:13:11.952] Timer tick, asking Buildbucket for the build status
[15:13:12.065] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:12.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:33:0) after 1m0s
[15:14:12.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:33:0)
[15:14:12.231] Timer tick, asking Buildbucket for the build status
[15:14:12.386] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:12.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:34:0) after 1m0s
[15:15:12.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:34:0)
[15:15:12.420] Timer tick, asking Buildbucket for the build status
[15:15:13.293] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:13.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:35:0) after 1m0s
[15:16:13.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:35:0)
[15:16:13.324] Timer tick, asking Buildbucket for the build status
[15:16:13.853] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:13.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:36:0) after 1m0s
[15:17:13.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:36:0)
[15:17:13.898] Timer tick, asking Buildbucket for the build status
[15:17:14.219] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:14.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:37:0) after 1m0s
[15:18:14.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:37:0)
[15:18:14.261] Timer tick, asking Buildbucket for the build status
[15:18:14.422] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:14.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:38:0) after 1m0s
[15:19:14.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:38:0)
[15:19:14.477] Timer tick, asking Buildbucket for the build status
[15:19:14.601] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:14.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:39:0) after 1m0s
[15:20:14.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:39:0)
[15:20:14.710] Timer tick, asking Buildbucket for the build status
[15:20:14.852] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:14.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:40:0) after 1m0s
[15:21:14.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:40:0)
[15:21:14.896] Timer tick, asking Buildbucket for the build status
[15:21:14.977] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:14.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:41:0) after 1m0s
[15:22:15.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:41:0)
[15:22:15.057] Timer tick, asking Buildbucket for the build status
[15:22:15.140] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:15.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:42:0) after 1m0s
[15:23:15.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:42:0)
[15:23:15.164] Timer tick, asking Buildbucket for the build status
[15:23:15.845] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:15.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:43:0) after 1m0s
[15:24:15.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:43:0)
[15:24:15.968] Timer tick, asking Buildbucket for the build status
[15:24:16.072] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:16.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:44:0) after 1m0s
[15:25:16.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:44:0)
[15:25:16.165] Timer tick, asking Buildbucket for the build status
[15:25:16.254] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:16.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:45:0) after 1m0s
[15:26:16.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:45:0)
[15:26:16.299] Timer tick, asking Buildbucket for the build status
[15:26:16.383] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:16.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:46:0) after 1m0s
[15:27:16.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:46:0)
[15:27:16.407] Timer tick, asking Buildbucket for the build status
[15:27:16.602] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:16.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:47:0) after 1m0s
[15:28:16.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:47:0)
[15:28:16.630] Timer tick, asking Buildbucket for the build status
[15:28:17.042] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:17.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:48:0) after 1m0s
[15:29:17.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:48:0)
[15:29:17.065] Timer tick, asking Buildbucket for the build status
[15:29:17.138] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:17.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:49:0) after 1m0s
[15:30:17.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:49:0)
[15:30:17.150] Timer tick, asking Buildbucket for the build status
[15:30:17.244] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:17.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:50:0) after 1m0s
[15:31:17.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:50:0)
[15:31:17.298] Timer tick, asking Buildbucket for the build status
[15:31:17.427] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:17.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:51:0) after 1m0s
[15:32:17.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:51:0)
[15:32:17.441] Timer tick, asking Buildbucket for the build status
[15:32:17.600] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:17.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:52:0) after 1m0s
[15:33:17.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:52:0)
[15:33:17.653] Timer tick, asking Buildbucket for the build status
[15:33:17.765] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:17.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:53:0) after 1m0s
[15:34:17.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:53:0)
[15:34:17.734] Timer tick, asking Buildbucket for the build status
[15:34:17.857] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:17.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:54:0) after 1m0s
[15:35:17.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:54:0)
[15:35:17.903] Timer tick, asking Buildbucket for the build status
[15:35:18.013] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:18.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:55:0) after 1m0s
[15:36:18.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:55:0)
[15:36:18.067] Timer tick, asking Buildbucket for the build status
[15:36:18.544] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:18.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:56:0) after 1m0s
[15:37:18.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:56:0)
[15:37:18.975] Timer tick, asking Buildbucket for the build status
[15:37:19.076] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:19.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:57:0) after 1m0s
[15:38:19.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:57:0)
[15:38:19.215] Timer tick, asking Buildbucket for the build status
[15:38:19.726] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:19.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:58:0) after 1m0s
[15:39:19.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:58:0)
[15:39:19.750] Timer tick, asking Buildbucket for the build status
[15:39:20.037] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:20.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:59:0) after 1m0s
[15:40:20.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:59:0)
[15:40:20.149] Timer tick, asking Buildbucket for the build status
[15:40:20.374] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:20.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:60:0) after 1m0s
[15:41:20.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:60:0)
[15:41:20.497] Timer tick, asking Buildbucket for the build status
[15:41:20.590] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:20.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:61:0) after 1m0s
[15:42:21.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:61:0)
[15:42:21.832] Timer tick, asking Buildbucket for the build status
[15:42:22.150] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:22.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:62:0) after 1m0s
[15:43:22.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:62:0)
[15:43:22.217] Timer tick, asking Buildbucket for the build status
[15:43:22.581] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:22.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:63:0) after 1m0s
[15:44:22.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:63:0)
[15:44:22.683] Timer tick, asking Buildbucket for the build status
[15:44:22.792] Build 8860656822132816512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:22.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026086884393339456:64:0) after 1m0s
[15:44:47.994] Received PubSub notification, asking Buildbucket for the build status
[15:44:48.276] Build 8860656822132816512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:44:48.276] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608219886523232",
"created_by": "project:chromium",
"created_ts": "1608216184450831",
"id": "8860656822132816512",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7361976c2c0be7139f4de8eb8844702a198f0cf\"}}",
"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\": \"a7361976c2c0be7139f4de8eb8844702a198f0cf\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-5-miap\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16282, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"b31cd871ad1000a56302409b62d76e079f8a0b46\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"a7361976c2c0be7139f4de8eb8844702a198f0cf\", \"got_revision_cp\": \"refs/heads/master@{#838067}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"804d2150372f0e8a19996e7152d984898e8cb85d\", \"got_v8_revision_cp\": \"refs/heads/8.9.164@{#1}\", \"got_webrtc_revision\": \"8592111e458d253e53806db65d23d21bfe9d9db4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32849}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7361976c2c0be7139f4de8eb8844702a198f0cf\", \"swarm_hashes_refs/heads/master(at){#838067}_without_patch\": {\"absl_hardening_tests\": \"b26b3ce7a210dd74eef1798edd085369db96155c\", \"accessibility_unittests\": \"c0506afe1104d3b8444fea352a667a823c380b05\", \"angle_unittests\": \"421aa7427367cab158b2d0dac5c6d36efc33d63c\", \"app_shell_unittests\": \"dbbe406e540d48ecaff39a01631c9e2dc5b7004b\", \"aura_unittests\": \"98a1b5d2561efdb8a38aebe8341df37f65f27b24\", \"base_unittests\": \"5df3ab4499bd38f5943826cca1eb203197cc9c7a\", \"base_util_unittests\": \"3f90d760b8c587b209bc931bde8684928a28b4ca\", \"blink_common_unittests\": \"518a7d3546c9dd2be42fee3d7304001efe145d4c\", \"blink_fuzzer_unittests\": \"3094f2541a8795f13d598a04d74a3a1ab0f801d8\", \"blink_heap_unittests\": \"d1a13ca2d8d5763f743abe1ff0b2d270033d24ac\", \"blink_platform_unittests\": \"5a157c271b1f959f17cce791956da287d2512381\", \"blink_unittests\": \"372fbba2f094eaf5de1f754f5f25d42ba4516790\", \"boringssl_crypto_tests\": \"a1fd58291dbbaec63b84afb6689f113576ac2490\", \"boringssl_ssl_tests\": \"c8c395e20fdaa14178ccd54eca04748e4ba77bb5\", \"browser_switcher_bho_unittests\": \"945a926fa98c001b4b9616ee08a649f939ed587f\", \"browser_tests\": \"fb771c394c4f0ee48539f872675513d683b55e5b\", \"capture_unittests\": \"56a61c8122cd7235bb4ba220eec666d960801415\", \"cast_unittests\": \"2689b67cb3f14470b4198e3f24b3f64c21bdd8a6\", \"cc_unittests\": \"36a7cf956600c3ff1003ef125b441013a8a0e05d\", \"chrome_app_unittests\": \"b470cb1c63a2744bd3f28398f2d985074fcb7f75\", \"chrome_cleaner_unittests\": \"3edbdac49f4547ea34b93c55680fd66b1eccf92f\", \"chrome_elf_unittests\": \"2f915b60dc0c1506740d8ec9e4356dafcc016177\", \"chromedriver_unittests\": \"72e14eff5b44c92c695c65b2d6978a9dd5de923f\", \"components_browsertests\": \"f934d605291b789de857c0cb8e5e68f609df42d0\", \"components_unittests\": \"d234dc442f3cde4ae15c96df178c7c3289af707b\", \"compositor_unittests\": \"e35123618cd7b934d21f1c0d9a5830f2fc4958a8\", \"content_browsertests\": \"a5546ef8d03e344e4cea6efc1f9030c7676c5e94\", \"content_unittests\": \"6d944c62123eb424e4b2d3f0c52631456d821d41\", \"courgette_unittests\": \"60c8205c662e34994c44749954132bbcafbfad72\", \"crashpad_tests\": \"cd1a230fade128f932d1a88c10672aa851499551\", \"cronet_tests\": \"d8d9682846d707eb2262e79655bccc9bbbc6493d\", \"cronet_unittests\": \"b38e43170269ece9833172480e785f4e8c884cb6\", \"crypto_unittests\": \"db0154428b8e88db035d8a5b9eaa0878c50c687b\", \"delayloads_unittests\": \"6665fa075eed3c6c99e5f829fc26b06acd561608\", \"device_unittests\": \"7bf16d2787a20d9de0e347217215bcbf721ed50f\", \"display_unittests\": \"e68ecaaa7273f4142b3117e37a255dab931c33ae\", \"elevation_service_unittests\": \"07f6423141d09b46baa99aff253450505c5d2cb4\", \"events_unittests\": \"254953f9018fee33068b89dafa93e7fbb4f3bdfc\", \"extensions_browsertests\": \"2aa9a68fdbd8a31d580df70a521e541023ebd180\", \"extensions_unittests\": \"d7ba7536b75bd0aebc28b22d4222f34eff738e21\", \"filesystem_service_unittests\": \"d9fca1aff1809f5f8470b77d97a09496c78264ea\", \"gcm_unit_tests\": \"c873f6d310be9fc8844c186dd84ae6ea312c8fa9\", \"gcp_unittests\": \"ca9327d3a5e660e19221cacab89e8dce77bd9598\", \"gfx_unittests\": \"0f92cf2dd993f5c5a92729f701a2e8e711eff93b\", \"gin_unittests\": \"3da0bc4558a13dcf1f8699c3ef0e58247c65adcd\", \"google_apis_unittests\": \"ab1eea69a829b2fb36ec955a893bcf88918b75a2\", \"gpu_unittests\": \"9b633494a0c612d5300137334facd396cbf55fc8\", \"gwp_asan_unittests\": \"4d0e37a065daee9e7d83b04bada5f680550c04c7\", \"headless_browsertests\": \"47cbed6ffbae63dde1ee6489a5dc31f6b5a151bf\", \"headless_unittests\": \"0f8333d0c27d7b63e7f2f89114ab62b6944a7ae9\", \"install_static_unittests\": \"75c458a227a9646abd8ea3c2b0124c685d4a0686\", \"installer_util_unittests\": \"d2d268b5ec4762122d5456885d4711f89847e27a\", \"interactive_ui_tests\": \"44d4ba15c9c8680125ceb2b79dbfdbc299586566\", \"ipc_tests\": \"7c3c118d69e8437452bdacafd00f03b60595d3e5\", \"jingle_unittests\": \"a417a53376120afffd7fd27b1fba5d5a571e59f1\", \"latency_unittests\": \"3792aa10946bd2b55e5152eb8becfa0e9c4b1ebd\", \"libjingle_xmpp_unittests\": \"3c790026f4f5527d6326a89d3ea65b6abb27957f\", \"liburlpattern_unittests\": \"1197a04acf4ef5deb63939ab04c28aacab4c191f\", \"media_blink_unittests\": \"821411ecec84c192fbd41ffc1cf1b48d7a74a70a\", \"media_unittests\": \"f6cfbe1050a689d7c435db96ed7f9159c64b6c7e\", \"message_center_unittests\": \"17481e6841efbda375b629f4abd9ae7f72c42681\", \"midi_unittests\": \"5539305e7709bdf09af422f447bb6e1b1ba07595\", \"mojo_core_unittests\": \"93400fd4e9a9f6cf9c52b2fbe1df537b3ed63f37\", \"mojo_unittests\": \"f319ebfa820e600052be4a2dc68afe3d122abbfc\", \"nacl_loader_unittests\": \"4bdc1cd346287fa8db266f494bb526ade5030ac6\", \"native_theme_unittests\": \"7318a39eb10d46e9159f45d7246cb46f396a3790\", \"net_unittests\": \"6c3787b08ae8f62e22f4b5fd6ed607c6087ff7d3\", \"notification_helper_unittests\": \"792c4aa443306ac6a5d14c5f43a90ebefd55d2ab\", \"pdf_unittests\": \"3431222c3713ee0ecac795739b6ac10043e944f9\", \"perfetto_unittests\": \"18c5a1a4e3b656b330dcac8dd04e9fb2d26c8871\", \"ppapi_unittests\": \"5e82021e0fc7ed3a5007846a1e0da6183ccf6f25\", \"printing_unittests\": \"8f7cc63f40669617328c2070acb01394cb701d26\", \"remoting_unittests\": \"1e2b49518de663941df0c3bccb7556ac21979c4d\", \"sbox_integration_tests\": \"40d8c7f653e3b35ee291c0b5d85bc7e3ad3e70c6\", \"sbox_unittests\": \"c96c868df94e3171e7ad612fc621145f53ba8729\", \"sbox_validation_tests\": \"c602bd4cb5ad04efa0ae042c76224100c878dc74\", \"service_manager_unittests\": \"4c49e3dea64bd30a549f082d0c2ef0236378ce01\", \"services_unittests\": \"5af099a944ec213230d6a20bf1a2ad6c1347f0cd\", \"setup_unittests\": \"3c07b14d77bb26bfbc0386909c1be7d08e204fb8\", \"shell_dialogs_unittests\": \"1115e5005931751201a409d22b9db049b2314a18\", \"skia_unittests\": \"e4a088f307b3db262bc97f7c9bf2b21c8549731e\", \"snapshot_unittests\": \"47129d59d6e445f6d1a7846c840fc2432175028d\", \"sql_unittests\": \"c9599d6cb4e4677b65f4e320e3c31f4e425242a6\", \"storage_unittests\": \"3049b9d5da432b2ff91d8082df4fda7f153d94be\", \"sync_integration_tests\": \"27e447e654107988c2defb8fde3614e5d1ac749c\", \"traffic_annotation_auditor_unittests\": \"f42de39edf9ad1852ddcd1f42a4872883b7e4cd1\", \"ui_base_unittests\": \"1de10ff9965616923e049993fa9ca1a01b540f70\", \"ui_touch_selection_unittests\": \"15483f3123975ede133b2e71ba50ebc877c9cc34\", \"unit_tests\": \"d04485697245eeb4f6f8157a648c21e3f607650f\", \"updater_tests\": \"dcd2bba4e9f9d1df21b68f4483049d09d651f858\", \"url_unittests\": \"3f85e886bfa59aacc2f7f644518e100af3226ab5\", \"views_unittests\": \"bf380f0f6fc686f1fbc2f9165762cd3b321f0df3\", \"viz_unittests\": \"b5fade6c6d23533408310bdba55a21a5fc564626\", \"vr_common_unittests\": \"ffa7472ae1e9f574d71ae8958c02e3b9669f6e1a\", \"vr_pixeltests\": \"d47e4ef248d7d52eec37581369bb2b7332c0045c\", \"weblayer_browsertests\": \"bc643015c0c7ec8b7600afdb9f4a80434da0ce3e\", \"weblayer_unittests\": \"4f793b85da3f8332aa23e809ad9bd2d7a78b3b3a\", \"wm_unittests\": \"085eb2935d7c84cdca5047b3095732062315fbdf\", \"wtf_unittests\": \"abbb664c55849c8ea24a58db495af34bf169de20\", \"zlib_unittests\": \"f3772cdc0be2f695d23257d34c35cf32a2f933db\", \"zucchini_unittests\": \"fdf0b1a674b858d482c0a4df9711ecf752523993\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-miap\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608216186033682",
"status": "COMPLETED",
"status_changed_ts": "1608219887503353",
"tags": [
"build_address:luci.chromium.ci/win-asan/16282",
"builder:win-asan",
"buildset:commit/git/a7361976c2c0be7139f4de8eb8844702a198f0cf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7361976c2c0be7139f4de8eb8844702a198f0cf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026086884393339456",
"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/8860656822132816512/+/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:5089f91364099110",
"user_agent:luci-scheduler"
],
"updated_ts": "1608219887510899",
"url": "https://ci.chromium.org/b/8860656822132816512",
"utcnow_ts": "1608219888251771"
}
}
[15:44:48.277] Invocation finished in 1h1m45.367181978s with status SUCCEEDED