[12:08:46.745] New invocation is queued and will start shortly
[12:08:47.818] Starting the invocation (attempt 1)
[12:08:47.853] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:08:47.854] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:08:47.854] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017852266259327488",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c13b60c62881c6dc0e88c752dde6bed9bd374750\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017852266259327488",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c13b60c62881c6dc0e88c752dde6bed9bd374750",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c13b60c62881c6dc0e88c752dde6bed9bd374750",
"gitiles_ref:refs/heads/master"
]
}
[12:08:48.560] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616069327947492",
"id": "8852422204336926768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c13b60c62881c6dc0e88c752dde6bed9bd374750\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616069328422771",
"tags": [
"build_address:luci.chromium.ci/win-asan/17851",
"builder:win-asan",
"buildset:commit/git/c13b60c62881c6dc0e88c752dde6bed9bd374750",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c13b60c62881c6dc0e88c752dde6bed9bd374750",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017852266259327488",
"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/8852422204336926768/+/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": "1616069328422897",
"url": "https://ci.chromium.org/b/8852422204336926768",
"utcnow_ts": "1616069328554187"
}
}
[12:08:48.560] Task URL: https://ci.chromium.org/b/8852422204336926768
[12:08:48.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:2:0) after 1m0s
[12:08:53.246] Received PubSub notification, asking Buildbucket for the build status
[12:08:53.320] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:48.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:2:0)
[12:09:48.560] Timer tick, asking Buildbucket for the build status
[12:09:48.625] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:48.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:4:0) after 1m0s
[12:10:48.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:4:0)
[12:10:48.666] Timer tick, asking Buildbucket for the build status
[12:10:48.745] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:48.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:5:0) after 1m0s
[12:11:48.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:5:0)
[12:11:48.769] Timer tick, asking Buildbucket for the build status
[12:11:48.986] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:48.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:6:0) after 1m0s
[12:12:49.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:6:0)
[12:12:49.039] Timer tick, asking Buildbucket for the build status
[12:12:49.117] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:49.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:7:0) after 1m0s
[12:13:49.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:7:0)
[12:13:49.135] Timer tick, asking Buildbucket for the build status
[12:13:49.194] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:49.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:8:0) after 1m0s
[12:14:49.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:8:0)
[12:14:49.195] Timer tick, asking Buildbucket for the build status
[12:14:49.594] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:49.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:9:0) after 1m0s
[12:15:49.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:9:0)
[12:15:49.635] Timer tick, asking Buildbucket for the build status
[12:15:49.697] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:49.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:10:0) after 1m0s
[12:16:49.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:10:0)
[12:16:49.703] Timer tick, asking Buildbucket for the build status
[12:16:49.765] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:49.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:11:0) after 1m0s
[12:17:49.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:11:0)
[12:17:49.788] Timer tick, asking Buildbucket for the build status
[12:17:49.854] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:49.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:12:0) after 1m0s
[12:18:49.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:12:0)
[12:18:49.871] Timer tick, asking Buildbucket for the build status
[12:18:49.937] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:49.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:13:0) after 1m0s
[12:19:49.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:13:0)
[12:19:49.986] Timer tick, asking Buildbucket for the build status
[12:19:50.144] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:50.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:14:0) after 1m0s
[12:20:50.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:14:0)
[12:20:50.159] Timer tick, asking Buildbucket for the build status
[12:20:50.264] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:50.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:15:0) after 1m0s
[12:21:50.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:15:0)
[12:21:50.281] Timer tick, asking Buildbucket for the build status
[12:21:50.370] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:50.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:16:0) after 1m0s
[12:22:50.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:16:0)
[12:22:50.410] Timer tick, asking Buildbucket for the build status
[12:22:50.571] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:50.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:17:0) after 1m0s
[12:23:50.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:17:0)
[12:23:50.588] Timer tick, asking Buildbucket for the build status
[12:23:50.658] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:50.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:18:0) after 1m0s
[12:24:50.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:18:0)
[12:24:50.746] Timer tick, asking Buildbucket for the build status
[12:24:51.234] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:51.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:19:0) after 1m0s
[12:25:51.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:19:0)
[12:25:51.269] Timer tick, asking Buildbucket for the build status
[12:25:51.827] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:51.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:20:0) after 1m0s
[12:26:51.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:20:0)
[12:26:51.850] Timer tick, asking Buildbucket for the build status
[12:26:51.947] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:51.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:21:0) after 1m0s
[12:27:51.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:21:0)
[12:27:51.993] Timer tick, asking Buildbucket for the build status
[12:27:52.110] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:52.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:22:0) after 1m0s
[12:28:52.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:22:0)
[12:28:52.185] Timer tick, asking Buildbucket for the build status
[12:28:52.247] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:52.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:23:0) after 1m0s
[12:29:52.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:23:0)
[12:29:52.318] Timer tick, asking Buildbucket for the build status
[12:29:52.492] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:52.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:24:0) after 1m0s
[12:30:52.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:24:0)
[12:30:52.516] Timer tick, asking Buildbucket for the build status
[12:30:52.630] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:52.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:25:0) after 1m0s
[12:31:52.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:25:0)
[12:31:52.755] Timer tick, asking Buildbucket for the build status
[12:31:53.254] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:53.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:26:0) after 1m0s
[12:32:53.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:26:0)
[12:32:53.284] Timer tick, asking Buildbucket for the build status
[12:32:53.469] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:53.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:27:0) after 1m0s
[12:33:53.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:27:0)
[12:33:53.565] Timer tick, asking Buildbucket for the build status
[12:33:53.650] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:53.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:28:0) after 1m0s
[12:34:53.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:28:0)
[12:34:53.724] Timer tick, asking Buildbucket for the build status
[12:34:54.117] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:54.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:29:0) after 1m0s
[12:35:54.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:29:0)
[12:35:54.136] Timer tick, asking Buildbucket for the build status
[12:35:54.202] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:54.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:30:0) after 1m0s
[12:36:54.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:30:0)
[12:36:54.231] Timer tick, asking Buildbucket for the build status
[12:36:54.310] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:54.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:31:0) after 1m0s
[12:37:54.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:31:0)
[12:37:54.347] Timer tick, asking Buildbucket for the build status
[12:37:54.458] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:54.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:32:0) after 1m0s
[12:38:54.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:32:0)
[12:38:54.502] Timer tick, asking Buildbucket for the build status
[12:38:54.564] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:54.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:33:0) after 1m0s
[12:39:54.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:33:0)
[12:39:54.581] Timer tick, asking Buildbucket for the build status
[12:39:55.186] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:55.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:34:0) after 1m0s
[12:40:55.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:34:0)
[12:40:55.247] Timer tick, asking Buildbucket for the build status
[12:40:55.321] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:55.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:35:0) after 1m0s
[12:41:55.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:35:0)
[12:41:55.340] Timer tick, asking Buildbucket for the build status
[12:41:55.735] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:55.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:36:0) after 1m0s
[12:42:55.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:36:0)
[12:42:55.757] Timer tick, asking Buildbucket for the build status
[12:42:55.832] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:55.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:37:0) after 1m0s
[12:43:55.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:37:0)
[12:43:55.848] Timer tick, asking Buildbucket for the build status
[12:43:55.932] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:55.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:38:0) after 1m0s
[12:44:55.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:38:0)
[12:44:55.931] Timer tick, asking Buildbucket for the build status
[12:44:56.019] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:56.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:39:0) after 1m0s
[12:45:56.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:39:0)
[12:45:56.064] Timer tick, asking Buildbucket for the build status
[12:45:56.330] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:56.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:40:0) after 1m0s
[12:46:56.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:40:0)
[12:46:56.347] Timer tick, asking Buildbucket for the build status
[12:46:56.427] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:56.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:41:0) after 1m0s
[12:47:56.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:41:0)
[12:47:56.496] Timer tick, asking Buildbucket for the build status
[12:47:56.578] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:56.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:42:0) after 1m0s
[12:48:56.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:42:0)
[12:48:56.594] Timer tick, asking Buildbucket for the build status
[12:48:56.670] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:56.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:43:0) after 1m0s
[12:49:56.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:43:0)
[12:49:56.693] Timer tick, asking Buildbucket for the build status
[12:49:56.838] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:56.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:44:0) after 1m0s
[12:50:56.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:44:0)
[12:50:56.891] Timer tick, asking Buildbucket for the build status
[12:50:57.012] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:57.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:45:0) after 1m0s
[12:51:57.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:45:0)
[12:51:57.062] Timer tick, asking Buildbucket for the build status
[12:51:57.148] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:57.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:46:0) after 1m0s
[12:52:57.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:46:0)
[12:52:57.274] Timer tick, asking Buildbucket for the build status
[12:52:57.560] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:57.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:47:0) after 1m0s
[12:53:57.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:47:0)
[12:53:57.547] Timer tick, asking Buildbucket for the build status
[12:53:57.635] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:57.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:48:0) after 1m0s
[12:54:57.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:48:0)
[12:54:57.654] Timer tick, asking Buildbucket for the build status
[12:54:57.743] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:57.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:49:0) after 1m0s
[12:55:57.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:49:0)
[12:55:57.759] Timer tick, asking Buildbucket for the build status
[12:55:57.837] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:57.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:50:0) after 1m0s
[12:56:57.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:50:0)
[12:56:57.856] Timer tick, asking Buildbucket for the build status
[12:56:57.946] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:57.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:51:0) after 1m0s
[12:57:57.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:51:0)
[12:57:57.963] Timer tick, asking Buildbucket for the build status
[12:57:58.036] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:58.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:52:0) after 1m0s
[12:58:58.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:52:0)
[12:58:58.077] Timer tick, asking Buildbucket for the build status
[12:58:58.153] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:58.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:53:0) after 1m0s
[12:59:58.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:53:0)
[12:59:58.179] Timer tick, asking Buildbucket for the build status
[12:59:58.629] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:58.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:54:0) after 1m0s
[13:00:58.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:54:0)
[13:00:58.704] Timer tick, asking Buildbucket for the build status
[13:00:58.773] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:58.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:55:0) after 1m0s
[13:01:58.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:55:0)
[13:01:58.816] Timer tick, asking Buildbucket for the build status
[13:01:58.907] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:58.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:56:0) after 1m0s
[13:02:58.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:56:0)
[13:02:58.963] Timer tick, asking Buildbucket for the build status
[13:02:59.071] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:59.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:57:0) after 1m0s
[13:03:59.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:57:0)
[13:03:59.259] Timer tick, asking Buildbucket for the build status
[13:03:59.489] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:59.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:58:0) after 1m0s
[13:04:59.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:58:0)
[13:04:59.601] Timer tick, asking Buildbucket for the build status
[13:05:00.172] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:00.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:59:0) after 1m0s
[13:06:00.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:59:0)
[13:06:00.287] Timer tick, asking Buildbucket for the build status
[13:06:00.369] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:00.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:60:0) after 1m0s
[13:07:00.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:60:0)
[13:07:00.422] Timer tick, asking Buildbucket for the build status
[13:07:00.887] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:00.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:61:0) after 1m0s
[13:08:01.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:61:0)
[13:08:01.186] Timer tick, asking Buildbucket for the build status
[13:08:01.279] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:01.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:62:0) after 1m0s
[13:09:01.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:62:0)
[13:09:01.309] Timer tick, asking Buildbucket for the build status
[13:09:01.454] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:01.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:63:0) after 1m0s
[13:10:01.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:63:0)
[13:10:01.453] Timer tick, asking Buildbucket for the build status
[13:10:01.616] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:01.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:64:0) after 1m0s
[13:11:01.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:64:0)
[13:11:01.612] Timer tick, asking Buildbucket for the build status
[13:11:01.726] Build 8852422204336926768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:01.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017852266259327488:65:0) after 1m0s
[13:11:39.396] Received PubSub notification, asking Buildbucket for the build status
[13:11:40.045] Build 8852422204336926768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:11:40.045] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616073098265036",
"created_by": "project:chromium",
"created_ts": "1616069327947492",
"id": "8852422204336926768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c13b60c62881c6dc0e88c752dde6bed9bd374750\"}}",
"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\": \"c13b60c62881c6dc0e88c752dde6bed9bd374750\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-6-xj00\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17851, \"clang_revision\": \"llvmorg-13-init-4720-g7bafe336-1\", \"got_angle_revision\": \"39296f396f5bf9abb8c8d80b147be7029c633964\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"c13b60c62881c6dc0e88c752dde6bed9bd374750\", \"got_revision_cp\": \"refs/heads/master@{#864195}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"4f0f4e2a3c5ad9dc7312774da5197e0179c30863\", \"got_v8_revision_cp\": \"refs/heads/9.1.146@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"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\": \"c13b60c62881c6dc0e88c752dde6bed9bd374750\", \"swarm_hashes_refs/heads/master(at){#864195}_without_patch\": {\"absl_hardening_tests\": \"ed1f52aaf9ebce5e852aaef12e9b5c1a4539eaf5\", \"accessibility_unittests\": \"43aa71aa32b71b05533942c8da94b23280e0e46b\", \"angle_unittests\": \"a7016e0191d1c7fea1876c80c7f091ba703f3d71\", \"app_shell_unittests\": \"55c735346e2d50cc5b680069884fdb78739cc242\", \"aura_unittests\": \"c00c46a42ec8ea020f9115720390d3cc5f491dd1\", \"base_unittests\": \"9d8709f6138c258ea683c83c14c8dd8e069569da\", \"base_util_unittests\": \"214b63faaa57fb49b70aec635926537c40d5809a\", \"blink_common_unittests\": \"937c1376d9910f1fc08ce1948b20c1419984f765\", \"blink_fuzzer_unittests\": \"6d8336f40e598a8e4568014a55977791f75583a8\", \"blink_heap_unittests\": \"a3f52b28c3c0fcc9e82fdae2e455015bd8bea7d2\", \"blink_platform_unittests\": \"45791fe7ffb5cd992f61c3c1ed65fffb849ced67\", \"blink_unittests\": \"689496ac631f896de4ec8c36badd639c6e0de528\", \"boringssl_crypto_tests\": \"ae3601c4b8599279402329a506d81d6fb7112e05\", \"boringssl_ssl_tests\": \"fd773023fa76e942453adecaa55b94b774d4c474\", \"browser_switcher_bho_unittests\": \"6072e2a5f395e5ae2801fc32550009c21b94cae1\", \"browser_tests\": \"5cc5a03e0ad6664a2a6610df4e9c518677197f33\", \"capture_unittests\": \"64ff2db3bd9411ab8bcb92962b3d0b3f2e1378e6\", \"cast_unittests\": \"0337d962ac73b03633df5adb45c4fee093a65a37\", \"cc_unittests\": \"12788033c61be15e7cc4a68cee35d26018a4513e\", \"chrome_app_unittests\": \"5845fae1dfd601803d749d45614cd56e08e99725\", \"chrome_cleaner_unittests\": \"bcefdb106558242730c199f9cc41c95aa7043635\", \"chrome_elf_unittests\": \"7b19d5606067a585a7e8774e2b25f2f49839c509\", \"chromedriver_unittests\": \"6ab5bfa5751d93e1a980c92eaddf38f37bfb94f4\", \"color_unittests\": \"459d3b3c66715c1d527667d50954356de80292b0\", \"components_browsertests\": \"b066d6f10dce45afa5149e3dbbc3334b2cf14367\", \"components_unittests\": \"4efae8dc5b4efac3fc4942a63613119dbed11428\", \"compositor_unittests\": \"ec40071b0e0f52a1f2fea421340ae8adb2445ed6\", \"content_browsertests\": \"182761ae1ba51b35f71cdb732250a6a2eec2f4fa\", \"content_unittests\": \"55b55e4d16051cd52d95b94bd179c17f5a9c4049\", \"courgette_unittests\": \"459207b90cd52ad8bf5eeced7afd059152259cdb\", \"crashpad_tests\": \"f1a1fca6646fc9c6910683083a456f5a76e2dcfc\", \"cronet_tests\": \"ae4165cdc70b9572a9b3285fe5b31ad9ba8e4683\", \"cronet_unittests\": \"469820907b908ad77fdfb064bf23b6b29716069e\", \"crypto_unittests\": \"0bf31d3e78179d92f48cd0a98404e9f71ff91f57\", \"delayloads_unittests\": \"327c902272f869c44c41cb0aef4f0be25c13f2cf\", \"device_unittests\": \"556e99017b24526ec308d14db65b60eda437d82a\", \"display_unittests\": \"fa221e247ae01e28e35c2298151428ba71caf56a\", \"elevation_service_unittests\": \"9f0ea9a146cd23bf36f83bc178721975e8e3d804\", \"events_unittests\": \"e611401060c4721aaaa2cccbc5de0817dccfd88d\", \"extensions_browsertests\": \"e548cb80d572c89a5b03fd49e85e5a6e1c856357\", \"extensions_unittests\": \"c046adcd8b70a1d93d393b17d40d8e9cf69b7367\", \"filesystem_service_unittests\": \"225e1976078cf0915d2e607bb57a22e7d4d9f1e8\", \"gcm_unit_tests\": \"2543abaa1374900c7a8c02befc3a3ef6f8a4fbbf\", \"gcp_unittests\": \"6b032581b72cf92ab8a739ad59e6bdf58c7f9b1a\", \"gfx_unittests\": \"b2fb062e358091cf60bb4dd17d6631763a9485c0\", \"gin_unittests\": \"a6673ac29fd39708d5246b01be09e41686180b5d\", \"google_apis_unittests\": \"e54bc4abc2c0d7fd307fcfa48199bd9d3e312b26\", \"gpu_unittests\": \"14c174cc86049e24183b7f47f383a37497210c3d\", \"gwp_asan_unittests\": \"007db8d58546d88548e14d36886a3172920ca941\", \"headless_browsertests\": \"813ba076d45801c37bcbf40d42ba6caa6d570069\", \"headless_unittests\": \"95349206410a6fa2311d385c3568ea84b60c7408\", \"install_static_unittests\": \"eea028710ceb22b813525deb6cc6d85135ac7613\", \"installer_util_unittests\": \"1367cf1c7c9a9e70c53f84a64c4f96d67fd17297\", \"interactive_ui_tests\": \"8ce9c8c8387e17d75df31ee2d5c6ab39f86d8b8d\", \"ipc_tests\": \"523267aba5fe7cb8c6f961e70534f594bf0e17a8\", \"jingle_unittests\": \"f0b443e70cbffe1b106cb489c8b706b48a784213\", \"latency_unittests\": \"3e7c385f94530d81173ca8c5969abd922944271f\", \"libjingle_xmpp_unittests\": \"cb9a3a0c3fb7725397a33bf92f71f29f216ebc9a\", \"liburlpattern_unittests\": \"ae30794054963cbadce46c045392d021e7c77939\", \"media_blink_unittests\": \"5747aeb1762dd934d5083da0bbc858b82881b8c2\", \"media_unittests\": \"19b82fdd070d0cb7e008b522bcbf2ae05d2dde90\", \"message_center_unittests\": \"a0b5136dcdb74857b8df56137e3efb26d3b24b18\", \"midi_unittests\": \"2ff390825a0b1e105073df31d52449e53e296605\", \"mojo_core_unittests\": \"cd260663f4fca088f5270b7e8093cf35b4cb94b7\", \"mojo_unittests\": \"235a399424a0107199432e6e95389f2e2249a27c\", \"nacl_loader_unittests\": \"62665b6f8b738170e241b6a701e69de861848380\", \"native_theme_unittests\": \"1e71d3842740811e78fbc0d96c80b366e297222d\", \"net_unittests\": \"ccbd4f457da3bf401ef2257facda5e6c6cfa0c24\", \"notification_helper_unittests\": \"a24f3e151aeb96aa45d43f3aaff6aa467e96c727\", \"pdf_unittests\": \"29438c176aa660be9f9cae133fe4f28bae6711f0\", \"perfetto_unittests\": \"57b6e46658975f62cdcf38d27e2fcf6cf2aa63e9\", \"ppapi_unittests\": \"6a3fc6adf78e16ed36f0d0cef56419c93d6ee66a\", \"printing_unittests\": \"2808a1a6228a7f5ddfa2eafbb954129fbdc9b60c\", \"remoting_unittests\": \"b16afdfd4a60c807e469dbc81a23aa1d480a16d3\", \"sbox_integration_tests\": \"6e367bf1abbd0f78d351ee7304fcdd2370fd950a\", \"sbox_unittests\": \"499f7bfe89f7d04ac43be91d8d51ac65d37fff26\", \"sbox_validation_tests\": \"b690cc0b6632a46bc1f23913a8db83e5f9eea447\", \"service_manager_unittests\": \"83c7d86a3054efacea5dea613aa74d86afe12ed1\", \"services_unittests\": \"de3c3dd1000bff7f5b3f40df709d3168daba8f94\", \"setup_unittests\": \"e690a8aaf7d800a9763b2b558540e8a637601cf6\", \"shell_dialogs_unittests\": \"b79762a67fb8a8ef2a1748670a3e0258969629b5\", \"skia_unittests\": \"36e20b57495e17ee602eb4266f0dd2df83d2f5bd\", \"snapshot_unittests\": \"d46df647028b004e40023f7a89c9be8c9f1388ac\", \"sql_unittests\": \"c15a1c0698a81cfd59e8ef537901a39080470f1a\", \"storage_unittests\": \"c11899dbe73c305aa7428721f0ece45b5322a43e\", \"sync_integration_tests\": \"ba1a0a367d055e81c90d577cc0749d2fdca41d61\", \"traffic_annotation_auditor_unittests\": \"b507ea0b5096f862c23d2f5943080ee688533937\", \"ui_base_unittests\": \"da074f009416dc9646c71cec62e29c4fec4a9181\", \"ui_touch_selection_unittests\": \"bce26dff51130f66a26e62c305eae717833609e5\", \"unit_tests\": \"41745d86866949ef901ee1f91fc490d18eb66a8d\", \"updater_tests\": \"cb2f52161704d24a10b3775cfa4155fca781b08e\", \"url_unittests\": \"562ad5e48e07f848a58b041e4a5d0bf189b8c52c\", \"views_unittests\": \"436ec35b5529b7ef2d39ddfee5b93f7f585534f1\", \"viz_unittests\": \"94070857e7d5a14ea311b53fd8dd9f72ac1ebf14\", \"vr_common_unittests\": \"588d239ebf85644abd5dd369d089e4c903f352e3\", \"vr_pixeltests\": \"98caacf9e23523c8008d64a626c03d5b8c9bb3e2\", \"weblayer_browsertests\": \"2a98b26883d88892a64f00897a2897b391fa4e65\", \"weblayer_unittests\": \"87bd83644805e01a5383fbb82549e6ecb3fc091f\", \"wm_unittests\": \"329603441003377120c9f0bb61c706ed38ea43bb\", \"wtf_unittests\": \"9bd1a51afb86699fef57b56e7fe9a59f5206070c\", \"zlib_unittests\": \"6eaae3628d8fe5372d8c744fc6ef9efa96d6f6f0\", \"zucchini_unittests\": \"a573d082dae8fc54bf0872f488ef6ca914392f6a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-xj00\"], \"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\", \"2.7\", \"2.7.13\"], \"server_version\": [\"5776-cb9e157\"], \"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": "1616069331944327",
"status": "COMPLETED",
"status_changed_ts": "1616073098479847",
"tags": [
"build_address:luci.chromium.ci/win-asan/17851",
"builder:win-asan",
"buildset:commit/git/c13b60c62881c6dc0e88c752dde6bed9bd374750",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c13b60c62881c6dc0e88c752dde6bed9bd374750",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017852266259327488",
"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/8852422204336926768/+/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:525e0e87172f3f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616073098483935",
"url": "https://ci.chromium.org/b/8852422204336926768",
"utcnow_ts": "1616073100021475"
}
}
[13:11:40.045] Invocation finished in 1h2m53.315030809s with status SUCCEEDED