[20:15:18.968] New invocation is queued and will start shortly
[20:15:20.064] Starting the invocation (attempt 1)
[20:15:20.099] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:15:20.099] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:15:20.100] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024525831510646880",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"854ae1c44e4f69a9cc07eb87f626c248b6113604\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024525831510646880",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/854ae1c44e4f69a9cc07eb87f626c248b6113604",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/854ae1c44e4f69a9cc07eb87f626c248b6113604",
"gitiles_ref:refs/heads/master"
]
}
[20:15:21.327] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609704920207278",
"id": "8859095769547768880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"854ae1c44e4f69a9cc07eb87f626c248b6113604\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609704921087741",
"tags": [
"build_address:luci.chromium.ci/win-asan/16614",
"builder:win-asan",
"buildset:commit/git/854ae1c44e4f69a9cc07eb87f626c248b6113604",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/854ae1c44e4f69a9cc07eb87f626c248b6113604",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024525831510646880",
"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/8859095769547768880/+/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": "1609704921087877",
"url": "https://ci.chromium.org/b/8859095769547768880",
"utcnow_ts": "1609704921310358"
}
}
[20:15:21.327] Task URL: https://ci.chromium.org/b/8859095769547768880
[20:15:21.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:2:0) after 1m0s
[20:16:21.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:2:0)
[20:16:21.380] Timer tick, asking Buildbucket for the build status
[20:16:21.478] Build 8859095769547768880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:21.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:3:0) after 1m0s
[20:17:21.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:3:0)
[20:17:21.551] Timer tick, asking Buildbucket for the build status
[20:17:21.641] Build 8859095769547768880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:21.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:4:0) after 1m0s
[20:18:21.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:4:0)
[20:18:21.650] Timer tick, asking Buildbucket for the build status
[20:18:21.817] Build 8859095769547768880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:21.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:5:0) after 1m0s
[20:19:21.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:5:0)
[20:19:21.871] Timer tick, asking Buildbucket for the build status
[20:19:21.937] Build 8859095769547768880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:21.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:6:0) after 1m0s
[20:19:30.192] Received PubSub notification, asking Buildbucket for the build status
[20:19:30.304] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:21.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:6:0)
[20:20:21.954] Timer tick, asking Buildbucket for the build status
[20:20:22.027] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:22.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:8:0) after 1m0s
[20:21:22.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:8:0)
[20:21:22.050] Timer tick, asking Buildbucket for the build status
[20:21:22.604] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:22.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:9:0) after 1m0s
[20:22:22.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:9:0)
[20:22:22.720] Timer tick, asking Buildbucket for the build status
[20:22:22.777] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:22.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:10:0) after 1m0s
[20:23:22.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:10:0)
[20:23:22.837] Timer tick, asking Buildbucket for the build status
[20:23:22.897] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:22.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:11:0) after 1m0s
[20:24:22.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:11:0)
[20:24:22.978] Timer tick, asking Buildbucket for the build status
[20:24:23.042] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:23.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:12:0) after 1m0s
[20:25:23.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:12:0)
[20:25:23.060] Timer tick, asking Buildbucket for the build status
[20:25:23.117] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:23.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:13:0) after 1m0s
[20:26:23.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:13:0)
[20:26:23.136] Timer tick, asking Buildbucket for the build status
[20:26:24.204] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:24.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:14:0) after 1m0s
[20:27:24.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:14:0)
[20:27:24.298] Timer tick, asking Buildbucket for the build status
[20:27:24.361] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:24.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:15:0) after 1m0s
[20:28:24.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:15:0)
[20:28:24.406] Timer tick, asking Buildbucket for the build status
[20:28:24.463] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:24.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:16:0) after 1m0s
[20:29:24.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:16:0)
[20:29:24.531] Timer tick, asking Buildbucket for the build status
[20:29:24.767] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:24.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:17:0) after 1m0s
[20:30:24.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:17:0)
[20:30:24.756] Timer tick, asking Buildbucket for the build status
[20:30:24.972] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:24.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:18:0) after 1m0s
[20:31:25.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:18:0)
[20:31:25.016] Timer tick, asking Buildbucket for the build status
[20:31:25.128] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:25.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:19:0) after 1m0s
[20:32:25.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:19:0)
[20:32:25.231] Timer tick, asking Buildbucket for the build status
[20:32:25.378] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:25.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:20:0) after 1m0s
[20:33:25.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:20:0)
[20:33:25.398] Timer tick, asking Buildbucket for the build status
[20:33:25.584] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:25.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:21:0) after 1m0s
[20:34:25.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:21:0)
[20:34:25.676] Timer tick, asking Buildbucket for the build status
[20:34:26.222] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:26.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:22:0) after 1m0s
[20:35:26.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:22:0)
[20:35:26.237] Timer tick, asking Buildbucket for the build status
[20:35:26.620] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:26.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:23:0) after 1m0s
[20:36:26.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:23:0)
[20:36:26.653] Timer tick, asking Buildbucket for the build status
[20:36:26.712] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:26.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:24:0) after 1m0s
[20:37:26.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:24:0)
[20:37:26.789] Timer tick, asking Buildbucket for the build status
[20:37:26.902] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:26.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:25:0) after 1m0s
[20:38:26.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:25:0)
[20:38:26.948] Timer tick, asking Buildbucket for the build status
[20:38:27.148] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:27.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:26:0) after 1m0s
[20:39:27.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:26:0)
[20:39:27.232] Timer tick, asking Buildbucket for the build status
[20:39:27.296] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:27.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:27:0) after 1m0s
[20:40:27.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:27:0)
[20:40:27.375] Timer tick, asking Buildbucket for the build status
[20:40:27.624] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:27.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:28:0) after 1m0s
[20:41:27.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:28:0)
[20:41:27.597] Timer tick, asking Buildbucket for the build status
[20:41:27.706] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:27.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:29:0) after 1m0s
[20:42:27.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:29:0)
[20:42:27.824] Timer tick, asking Buildbucket for the build status
[20:42:27.989] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:27.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:30:0) after 1m0s
[20:43:28.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:30:0)
[20:43:28.010] Timer tick, asking Buildbucket for the build status
[20:43:28.103] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:28.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:31:0) after 1m0s
[20:44:28.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:31:0)
[20:44:28.121] Timer tick, asking Buildbucket for the build status
[20:44:28.185] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:28.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:32:0) after 1m0s
[20:45:28.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:32:0)
[20:45:28.222] Timer tick, asking Buildbucket for the build status
[20:45:28.469] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:28.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:33:0) after 1m0s
[20:46:28.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:33:0)
[20:46:28.510] Timer tick, asking Buildbucket for the build status
[20:46:28.584] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:28.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:34:0) after 1m0s
[20:47:28.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:34:0)
[20:47:28.640] Timer tick, asking Buildbucket for the build status
[20:47:28.716] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:28.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:35:0) after 1m0s
[20:48:28.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:35:0)
[20:48:28.821] Timer tick, asking Buildbucket for the build status
[20:48:28.910] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:28.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:36:0) after 1m0s
[20:49:28.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:36:0)
[20:49:28.999] Timer tick, asking Buildbucket for the build status
[20:49:29.097] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:29.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:37:0) after 1m0s
[20:50:29.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:37:0)
[20:50:29.148] Timer tick, asking Buildbucket for the build status
[20:50:29.492] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:29.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:38:0) after 1m0s
[20:51:29.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:38:0)
[20:51:29.618] Timer tick, asking Buildbucket for the build status
[20:51:29.927] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:29.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:39:0) after 1m0s
[20:52:30.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:39:0)
[20:52:30.049] Timer tick, asking Buildbucket for the build status
[20:52:30.105] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:30.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:40:0) after 1m0s
[20:53:30.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:40:0)
[20:53:30.232] Timer tick, asking Buildbucket for the build status
[20:53:30.302] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:30.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:41:0) after 1m0s
[20:54:30.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:41:0)
[20:54:30.530] Timer tick, asking Buildbucket for the build status
[20:54:30.602] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:30.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:42:0) after 1m0s
[20:55:30.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:42:0)
[20:55:30.694] Timer tick, asking Buildbucket for the build status
[20:55:30.913] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:30.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:43:0) after 1m0s
[20:56:30.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:43:0)
[20:56:30.993] Timer tick, asking Buildbucket for the build status
[20:56:31.053] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:31.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:44:0) after 1m0s
[20:57:31.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:44:0)
[20:57:31.031] Timer tick, asking Buildbucket for the build status
[20:57:31.087] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:31.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:45:0) after 1m0s
[20:58:31.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:45:0)
[20:58:31.192] Timer tick, asking Buildbucket for the build status
[20:58:31.243] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:31.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:46:0) after 1m0s
[20:59:31.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:46:0)
[20:59:31.248] Timer tick, asking Buildbucket for the build status
[20:59:31.349] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:31.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:47:0) after 1m0s
[21:00:31.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:47:0)
[21:00:31.372] Timer tick, asking Buildbucket for the build status
[21:00:31.903] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:31.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:48:0) after 1m0s
[21:01:32.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:48:0)
[21:01:32.067] Timer tick, asking Buildbucket for the build status
[21:01:32.401] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:32.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:49:0) after 1m0s
[21:02:32.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:49:0)
[21:02:32.476] Timer tick, asking Buildbucket for the build status
[21:02:32.585] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:32.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:50:0) after 1m0s
[21:03:32.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:50:0)
[21:03:32.628] Timer tick, asking Buildbucket for the build status
[21:03:32.729] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:32.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:51:0) after 1m0s
[21:04:32.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:51:0)
[21:04:32.871] Timer tick, asking Buildbucket for the build status
[21:04:32.950] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:32.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:52:0) after 1m0s
[21:05:33.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:52:0)
[21:05:33.082] Timer tick, asking Buildbucket for the build status
[21:05:33.535] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:33.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:53:0) after 1m0s
[21:06:33.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:53:0)
[21:06:33.647] Timer tick, asking Buildbucket for the build status
[21:06:33.978] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:33.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:54:0) after 1m0s
[21:07:33.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:54:0)
[21:07:33.992] Timer tick, asking Buildbucket for the build status
[21:07:34.313] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:34.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:55:0) after 1m0s
[21:08:34.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:55:0)
[21:08:34.487] Timer tick, asking Buildbucket for the build status
[21:08:34.631] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:34.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:56:0) after 1m0s
[21:09:34.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:56:0)
[21:09:34.710] Timer tick, asking Buildbucket for the build status
[21:09:34.816] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:34.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:57:0) after 1m0s
[21:10:34.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:57:0)
[21:10:34.896] Timer tick, asking Buildbucket for the build status
[21:10:35.013] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:35.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:58:0) after 1m0s
[21:11:35.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:58:0)
[21:11:35.042] Timer tick, asking Buildbucket for the build status
[21:11:35.685] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:35.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:59:0) after 1m0s
[21:12:35.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:59:0)
[21:12:35.750] Timer tick, asking Buildbucket for the build status
[21:12:35.848] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:35.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:60:0) after 1m0s
[21:13:36.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:60:0)
[21:13:36.359] Timer tick, asking Buildbucket for the build status
[21:13:36.801] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:36.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:61:0) after 1m0s
[21:14:36.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:61:0)
[21:14:36.995] Timer tick, asking Buildbucket for the build status
[21:14:37.057] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:37.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:62:0) after 1m0s
[21:15:37.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:62:0)
[21:15:37.240] Timer tick, asking Buildbucket for the build status
[21:15:37.398] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:37.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:63:0) after 1m0s
[21:16:37.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:63:0)
[21:16:37.610] Timer tick, asking Buildbucket for the build status
[21:16:37.781] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:37.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:64:0) after 1m0s
[21:17:37.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:64:0)
[21:17:37.889] Timer tick, asking Buildbucket for the build status
[21:17:37.950] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:37.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:65:0) after 1m0s
[21:18:38.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:65:0)
[21:18:38.030] Timer tick, asking Buildbucket for the build status
[21:18:38.102] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:38.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:66:0) after 1m0s
[21:19:38.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:66:0)
[21:19:38.158] Timer tick, asking Buildbucket for the build status
[21:19:38.317] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:38.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:67:0) after 1m0s
[21:20:38.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:67:0)
[21:20:38.497] Timer tick, asking Buildbucket for the build status
[21:20:38.648] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:38.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:68:0) after 1m0s
[21:21:38.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:68:0)
[21:21:38.726] Timer tick, asking Buildbucket for the build status
[21:21:39.133] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:39.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:69:0) after 1m0s
[21:22:39.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:69:0)
[21:22:39.227] Timer tick, asking Buildbucket for the build status
[21:22:39.290] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:39.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:70:0) after 1m0s
[21:23:39.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:70:0)
[21:23:39.481] Timer tick, asking Buildbucket for the build status
[21:23:39.873] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:39.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:71:0) after 1m0s
[21:24:39.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:71:0)
[21:24:39.893] Timer tick, asking Buildbucket for the build status
[21:24:40.031] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:40.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:72:0) after 1m0s
[21:25:40.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:72:0)
[21:25:40.025] Timer tick, asking Buildbucket for the build status
[21:25:40.153] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:40.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:73:0) after 1m0s
[21:26:40.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:73:0)
[21:26:40.221] Timer tick, asking Buildbucket for the build status
[21:26:40.432] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:40.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:74:0) after 1m0s
[21:27:40.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:74:0)
[21:27:40.451] Timer tick, asking Buildbucket for the build status
[21:27:40.847] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:40.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:75:0) after 1m0s
[21:28:40.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:75:0)
[21:28:40.873] Timer tick, asking Buildbucket for the build status
[21:28:40.991] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:40.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:76:0) after 1m0s
[21:29:41.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:76:0)
[21:29:41.068] Timer tick, asking Buildbucket for the build status
[21:29:41.135] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:41.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:77:0) after 1m0s
[21:30:41.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:77:0)
[21:30:41.168] Timer tick, asking Buildbucket for the build status
[21:30:41.409] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:41.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:78:0) after 1m0s
[21:31:41.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:78:0)
[21:31:41.464] Timer tick, asking Buildbucket for the build status
[21:31:41.545] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:41.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:79:0) after 1m0s
[21:32:41.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:79:0)
[21:32:41.624] Timer tick, asking Buildbucket for the build status
[21:32:41.714] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:41.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:80:0) after 1m0s
[21:33:41.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:80:0)
[21:33:41.746] Timer tick, asking Buildbucket for the build status
[21:33:41.829] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:41.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:81:0) after 1m0s
[21:34:41.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:81:0)
[21:34:41.983] Timer tick, asking Buildbucket for the build status
[21:34:42.070] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:42.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:82:0) after 1m0s
[21:35:42.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:82:0)
[21:35:42.162] Timer tick, asking Buildbucket for the build status
[21:35:42.269] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:42.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:83:0) after 1m0s
[21:36:42.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:83:0)
[21:36:42.381] Timer tick, asking Buildbucket for the build status
[21:36:42.493] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:42.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:84:0) after 1m0s
[21:37:42.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:84:0)
[21:37:42.573] Timer tick, asking Buildbucket for the build status
[21:37:42.724] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:42.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:85:0) after 1m0s
[21:38:42.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:85:0)
[21:38:42.754] Timer tick, asking Buildbucket for the build status
[21:38:42.846] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:42.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:86:0) after 1m0s
[21:39:42.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:86:0)
[21:39:42.884] Timer tick, asking Buildbucket for the build status
[21:39:43.222] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:43.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:87:0) after 1m0s
[21:40:43.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:87:0)
[21:40:43.276] Timer tick, asking Buildbucket for the build status
[21:40:43.386] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:43.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:88:0) after 1m0s
[21:41:43.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:88:0)
[21:41:43.409] Timer tick, asking Buildbucket for the build status
[21:41:43.467] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:43.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:89:0) after 1m0s
[21:42:43.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:89:0)
[21:42:43.571] Timer tick, asking Buildbucket for the build status
[21:42:43.925] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:43.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:90:0) after 1m0s
[21:43:43.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:90:0)
[21:43:43.956] Timer tick, asking Buildbucket for the build status
[21:43:44.052] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:44.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:91:0) after 1m0s
[21:44:44.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:91:0)
[21:44:44.076] Timer tick, asking Buildbucket for the build status
[21:44:44.421] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:44.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:92:0) after 1m0s
[21:45:44.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:92:0)
[21:45:44.505] Timer tick, asking Buildbucket for the build status
[21:45:44.656] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:44.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:93:0) after 1m0s
[21:46:44.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:93:0)
[21:46:44.673] Timer tick, asking Buildbucket for the build status
[21:46:44.749] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:44.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:94:0) after 1m0s
[21:47:44.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:94:0)
[21:47:44.769] Timer tick, asking Buildbucket for the build status
[21:47:44.832] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:44.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:95:0) after 1m0s
[21:48:44.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:95:0)
[21:48:44.884] Timer tick, asking Buildbucket for the build status
[21:48:44.952] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:44.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:96:0) after 1m0s
[21:49:44.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:96:0)
[21:49:44.975] Timer tick, asking Buildbucket for the build status
[21:49:45.332] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:45.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:97:0) after 1m0s
[21:50:45.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:97:0)
[21:50:45.437] Timer tick, asking Buildbucket for the build status
[21:50:45.545] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:45.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:98:0) after 1m0s
[21:51:45.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:98:0)
[21:51:45.804] Timer tick, asking Buildbucket for the build status
[21:51:46.013] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:46.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:99:0) after 1m0s
[21:52:46.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:99:0)
[21:52:46.037] Timer tick, asking Buildbucket for the build status
[21:52:46.149] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:46.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:100:0) after 1m0s
[21:53:46.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:100:0)
[21:53:46.299] Timer tick, asking Buildbucket for the build status
[21:53:46.427] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:46.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:101:0) after 1m0s
[21:54:46.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:101:0)
[21:54:46.512] Timer tick, asking Buildbucket for the build status
[21:54:46.622] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:46.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:102:0) after 1m0s
[21:55:46.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:102:0)
[21:55:46.716] Timer tick, asking Buildbucket for the build status
[21:55:46.815] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:46.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:103:0) after 1m0s
[21:56:46.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:103:0)
[21:56:46.919] Timer tick, asking Buildbucket for the build status
[21:56:46.985] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:46.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:104:0) after 1m0s
[21:57:47.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:104:0)
[21:57:47.242] Timer tick, asking Buildbucket for the build status
[21:57:47.506] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:47.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:105:0) after 1m0s
[21:58:47.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:105:0)
[21:58:47.577] Timer tick, asking Buildbucket for the build status
[21:58:47.838] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:47.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:106:0) after 1m0s
[21:59:50.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:106:0)
[21:59:50.576] Timer tick, asking Buildbucket for the build status
[21:59:50.792] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:50.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:107:0) after 1m0s
[22:00:50.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:107:0)
[22:00:50.863] Timer tick, asking Buildbucket for the build status
[22:00:50.958] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:50.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:108:0) after 1m0s
[22:01:51.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:108:0)
[22:01:51.021] Timer tick, asking Buildbucket for the build status
[22:01:51.273] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:51.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:109:0) after 1m0s
[22:02:51.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:109:0)
[22:02:51.300] Timer tick, asking Buildbucket for the build status
[22:02:51.494] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:51.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:110:0) after 1m0s
[22:03:51.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:110:0)
[22:03:51.545] Timer tick, asking Buildbucket for the build status
[22:03:51.644] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:51.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:111:0) after 1m0s
[22:04:51.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:111:0)
[22:04:51.665] Timer tick, asking Buildbucket for the build status
[22:04:51.817] Build 8859095769547768880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:51.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024525831510646880:112:0) after 1m0s
[22:05:39.465] Received PubSub notification, asking Buildbucket for the build status
[22:05:39.529] Build 8859095769547768880: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:05:39.529] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609711538846635",
"created_by": "project:chromium",
"created_ts": "1609704920207278",
"failure_reason": "BUILD_FAILURE",
"id": "8859095769547768880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"854ae1c44e4f69a9cc07eb87f626c248b6113604\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**net_unittests** failed because of:\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.IntermediateFromAia200Pem/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.RevocationSoftFailLeafCrlDpHttpError/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalTest.ValidityJustAfterNotBefore/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.RevocationHardFailLeafCrlDpHttpError/CertVerifyProcWin\\n\\n- ...13 more failure(s) (17 total)...\"}, \"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\": \"854ae1c44e4f69a9cc07eb87f626c248b6113604\", \"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-us-central1-b-32-1-aof9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16614, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"bf02c31bec07a549cc8eea26ad0e63143954643d\", \"got_dawn_revision\": \"86980018b3f2786aeb5ded1220ec94bc927fa6b2\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"854ae1c44e4f69a9cc07eb87f626c248b6113604\", \"got_revision_cp\": \"refs/heads/master@{#839984}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"ff29e249d3179766a40c9486183559fe3e5c1456\", \"got_v8_revision\": \"a5786bb381dd6ae4d1b936320a5fc00481566700\", \"got_v8_revision_cp\": \"refs/heads/8.9.202@{#1}\", \"got_webrtc_revision\": \"8673643bc2e357ca7b6dc01c0018ed05d20ffd72\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32900}\", \"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\": \"854ae1c44e4f69a9cc07eb87f626c248b6113604\", \"swarm_hashes_refs/heads/master(at){#839984}_without_patch\": {\"absl_hardening_tests\": \"e9d11936c365cf0f5b108a7b7d2db2cb46ce5e7a\", \"accessibility_unittests\": \"da7c0944fe11d19d8907974af7ba78b5430ad42c\", \"angle_unittests\": \"b9d5634918b30ac6bf0ab98e5662fcd912efb0cc\", \"app_shell_unittests\": \"4d4c2e26afe21e89ae4d7dbe7bd36a92839643e2\", \"aura_unittests\": \"e20e66622a35baff291ed52919995cfedb82c48f\", \"base_unittests\": \"2472d94333eb850a5e9dc4af9771f0628cd7e490\", \"base_util_unittests\": \"495bd5cff554bf0b1aacada963d9823a1c740585\", \"blink_common_unittests\": \"ce28e165c41b75a933f2af7b797dff53f779bc9d\", \"blink_fuzzer_unittests\": \"5cbcab9793dfc09272a8709b7bee400c62375e61\", \"blink_heap_unittests\": \"18b416fd9d89353ecf50af6066baad30881f34e6\", \"blink_platform_unittests\": \"a8b15b135cb5f61bcfcc9421b10be7fce98e7c06\", \"blink_unittests\": \"b54369b0fe556604dfd952772d09f457320f9660\", \"boringssl_crypto_tests\": \"6800a3061437530f52cafffae8445d1a9aea66a8\", \"boringssl_ssl_tests\": \"a65e6f68ca4808a667cc8c9733d4704a6932c16b\", \"browser_switcher_bho_unittests\": \"d29bb6005af10740a62533c4e593e7ca292401c1\", \"browser_tests\": \"11094822bd285dce2d94d90a88a77bf54b6e8e83\", \"capture_unittests\": \"a04f8c1d707bbac4ddfd4320fa1d91292c9f823b\", \"cast_unittests\": \"0761fc35488721839348cc62fecbb51f41d4909a\", \"cc_unittests\": \"7985dcdc18ed1811fe54368405fe7e762a4e5369\", \"chrome_app_unittests\": \"35936e15d818d11c10024d1afbdfbc54c42e6077\", \"chrome_cleaner_unittests\": \"cf2100bb37fdf3dc676b648a1a8c4daeccd63ba2\", \"chrome_elf_unittests\": \"9eee5d50bd4519dc1ad5dd1e3049e1613e828e96\", \"chromedriver_unittests\": \"b1252a004e689ca6019f3db20ae78067d885f9ce\", \"components_browsertests\": \"59bacfab5845921e03c622eac68430e092682717\", \"components_unittests\": \"28359285921e206660d6449cab5e8742017d2231\", \"compositor_unittests\": \"02e6e9489a07f94181442b0d71308d1d3a9a469a\", \"content_browsertests\": \"78ebb26121cb96d849237720ff5110c1e4684718\", \"content_unittests\": \"eab42b29024e61c63c7c895aa1a065c26e7dd039\", \"courgette_unittests\": \"1e0c8df484f53f58565ce5d19689dabcf494a7e2\", \"crashpad_tests\": \"bba8543eddaad4bdf8513401b6937b6ee116f904\", \"cronet_tests\": \"1ebd9bb464173ca3a3352a4b3f930f6b8cccc024\", \"cronet_unittests\": \"d85dfa9a1ebbfcdff18307a248b571dc10ec429d\", \"crypto_unittests\": \"16109dd2c8f673af8d0d1cddf035534df499646b\", \"delayloads_unittests\": \"25bdbe7855b9080e36cda58e7f7c36aa0f52720a\", \"device_unittests\": \"80c52d06d0c5e017c321667b74bde034e5f79c38\", \"display_unittests\": \"55f16368773c2569593aa84c2996b8c42b9790fc\", \"elevation_service_unittests\": \"d1922168ab3b46b2c6bde1530ab3625646ac59bb\", \"events_unittests\": \"431022a47691564402760a182390886f66c19487\", \"extensions_browsertests\": \"4574eba4cf3b532b3aeb8d5827467018c7d78dc0\", \"extensions_unittests\": \"2682b4ab10b36bfafebb1e49a12ae1d906759424\", \"filesystem_service_unittests\": \"5fcd077a6d3ec877ba41856ca1115e545bb1b8e0\", \"gcm_unit_tests\": \"a4bed128af41cc9936c019b5e632fcea08f06afe\", \"gcp_unittests\": \"5bbe2ce71ae51f0e90a6929fe5f62bdf97d68f48\", \"gfx_unittests\": \"215f3e0c872affc803f19a965228ae17fe20e76a\", \"gin_unittests\": \"7c9255db1d28624ffd6f229eb18dde3777583657\", \"google_apis_unittests\": \"5abceae0e1a5efc879ab99c414f24149d3b9ae8d\", \"gpu_unittests\": \"0004482ef6213aa6d875f99d3d9ae7618ef1e41b\", \"gwp_asan_unittests\": \"8d34bcc4b3938135778176f1e9190fb6809f5b83\", \"headless_browsertests\": \"61c90be6384f85e981e4b71fecd54fb265010e06\", \"headless_unittests\": \"3bcc1efa12a95410ec116a243fb42e996267e8ba\", \"install_static_unittests\": \"960a38631877b9018e1ba50b526fd21fd7c2d3b7\", \"installer_util_unittests\": \"accf29a677abcf0ddf07d6f1e45d8dafdfb83984\", \"interactive_ui_tests\": \"9ccb5cf14a08310f37707eaf665f82890970a33a\", \"ipc_tests\": \"b7bf40d72aab3f20443d7a4646bdf69e5e685d2e\", \"jingle_unittests\": \"f001d1662170c246cb15c36677c5b2a82ad298d3\", \"latency_unittests\": \"be38e728591a093b55ada8b5d7b86616f57be8ea\", \"libjingle_xmpp_unittests\": \"862656bd614eb685aa1cedb8f94fd54896daa164\", \"liburlpattern_unittests\": \"325d347b65d604f900ff2d6d4d619fe2bd7473d2\", \"media_blink_unittests\": \"40cc277dd7d2d4c05ef0539ecce85fc253116bb0\", \"media_unittests\": \"10b9a9d175a8577137e13d7b13ae067dc3fd6357\", \"message_center_unittests\": \"3f88eba7ffa7415f57979245345dab245d6060fe\", \"midi_unittests\": \"68860ea5438d54fc25c25289b81c7795297244ae\", \"mojo_core_unittests\": \"e8c671589c3c8a5e070343991e1c749e8efa909b\", \"mojo_unittests\": \"15c26b707ec319fbcce6b7f0549ad09e3ff7f80c\", \"nacl_loader_unittests\": \"0e695df93063689ddd7dc53bf8d390e02a2f1fec\", \"native_theme_unittests\": \"c4a520830586b2fc4e8a7f8e42c63a4cb39be61e\", \"net_unittests\": \"81db6964d715593ba5a616f0939bf8fc872eb75c\", \"notification_helper_unittests\": \"619151242940ba79a75dda6b2dcfb9e4527976d2\", \"pdf_unittests\": \"911fd515aef26b0eaca6485ad5a469fa3fa880a1\", \"perfetto_unittests\": \"c71e3596d0011509b3801defa0647d7aea411d83\", \"ppapi_unittests\": \"29784176d525bd0ff166d95439d46ceff0f99db4\", \"printing_unittests\": \"cf67ced35da1b7df4c4dfdccb3afb7b9b152ad10\", \"remoting_unittests\": \"713dc89354029b356db333ea6cab549d5b90ddcf\", \"sbox_integration_tests\": \"318d2e17a71028e564010f2b105830696f2c53a7\", \"sbox_unittests\": \"c07ab0f97ada0b972bd4c10a0a92e2ee77e31abe\", \"sbox_validation_tests\": \"ba9523bbb4467cc570263e8ef76c6088afdc8662\", \"service_manager_unittests\": \"3c8bb449a08b0d0b0366c48656fd83a90cbfe977\", \"services_unittests\": \"dfb9a69e0f873ea11b974a136eff9a3993e89a54\", \"setup_unittests\": \"c4fe6c42468d1950b22963f147c9c78166fb4244\", \"shell_dialogs_unittests\": \"dcb8fde63212d75cd8afbbdec0ce9f183c8fc48b\", \"skia_unittests\": \"9b3423a1edaa3fddf41cdd4fcdf59a9faa08c26f\", \"snapshot_unittests\": \"bdb66ccacba0f46e5b58d445e7ded9545138526e\", \"sql_unittests\": \"d0bbacc17a08cf09bc7ed656fda84839074fe385\", \"storage_unittests\": \"bfa6dc4a953868151210858d9b1126a6e30a887e\", \"sync_integration_tests\": \"19b9a124f85100a149dc5c2ebf8eea9f1f668ce0\", \"traffic_annotation_auditor_unittests\": \"08a63655575f80c433935486b7884eaceeb824da\", \"ui_base_unittests\": \"064ce3b9f6d69120ade8112c83696b4fd288fa61\", \"ui_touch_selection_unittests\": \"b877568e5751350482126c838b53019f561d20a1\", \"unit_tests\": \"455b9eb9f3984413d6d2d8669ed7629d86c968d1\", \"updater_tests\": \"8a4235e12f0de6a4313f1da7028713527593d7c8\", \"url_unittests\": \"efa11e379ce85c066b8681e984ed17551d05ee06\", \"views_unittests\": \"90e50b1e06428e96e33676d5c072172ce46f4d05\", \"viz_unittests\": \"625da7cb77e1203e6acca242e7434468932ee6a8\", \"vr_common_unittests\": \"1f682cf9924e0e71adeff40d2fdb7ce6b58bfb68\", \"vr_pixeltests\": \"c4190b773c42ee96f170412f626dc0a37a0795b8\", \"weblayer_browsertests\": \"39b5cd2d947f039c5644f6824124aaef6e5547b0\", \"weblayer_unittests\": \"46c27643e836c4773c0ac65fc4d05c4e5caf175b\", \"wm_unittests\": \"023e91aec53ec246b30154f6a404766e39605382\", \"wtf_unittests\": \"bb4f48a17f6b1cbc32f59783305ee8cdd68012bc\", \"zlib_unittests\": \"d5df90c6dee42e9c8111b3285cd5204d00870d92\", \"zucchini_unittests\": \"3a03b86e3dc9d6c32be03bff74c1d9b2c48ccbf8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-aof9\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**net_unittests** failed because of:\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.IntermediateFromAia200Pem/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.RevocationSoftFailLeafCrlDpHttpError/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalTest.ValidityJustAfterNotBefore/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.RevocationHardFailLeafCrlDpHttpError/CertVerifyProcWin\\n\\n- ...13 more failure(s) (17 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609705169469067",
"status": "COMPLETED",
"status_changed_ts": "1609705170005604",
"tags": [
"build_address:luci.chromium.ci/win-asan/16614",
"builder:win-asan",
"buildset:commit/git/854ae1c44e4f69a9cc07eb87f626c248b6113604",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/854ae1c44e4f69a9cc07eb87f626c248b6113604",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024525831510646880",
"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/8859095769547768880/+/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:50e2b561af07c910",
"user_agent:luci-scheduler"
],
"updated_ts": "1609711539146130",
"url": "https://ci.chromium.org/b/8859095769547768880",
"utcnow_ts": "1609711539520583"
}
}
[22:05:39.529] Invocation finished in 1h50m20.578152487s with status FAILED