[02:26:13.440] New invocation is queued and will start shortly
[02:26:14.913] Starting the invocation (attempt 1)
[02:26:14.980] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:26:14.980] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:26:14.980] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023596526341048816",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"68e87810edf1578e41f4899631aa75af9233777b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023596526341048816",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/68e87810edf1578e41f4899631aa75af9233777b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68e87810edf1578e41f4899631aa75af9233777b",
"gitiles_ref:refs/heads/master"
]
}
[02:26:15.544] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610591175138863",
"id": "8858166463896493936",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"68e87810edf1578e41f4899631aa75af9233777b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610591175404886",
"tags": [
"build_address:luci.chromium.ci/win-asan/16807",
"builder:win-asan",
"buildset:commit/git/68e87810edf1578e41f4899631aa75af9233777b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68e87810edf1578e41f4899631aa75af9233777b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023596526341048816",
"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/8858166463896493936/+/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": "1610591175404986",
"url": "https://ci.chromium.org/b/8858166463896493936",
"utcnow_ts": "1610591175536861"
}
}
[02:26:15.544] Task URL: https://ci.chromium.org/b/8858166463896493936
[02:26:15.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:2:0) after 1m0s
[02:26:19.194] Received PubSub notification, asking Buildbucket for the build status
[02:26:19.465] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:15.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:2:0)
[02:27:15.618] Timer tick, asking Buildbucket for the build status
[02:27:15.715] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:15.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:4:0) after 1m0s
[02:28:15.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:4:0)
[02:28:15.755] Timer tick, asking Buildbucket for the build status
[02:28:15.959] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:15.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:5:0) after 1m0s
[02:29:16.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:5:0)
[02:29:16.037] Timer tick, asking Buildbucket for the build status
[02:29:16.343] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:16.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:6:0) after 1m0s
[02:30:16.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:6:0)
[02:30:16.336] Timer tick, asking Buildbucket for the build status
[02:30:16.516] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:16.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:7:0) after 1m0s
[02:31:16.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:7:0)
[02:31:16.603] Timer tick, asking Buildbucket for the build status
[02:31:16.724] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:16.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:8:0) after 1m0s
[02:32:16.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:8:0)
[02:32:16.781] Timer tick, asking Buildbucket for the build status
[02:32:16.921] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:16.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:9:0) after 1m0s
[02:33:16.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:9:0)
[02:33:16.924] Timer tick, asking Buildbucket for the build status
[02:33:17.064] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:17.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:10:0) after 1m0s
[02:34:17.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:10:0)
[02:34:17.117] Timer tick, asking Buildbucket for the build status
[02:34:17.221] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:17.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:11:0) after 1m0s
[02:35:17.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:11:0)
[02:35:17.304] Timer tick, asking Buildbucket for the build status
[02:35:17.364] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:17.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:12:0) after 1m0s
[02:36:17.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:12:0)
[02:36:17.492] Timer tick, asking Buildbucket for the build status
[02:36:17.905] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:17.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:13:0) after 1m0s
[02:37:17.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:13:0)
[02:37:17.957] Timer tick, asking Buildbucket for the build status
[02:37:18.034] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:18.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:14:0) after 1m0s
[02:38:18.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:14:0)
[02:38:18.110] Timer tick, asking Buildbucket for the build status
[02:38:18.308] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:18.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:15:0) after 1m0s
[02:39:18.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:15:0)
[02:39:18.345] Timer tick, asking Buildbucket for the build status
[02:39:18.411] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:18.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:16:0) after 1m0s
[02:40:18.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:16:0)
[02:40:18.503] Timer tick, asking Buildbucket for the build status
[02:40:18.628] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:18.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:17:0) after 1m0s
[02:41:18.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:17:0)
[02:41:18.662] Timer tick, asking Buildbucket for the build status
[02:41:18.908] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:18.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:18:0) after 1m0s
[02:42:19.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:18:0)
[02:42:19.069] Timer tick, asking Buildbucket for the build status
[02:42:22.072] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:22.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:19:0) after 1m0s
[02:43:22.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:19:0)
[02:43:22.070] Timer tick, asking Buildbucket for the build status
[02:43:22.242] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:22.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:20:0) after 1m0s
[02:44:22.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:20:0)
[02:44:22.249] Timer tick, asking Buildbucket for the build status
[02:44:22.383] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:22.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:21:0) after 1m0s
[02:45:22.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:21:0)
[02:45:22.421] Timer tick, asking Buildbucket for the build status
[02:45:22.514] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:22.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:22:0) after 1m0s
[02:46:22.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:22:0)
[02:46:22.624] Timer tick, asking Buildbucket for the build status
[02:46:22.832] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:22.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:23:0) after 1m0s
[02:47:22.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:23:0)
[02:47:22.853] Timer tick, asking Buildbucket for the build status
[02:47:22.933] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:22.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:24:0) after 1m0s
[02:48:22.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:24:0)
[02:48:22.983] Timer tick, asking Buildbucket for the build status
[02:48:23.302] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:23.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:25:0) after 1m0s
[02:49:23.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:25:0)
[02:49:23.312] Timer tick, asking Buildbucket for the build status
[02:49:23.373] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:23.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:26:0) after 1m0s
[02:50:23.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:26:0)
[02:50:23.410] Timer tick, asking Buildbucket for the build status
[02:50:23.837] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:23.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:27:0) after 1m0s
[02:51:23.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:27:0)
[02:51:23.851] Timer tick, asking Buildbucket for the build status
[02:51:23.941] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:23.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:28:0) after 1m0s
[02:52:24.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:28:0)
[02:52:24.003] Timer tick, asking Buildbucket for the build status
[02:52:24.196] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:24.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:29:0) after 1m0s
[02:53:24.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:29:0)
[02:53:24.282] Timer tick, asking Buildbucket for the build status
[02:53:24.339] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:24.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:30:0) after 1m0s
[02:54:24.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:30:0)
[02:54:24.393] Timer tick, asking Buildbucket for the build status
[02:54:25.143] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:25.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:31:0) after 1m0s
[02:55:25.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:31:0)
[02:55:25.179] Timer tick, asking Buildbucket for the build status
[02:55:25.229] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:25.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:32:0) after 1m0s
[02:56:25.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:32:0)
[02:56:25.275] Timer tick, asking Buildbucket for the build status
[02:56:25.382] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:25.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:33:0) after 1m0s
[02:57:25.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:33:0)
[02:57:25.467] Timer tick, asking Buildbucket for the build status
[02:57:25.582] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:25.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:34:0) after 1m0s
[02:58:25.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:34:0)
[02:58:25.630] Timer tick, asking Buildbucket for the build status
[02:58:26.212] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:26.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:35:0) after 1m0s
[02:59:26.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:35:0)
[02:59:26.315] Timer tick, asking Buildbucket for the build status
[02:59:26.443] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:26.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:36:0) after 1m0s
[03:00:26.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:36:0)
[03:00:26.508] Timer tick, asking Buildbucket for the build status
[03:00:26.602] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:26.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:37:0) after 1m0s
[03:01:26.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:37:0)
[03:01:26.649] Timer tick, asking Buildbucket for the build status
[03:01:26.784] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:26.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:38:0) after 1m0s
[03:02:26.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:38:0)
[03:02:26.903] Timer tick, asking Buildbucket for the build status
[03:02:27.011] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:27.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:39:0) after 1m0s
[03:03:27.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:39:0)
[03:03:27.061] Timer tick, asking Buildbucket for the build status
[03:03:27.161] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:27.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:40:0) after 1m0s
[03:04:27.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:40:0)
[03:04:27.257] Timer tick, asking Buildbucket for the build status
[03:04:27.321] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:27.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:41:0) after 1m0s
[03:05:27.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:41:0)
[03:05:27.317] Timer tick, asking Buildbucket for the build status
[03:05:27.376] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:27.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:42:0) after 1m0s
[03:06:27.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:42:0)
[03:06:27.555] Timer tick, asking Buildbucket for the build status
[03:06:27.668] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:27.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:43:0) after 1m0s
[03:07:27.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:43:0)
[03:07:27.899] Timer tick, asking Buildbucket for the build status
[03:07:28.205] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:28.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:44:0) after 1m0s
[03:08:28.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:44:0)
[03:08:28.398] Timer tick, asking Buildbucket for the build status
[03:08:28.585] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:28.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:45:0) after 1m0s
[03:09:28.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:45:0)
[03:09:28.643] Timer tick, asking Buildbucket for the build status
[03:09:28.792] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:28.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:46:0) after 1m0s
[03:10:28.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:46:0)
[03:10:28.821] Timer tick, asking Buildbucket for the build status
[03:10:29.103] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:29.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:47:0) after 1m0s
[03:11:29.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:47:0)
[03:11:29.157] Timer tick, asking Buildbucket for the build status
[03:11:29.581] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:29.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:48:0) after 1m0s
[03:12:29.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:48:0)
[03:12:29.678] Timer tick, asking Buildbucket for the build status
[03:12:29.749] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:29.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:49:0) after 1m0s
[03:13:29.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:49:0)
[03:13:29.794] Timer tick, asking Buildbucket for the build status
[03:13:29.862] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:29.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:50:0) after 1m0s
[03:14:29.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:50:0)
[03:14:29.907] Timer tick, asking Buildbucket for the build status
[03:14:30.086] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:30.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:51:0) after 1m0s
[03:15:30.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:51:0)
[03:15:30.112] Timer tick, asking Buildbucket for the build status
[03:15:30.360] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:30.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:52:0) after 1m0s
[03:16:30.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:52:0)
[03:16:30.434] Timer tick, asking Buildbucket for the build status
[03:16:30.622] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:30.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:53:0) after 1m0s
[03:17:30.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:53:0)
[03:17:30.644] Timer tick, asking Buildbucket for the build status
[03:17:30.898] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:30.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:54:0) after 1m0s
[03:18:30.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:54:0)
[03:18:30.999] Timer tick, asking Buildbucket for the build status
[03:18:31.082] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:31.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:55:0) after 1m0s
[03:19:31.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:55:0)
[03:19:31.175] Timer tick, asking Buildbucket for the build status
[03:19:31.241] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:31.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:56:0) after 1m0s
[03:20:31.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:56:0)
[03:20:31.454] Timer tick, asking Buildbucket for the build status
[03:20:31.533] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:31.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:57:0) after 1m0s
[03:21:31.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:57:0)
[03:21:31.547] Timer tick, asking Buildbucket for the build status
[03:21:31.646] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:31.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:58:0) after 1m0s
[03:22:31.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:58:0)
[03:22:31.715] Timer tick, asking Buildbucket for the build status
[03:22:31.800] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:31.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:59:0) after 1m0s
[03:23:31.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:59:0)
[03:23:31.875] Timer tick, asking Buildbucket for the build status
[03:23:31.936] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:31.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:60:0) after 1m0s
[03:24:32.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:60:0)
[03:24:32.014] Timer tick, asking Buildbucket for the build status
[03:24:32.121] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:32.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:61:0) after 1m0s
[03:25:32.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:61:0)
[03:25:32.302] Timer tick, asking Buildbucket for the build status
[03:25:32.444] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:32.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:62:0) after 1m0s
[03:26:32.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:62:0)
[03:26:32.576] Timer tick, asking Buildbucket for the build status
[03:26:32.679] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:32.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:63:0) after 1m0s
[03:27:32.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:63:0)
[03:27:32.702] Timer tick, asking Buildbucket for the build status
[03:27:33.026] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:33.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:64:0) after 1m0s
[03:28:33.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:64:0)
[03:28:33.078] Timer tick, asking Buildbucket for the build status
[03:28:33.223] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:33.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:65:0) after 1m0s
[03:29:33.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:65:0)
[03:29:33.273] Timer tick, asking Buildbucket for the build status
[03:29:33.372] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:33.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:66:0) after 1m0s
[03:30:33.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:66:0)
[03:30:33.360] Timer tick, asking Buildbucket for the build status
[03:30:33.413] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:33.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:67:0) after 1m0s
[03:31:33.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:67:0)
[03:31:33.586] Timer tick, asking Buildbucket for the build status
[03:31:33.727] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:33.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:68:0) after 1m0s
[03:32:33.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:68:0)
[03:32:33.811] Timer tick, asking Buildbucket for the build status
[03:32:33.948] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:33.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:69:0) after 1m0s
[03:33:34.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:69:0)
[03:33:34.121] Timer tick, asking Buildbucket for the build status
[03:33:34.241] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:34.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:70:0) after 1m0s
[03:34:34.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:70:0)
[03:34:34.289] Timer tick, asking Buildbucket for the build status
[03:34:34.391] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:34.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:71:0) after 1m0s
[03:35:34.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:71:0)
[03:35:34.438] Timer tick, asking Buildbucket for the build status
[03:35:34.669] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:34.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:72:0) after 1m0s
[03:36:34.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:72:0)
[03:36:34.693] Timer tick, asking Buildbucket for the build status
[03:36:34.806] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:34.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:73:0) after 1m0s
[03:37:34.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:73:0)
[03:37:34.834] Timer tick, asking Buildbucket for the build status
[03:37:35.147] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:35.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:74:0) after 1m0s
[03:38:35.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:74:0)
[03:38:35.170] Timer tick, asking Buildbucket for the build status
[03:38:35.312] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:35.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:75:0) after 1m0s
[03:39:35.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:75:0)
[03:39:35.351] Timer tick, asking Buildbucket for the build status
[03:39:35.462] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:35.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:76:0) after 1m0s
[03:40:35.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:76:0)
[03:40:35.484] Timer tick, asking Buildbucket for the build status
[03:40:35.709] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:35.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:77:0) after 1m0s
[03:41:35.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:77:0)
[03:41:35.729] Timer tick, asking Buildbucket for the build status
[03:41:35.833] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:35.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:78:0) after 1m0s
[03:42:35.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:78:0)
[03:42:35.886] Timer tick, asking Buildbucket for the build status
[03:42:35.964] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:35.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:79:0) after 1m0s
[03:43:36.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:79:0)
[03:43:36.078] Timer tick, asking Buildbucket for the build status
[03:43:36.170] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:36.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:80:0) after 1m0s
[03:44:36.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:80:0)
[03:44:36.217] Timer tick, asking Buildbucket for the build status
[03:44:36.288] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:36.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:81:0) after 1m0s
[03:45:36.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:81:0)
[03:45:36.294] Timer tick, asking Buildbucket for the build status
[03:45:36.453] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:36.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:82:0) after 1m0s
[03:46:36.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:82:0)
[03:46:36.614] Timer tick, asking Buildbucket for the build status
[03:46:36.756] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:36.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:83:0) after 1m0s
[03:47:36.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:83:0)
[03:47:36.789] Timer tick, asking Buildbucket for the build status
[03:47:37.169] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:37.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:84:0) after 1m0s
[03:48:37.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:84:0)
[03:48:37.168] Timer tick, asking Buildbucket for the build status
[03:48:37.311] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:37.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:85:0) after 1m0s
[03:49:37.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:85:0)
[03:49:37.351] Timer tick, asking Buildbucket for the build status
[03:49:37.669] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:37.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:86:0) after 1m0s
[03:50:37.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:86:0)
[03:50:37.710] Timer tick, asking Buildbucket for the build status
[03:50:37.800] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:37.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:87:0) after 1m0s
[03:51:37.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:87:0)
[03:51:37.825] Timer tick, asking Buildbucket for the build status
[03:51:37.972] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:37.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:88:0) after 1m0s
[03:52:37.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:88:0)
[03:52:37.989] Timer tick, asking Buildbucket for the build status
[03:52:38.501] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:38.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:89:0) after 1m0s
[03:53:38.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:89:0)
[03:53:38.552] Timer tick, asking Buildbucket for the build status
[03:53:38.652] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:38.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:90:0) after 1m0s
[03:54:38.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:90:0)
[03:54:38.681] Timer tick, asking Buildbucket for the build status
[03:54:38.803] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:38.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:91:0) after 1m0s
[03:55:38.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:91:0)
[03:55:38.829] Timer tick, asking Buildbucket for the build status
[03:55:39.461] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:39.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:92:0) after 1m0s
[03:56:39.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:92:0)
[03:56:39.533] Timer tick, asking Buildbucket for the build status
[03:56:39.658] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:39.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:93:0) after 1m0s
[03:57:39.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:93:0)
[03:57:39.687] Timer tick, asking Buildbucket for the build status
[03:57:39.849] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:39.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:94:0) after 1m0s
[03:58:39.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:94:0)
[03:58:39.877] Timer tick, asking Buildbucket for the build status
[03:58:39.990] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:39.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:95:0) after 1m0s
[03:59:40.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:95:0)
[03:59:40.072] Timer tick, asking Buildbucket for the build status
[03:59:40.254] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:40.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:96:0) after 1m0s
[04:00:40.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:96:0)
[04:00:40.317] Timer tick, asking Buildbucket for the build status
[04:00:40.561] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:40.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:97:0) after 1m0s
[04:01:40.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:97:0)
[04:01:40.603] Timer tick, asking Buildbucket for the build status
[04:01:41.351] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:41.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:98:0) after 1m0s
[04:02:41.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:98:0)
[04:02:41.478] Timer tick, asking Buildbucket for the build status
[04:02:41.704] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:41.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:99:0) after 1m0s
[04:03:41.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:99:0)
[04:03:41.747] Timer tick, asking Buildbucket for the build status
[04:03:42.029] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:42.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:100:0) after 1m0s
[04:04:42.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:100:0)
[04:04:42.088] Timer tick, asking Buildbucket for the build status
[04:04:42.490] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:42.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:101:0) after 1m0s
[04:05:42.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:101:0)
[04:05:42.550] Timer tick, asking Buildbucket for the build status
[04:05:42.637] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:42.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:102:0) after 1m0s
[04:06:42.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:102:0)
[04:06:42.671] Timer tick, asking Buildbucket for the build status
[04:06:42.794] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:42.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:103:0) after 1m0s
[04:07:42.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:103:0)
[04:07:42.852] Timer tick, asking Buildbucket for the build status
[04:07:43.211] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:43.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:104:0) after 1m0s
[04:08:43.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:104:0)
[04:08:43.337] Timer tick, asking Buildbucket for the build status
[04:08:43.415] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:43.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:105:0) after 1m0s
[04:09:43.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:105:0)
[04:09:43.500] Timer tick, asking Buildbucket for the build status
[04:09:43.586] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:43.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:106:0) after 1m0s
[04:10:43.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:106:0)
[04:10:43.667] Timer tick, asking Buildbucket for the build status
[04:10:43.738] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:43.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:107:0) after 1m0s
[04:11:43.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:107:0)
[04:11:43.728] Timer tick, asking Buildbucket for the build status
[04:11:43.970] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:43.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:108:0) after 1m0s
[04:12:43.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:108:0)
[04:12:43.996] Timer tick, asking Buildbucket for the build status
[04:12:44.271] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:44.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:109:0) after 1m0s
[04:13:44.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:109:0)
[04:13:44.319] Timer tick, asking Buildbucket for the build status
[04:13:44.790] Build 8858166463896493936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:44.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023596526341048816:110:0) after 1m0s
[04:14:05.729] Received PubSub notification, asking Buildbucket for the build status
[04:14:05.838] Build 8858166463896493936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:14:05.838] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610597644688808",
"created_by": "project:chromium",
"created_ts": "1610591175138863",
"id": "8858166463896493936",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"68e87810edf1578e41f4899631aa75af9233777b\"}}",
"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\": \"68e87810edf1578e41f4899631aa75af9233777b\", \"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-3-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16807, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"6c79df687e098972d3fcc861f5231f8f582379b8\", \"got_dawn_revision\": \"8ba0a01d1e59d21d7db6ea4396a0f951fff3c2d0\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"68e87810edf1578e41f4899631aa75af9233777b\", \"got_revision_cp\": \"refs/heads/master@{#843335}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"c4a1bd4553ff61f771ce240a2b7033a02d4e5325\", \"got_v8_revision\": \"8b42c4e7175e5d28c57d72dade99c52a144bec1f\", \"got_v8_revision_cp\": \"refs/heads/8.9.270@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"68e87810edf1578e41f4899631aa75af9233777b\", \"swarm_hashes_refs/heads/master(at){#843335}_without_patch\": {\"absl_hardening_tests\": \"16fd54e01c5e096901994fdff89723e20b1a697b\", \"accessibility_unittests\": \"0a4433c19ef8f6146359b8bafc20f50cb987ac52\", \"angle_unittests\": \"3f33c70121a123c08c085594b93f381e698d3fb1\", \"app_shell_unittests\": \"059ff8d16ba1423cc7f1091ca52346e56fa109b8\", \"aura_unittests\": \"e40788d5c9ef13c18ceb67a62f787f5a4b2a3c1f\", \"base_unittests\": \"8b475be59b4a1e6de91e20a01e2d7ff1d12042d0\", \"base_util_unittests\": \"811c2e811030569c2579db7b08d99825472efc0f\", \"blink_common_unittests\": \"bb212823ca8a91abce90df3446e7b8d2aa22045c\", \"blink_fuzzer_unittests\": \"e5301cf022d83428c832b5b9fbace3fdc42f7c1f\", \"blink_heap_unittests\": \"6ddfdc83e5dcbdc37042ac17d13bdb46cd9fad71\", \"blink_platform_unittests\": \"175af0ae058ac159a0c6ed50e1ea3d9fd54dc2e0\", \"blink_unittests\": \"f2398a3ca167992c80a32725edd71969d376d856\", \"boringssl_crypto_tests\": \"d208477321d6630f32bbeb087985d939ca47ea01\", \"boringssl_ssl_tests\": \"5b4d99efa7c52e17265ac7dfe034dfb6c88e822d\", \"browser_switcher_bho_unittests\": \"a9d2308bfe42bd111a45855635fb2890f7b713db\", \"browser_tests\": \"b31854b0bf87cf7e61f498999845bda7abbc7628\", \"capture_unittests\": \"a1f5b3b039cedc3b7885a9d6f9554802e185c21d\", \"cast_unittests\": \"c38d42538f1dd5b4a2afd0d3d0aa2d28e199bcd6\", \"cc_unittests\": \"0d9482ee2b17733c0c932b0a614b61db9300f031\", \"chrome_app_unittests\": \"9d1ce286311facf8e67596dcf76b4afd56956aba\", \"chrome_cleaner_unittests\": \"6d9cd2ba89e6e9ae4f9d5226ab7c0399a4248902\", \"chrome_elf_unittests\": \"36aa308fb1d55d8ea8d1c0c14e275e1bcf655fd9\", \"chromedriver_unittests\": \"d89d67c507d0b512f8aff41f55647aa67a31169f\", \"components_browsertests\": \"518d3d7a35d98e3ba3dabac024caf3fe8dcb3cc8\", \"components_unittests\": \"bed6c5fc52c8e0c67b59e0d6a2733fcb9b4e0871\", \"compositor_unittests\": \"5cdd7b1cbb86cf6fcf08e372a40b790fe2453132\", \"content_browsertests\": \"a7fc9beae7dd3dc4ef26d45b7747d792b20792ae\", \"content_unittests\": \"a5af0a60058a9a34ec26c811cb3d32cb2f1c3660\", \"courgette_unittests\": \"52ed5c5b9d582aa1cce41f18f1b088256541712e\", \"crashpad_tests\": \"609c92224d59df347b1827d1fce5d01cb5eb6195\", \"cronet_tests\": \"af69dd3209b1baf6dcbe37d2328b2b4ff02b9209\", \"cronet_unittests\": \"c25f76a79bdb9cede18f9dbfd2466ccae9c48fe1\", \"crypto_unittests\": \"5a7c653b5d5b5458d1a31589b39de06d0af5fb2e\", \"delayloads_unittests\": \"347b266595aa2888ad8ba36dd0eef6a3bf91c1f5\", \"device_unittests\": \"0d8e3131f46648a243dca887c37a4703cc678a5f\", \"display_unittests\": \"7f822ef8a31e965778d6cfddddcb86a2799e891d\", \"elevation_service_unittests\": \"02a907faebd447a5b2321f990c003014dad0bddd\", \"events_unittests\": \"d65f264286ad665b252910e9bd68985dd993656b\", \"extensions_browsertests\": \"27ae011264c259bca4e28f2db6289255d271e20d\", \"extensions_unittests\": \"86805bf54eff39afaddff9a599fb9b7474e6b57d\", \"filesystem_service_unittests\": \"fc3b9f227e21eddc497fe438898201d40a2e8687\", \"gcm_unit_tests\": \"ec78e64d39f4dc6a43a63103a178a66462362d15\", \"gcp_unittests\": \"53b5b9f70cd01b23bcd1d493ece81fe69b0888a5\", \"gfx_unittests\": \"7bd392c0f53783b66d1d42e710cbcea8d617b878\", \"gin_unittests\": \"03942c84fbb42a7d962c79276b3dd03a70e663c4\", \"google_apis_unittests\": \"f389c2d3e80342cec92a07f6ddf1e2eb898f3be5\", \"gpu_unittests\": \"e3e95d2e9f26d1e58e91f25156eebcb36b0978e0\", \"gwp_asan_unittests\": \"00601971ea9c90f29e0a7b7527577515d51dfd34\", \"headless_browsertests\": \"110bf831ec9686d517090db6178bdaac4e18f354\", \"headless_unittests\": \"350a9ee3e97e6bc0ca61d8dde755a5089aec011a\", \"install_static_unittests\": \"dcb7706af8b9f58b7398d6b4e234835febd2ea06\", \"installer_util_unittests\": \"82d10c65726c24394fdc8a8c49d48d9871f42908\", \"interactive_ui_tests\": \"c11f4a7a78adced24ecd4a6a38eee6706449236a\", \"ipc_tests\": \"9ee35e9a67875e08d431dbc1bcedeb1435b5c582\", \"jingle_unittests\": \"026259f8d978ef82692dd09a7954e94021c1d40b\", \"latency_unittests\": \"b486f83cd528cdbe14ed44ae3524e34c7631e14d\", \"libjingle_xmpp_unittests\": \"8cd92186322cc39b23ff847da3c1c8aa75c3fcd3\", \"liburlpattern_unittests\": \"e7a063d2c74891ea53e4d300ff7c6919835d1c07\", \"media_blink_unittests\": \"0cb7d6579413c6861684f57fc665984a7ef4e4ff\", \"media_unittests\": \"26de01d644b288caac4da30afb2e5ee6fff1f80e\", \"message_center_unittests\": \"e4d6c8fcc1ca5a3f13dc02e1301d00c02e85c8f2\", \"midi_unittests\": \"dd9eb565b92f53db9811d58a97bb19a653cb9d9f\", \"mojo_core_unittests\": \"c92c8ae323b16f3569faa3f39df8a80c07fb5e39\", \"mojo_unittests\": \"5432c2bcdede4936a52395d14b567d4498f3bc7d\", \"nacl_loader_unittests\": \"6e4833ff76da8cc2bf5c0924b74fa0e344c2796c\", \"native_theme_unittests\": \"e5637cba14e98174325559f3e685a4d34fad5450\", \"net_unittests\": \"ada747acf00bf98873ec6194583120f6c0706317\", \"notification_helper_unittests\": \"d01df16243ec34ac18be3e4fbeb2996736f4c450\", \"pdf_unittests\": \"a23a59e00d98cfff08b45771097f83b30febbde9\", \"perfetto_unittests\": \"efea64bf1106c5835880f2918b37f754d7f7dc7f\", \"ppapi_unittests\": \"c8fbaf40d0fad83658a2ec26c14b9a1d695f4835\", \"printing_unittests\": \"4de470a1338350e3288f9aa84e22fc68f9beea6d\", \"remoting_unittests\": \"2b73743fd5fabc956b03b12fc06316e43fcf7ce0\", \"sbox_integration_tests\": \"d0169e890d8f6413fdc31e480e8bad0aa6e89262\", \"sbox_unittests\": \"86167eeda93714741ee89cc0922f71137a4ff1c3\", \"sbox_validation_tests\": \"eec0db1915bebaea3489cbdce4c8346a2fa18b37\", \"service_manager_unittests\": \"d537dfa7cab38f3fccd23dba6408230ea8514312\", \"services_unittests\": \"e1fb6c39cf7f8a7894ef2e6886021a4c43e3f4c0\", \"setup_unittests\": \"21fe5995bd0ba87b3f03c8270081bd968842456a\", \"shell_dialogs_unittests\": \"f214862f56f68b25c62c7c992af23ccfb82b385e\", \"skia_unittests\": \"8a8287080a2bfc9ae8cd77d5b007d70aea4af370\", \"snapshot_unittests\": \"5e9d301a0bc13a508aba928ba8e89173f92a0962\", \"sql_unittests\": \"5c33ea531bb0c7adf6ec2469b24a41e28517f547\", \"storage_unittests\": \"8066e7800e9c2e3913c98ce791beacfebda10eff\", \"sync_integration_tests\": \"7a4b6441894ce1adb2aeb8f30e836cc6fdf93846\", \"traffic_annotation_auditor_unittests\": \"ba02703fcacbf4c5d6d049bf4a06c1737018ea37\", \"ui_base_unittests\": \"394a04ae74dbc2af40287a6a4651a9e1b172f18c\", \"ui_touch_selection_unittests\": \"570647c4541dc84ba6c4cbd2244b48385c30cc05\", \"unit_tests\": \"ac12691e0cbe250e1188881caf352d926635fe20\", \"updater_tests\": \"fe5975291053c5973b1a028f47491e4b959b2f17\", \"url_unittests\": \"d184abe2b5e2fb242074fb1b8413ab1801029291\", \"views_unittests\": \"f0f1bc3e53f639081d63b734427b4f4b3b530f8d\", \"viz_unittests\": \"f6cbb4fce239807c345c2585403063b6eb5c587a\", \"vr_common_unittests\": \"3ecacfcd11666c222e74ed783ca06608aadcc384\", \"vr_pixeltests\": \"2f59848d1c2fdf5fb0b778ec14ff633c361354dc\", \"weblayer_browsertests\": \"5c646aa549b0c719942e667f8cfb328b4601d015\", \"weblayer_unittests\": \"0098e18aae81072b5f262ba64bd39aa1392509a1\", \"wm_unittests\": \"5343c4a681da97ec474eb283c565701917654486\", \"wtf_unittests\": \"879ff841b9b1ec31332426d4759c645230333ae8\", \"zlib_unittests\": \"5178692419ad6a6e75ce8f10ceb26c492cbd9525\", \"zucchini_unittests\": \"3575a327e814dae9134d8a3a6c384abfdac9444d\"}}, \"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-3-nb2x\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5661-c6f6250\"], \"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": "1610591177811617",
"status": "COMPLETED",
"status_changed_ts": "1610597645194941",
"tags": [
"build_address:luci.chromium.ci/win-asan/16807",
"builder:win-asan",
"buildset:commit/git/68e87810edf1578e41f4899631aa75af9233777b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68e87810edf1578e41f4899631aa75af9233777b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023596526341048816",
"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/8858166463896493936/+/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:5117888c29ee5610",
"user_agent:luci-scheduler"
],
"updated_ts": "1610597645198973",
"url": "https://ci.chromium.org/b/8858166463896493936",
"utcnow_ts": "1610597645827124"
}
}
[04:14:05.838] Invocation finished in 1h47m52.415076019s with status SUCCEEDED