[14:56:46.661] New invocation is queued and will start shortly
[14:56:47.999] Starting the invocation (attempt 1)
[14:56:48.033] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:56:48.033] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:56:48.034] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026901393341576688",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5dd4678c54e781f36824a37249d2fbf7ad5c0908\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026901393341576688",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5dd4678c54e781f36824a37249d2fbf7ad5c0908",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dd4678c54e781f36824a37249d2fbf7ad5c0908",
"gitiles_ref:refs/heads/master"
]
}
[14:56:49.289] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607439408127746",
"id": "8861471331142156528",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5dd4678c54e781f36824a37249d2fbf7ad5c0908\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607439408803494",
"tags": [
"build_address:luci.chromium.ci/win-asan/16101",
"builder:win-asan",
"buildset:commit/git/5dd4678c54e781f36824a37249d2fbf7ad5c0908",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dd4678c54e781f36824a37249d2fbf7ad5c0908",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026901393341576688",
"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/8861471331142156528/+/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": "1607439408803650",
"url": "https://ci.chromium.org/b/8861471331142156528",
"utcnow_ts": "1607439408969015"
}
}
[14:56:49.289] Task URL: https://ci.chromium.org/b/8861471331142156528
[14:56:49.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:2:0) after 1m0s
[14:57:28.169] Received PubSub notification, asking Buildbucket for the build status
[14:57:28.332] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:49.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:2:0)
[14:57:49.307] Timer tick, asking Buildbucket for the build status
[14:57:49.377] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:49.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:4:0) after 1m0s
[14:58:49.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:4:0)
[14:58:49.335] Timer tick, asking Buildbucket for the build status
[14:58:49.407] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:49.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:5:0) after 1m0s
[14:59:49.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:5:0)
[14:59:49.427] Timer tick, asking Buildbucket for the build status
[14:59:49.547] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:49.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:6:0) after 1m0s
[15:00:49.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:6:0)
[15:00:49.685] Timer tick, asking Buildbucket for the build status
[15:00:49.813] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:49.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:7:0) after 1m0s
[15:01:49.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:7:0)
[15:01:49.852] Timer tick, asking Buildbucket for the build status
[15:01:51.728] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:51.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:8:0) after 1m0s
[15:02:51.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:8:0)
[15:02:51.819] Timer tick, asking Buildbucket for the build status
[15:02:51.901] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:51.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:9:0) after 1m0s
[15:03:51.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:9:0)
[15:03:51.923] Timer tick, asking Buildbucket for the build status
[15:03:51.993] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:51.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:10:0) after 1m0s
[15:04:52.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:10:0)
[15:04:52.009] Timer tick, asking Buildbucket for the build status
[15:04:52.088] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:52.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:11:0) after 1m0s
[15:05:52.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:11:0)
[15:05:52.117] Timer tick, asking Buildbucket for the build status
[15:05:52.186] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:52.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:12:0) after 1m0s
[15:06:52.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:12:0)
[15:06:52.206] Timer tick, asking Buildbucket for the build status
[15:06:52.636] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:52.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:13:0) after 1m0s
[15:07:52.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:13:0)
[15:07:52.664] Timer tick, asking Buildbucket for the build status
[15:07:52.735] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:52.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:14:0) after 1m0s
[15:08:52.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:14:0)
[15:08:52.790] Timer tick, asking Buildbucket for the build status
[15:08:52.906] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:52.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:15:0) after 1m0s
[15:09:52.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:15:0)
[15:09:52.967] Timer tick, asking Buildbucket for the build status
[15:09:53.341] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:53.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:16:0) after 1m0s
[15:10:53.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:16:0)
[15:10:53.451] Timer tick, asking Buildbucket for the build status
[15:10:53.545] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:53.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:17:0) after 1m0s
[15:11:53.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:17:0)
[15:11:53.567] Timer tick, asking Buildbucket for the build status
[15:11:53.688] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:53.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:18:0) after 1m0s
[15:12:53.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:18:0)
[15:12:53.739] Timer tick, asking Buildbucket for the build status
[15:12:53.828] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:53.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:19:0) after 1m0s
[15:13:53.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:19:0)
[15:13:53.901] Timer tick, asking Buildbucket for the build status
[15:13:53.973] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:53.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:20:0) after 1m0s
[15:14:53.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:20:0)
[15:14:53.973] Timer tick, asking Buildbucket for the build status
[15:14:54.508] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:54.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:21:0) after 1m0s
[15:15:54.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:21:0)
[15:15:54.530] Timer tick, asking Buildbucket for the build status
[15:15:54.599] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:54.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:22:0) after 1m0s
[15:16:54.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:22:0)
[15:16:54.610] Timer tick, asking Buildbucket for the build status
[15:16:54.728] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:54.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:23:0) after 1m0s
[15:17:54.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:23:0)
[15:17:54.750] Timer tick, asking Buildbucket for the build status
[15:17:54.830] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:54.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:24:0) after 1m0s
[15:18:54.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:24:0)
[15:18:54.885] Timer tick, asking Buildbucket for the build status
[15:18:54.951] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:54.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:25:0) after 1m0s
[15:19:54.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:25:0)
[15:19:54.979] Timer tick, asking Buildbucket for the build status
[15:19:55.044] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:55.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:26:0) after 1m0s
[15:20:55.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:26:0)
[15:20:55.046] Timer tick, asking Buildbucket for the build status
[15:20:55.136] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:55.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:27:0) after 1m0s
[15:21:55.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:27:0)
[15:21:55.157] Timer tick, asking Buildbucket for the build status
[15:21:55.268] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:55.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:28:0) after 1m0s
[15:22:55.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:28:0)
[15:22:55.320] Timer tick, asking Buildbucket for the build status
[15:22:55.399] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:55.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:29:0) after 1m0s
[15:23:55.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:29:0)
[15:23:55.477] Timer tick, asking Buildbucket for the build status
[15:23:55.670] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:55.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:30:0) after 1m0s
[15:24:55.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:30:0)
[15:24:55.675] Timer tick, asking Buildbucket for the build status
[15:24:55.763] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:55.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:31:0) after 1m0s
[15:25:55.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:31:0)
[15:25:55.778] Timer tick, asking Buildbucket for the build status
[15:25:56.185] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:56.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:32:0) after 1m0s
[15:26:56.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:32:0)
[15:26:56.220] Timer tick, asking Buildbucket for the build status
[15:26:56.382] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:56.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:33:0) after 1m0s
[15:27:56.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:33:0)
[15:27:56.357] Timer tick, asking Buildbucket for the build status
[15:27:56.409] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:56.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:34:0) after 1m0s
[15:28:56.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:34:0)
[15:28:56.438] Timer tick, asking Buildbucket for the build status
[15:28:56.699] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:56.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:35:0) after 1m0s
[15:29:56.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:35:0)
[15:29:56.789] Timer tick, asking Buildbucket for the build status
[15:29:57.109] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:57.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:36:0) after 1m0s
[15:30:57.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:36:0)
[15:30:57.187] Timer tick, asking Buildbucket for the build status
[15:30:57.242] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:57.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:37:0) after 1m0s
[15:31:57.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:37:0)
[15:31:57.329] Timer tick, asking Buildbucket for the build status
[15:31:57.423] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:57.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:38:0) after 1m0s
[15:32:57.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:38:0)
[15:32:57.430] Timer tick, asking Buildbucket for the build status
[15:32:57.494] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:57.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:39:0) after 1m0s
[15:33:57.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:39:0)
[15:33:57.548] Timer tick, asking Buildbucket for the build status
[15:33:57.612] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:57.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:40:0) after 1m0s
[15:34:57.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:40:0)
[15:34:57.714] Timer tick, asking Buildbucket for the build status
[15:34:57.770] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:57.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:41:0) after 1m0s
[15:35:57.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:41:0)
[15:35:57.785] Timer tick, asking Buildbucket for the build status
[15:35:57.836] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:57.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:42:0) after 1m0s
[15:36:57.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:42:0)
[15:36:57.846] Timer tick, asking Buildbucket for the build status
[15:36:57.957] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:57.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:43:0) after 1m0s
[15:37:57.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:43:0)
[15:37:57.982] Timer tick, asking Buildbucket for the build status
[15:37:58.059] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:58.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:44:0) after 1m0s
[15:38:58.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:44:0)
[15:38:58.109] Timer tick, asking Buildbucket for the build status
[15:38:58.171] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:58.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:45:0) after 1m0s
[15:39:58.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:45:0)
[15:39:58.189] Timer tick, asking Buildbucket for the build status
[15:39:58.245] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:58.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:46:0) after 1m0s
[15:40:58.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:46:0)
[15:40:58.224] Timer tick, asking Buildbucket for the build status
[15:40:58.429] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:58.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:47:0) after 1m0s
[15:41:58.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:47:0)
[15:41:58.403] Timer tick, asking Buildbucket for the build status
[15:41:58.504] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:58.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:48:0) after 1m0s
[15:42:58.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:48:0)
[15:42:58.557] Timer tick, asking Buildbucket for the build status
[15:42:58.639] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:58.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:49:0) after 1m0s
[15:43:58.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:49:0)
[15:43:58.643] Timer tick, asking Buildbucket for the build status
[15:43:58.699] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:58.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:50:0) after 1m0s
[15:44:58.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:50:0)
[15:44:58.721] Timer tick, asking Buildbucket for the build status
[15:44:58.878] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:58.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:51:0) after 1m0s
[15:45:58.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:51:0)
[15:45:58.945] Timer tick, asking Buildbucket for the build status
[15:45:59.012] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:59.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:52:0) after 1m0s
[15:46:59.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:52:0)
[15:46:59.065] Timer tick, asking Buildbucket for the build status
[15:46:59.304] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:59.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:53:0) after 1m0s
[15:47:59.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:53:0)
[15:47:59.330] Timer tick, asking Buildbucket for the build status
[15:47:59.431] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:59.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:54:0) after 1m0s
[15:48:59.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:54:0)
[15:48:59.439] Timer tick, asking Buildbucket for the build status
[15:48:59.495] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:59.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:55:0) after 1m0s
[15:49:59.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:55:0)
[15:49:59.557] Timer tick, asking Buildbucket for the build status
[15:49:59.622] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:59.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:56:0) after 1m0s
[15:50:59.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:56:0)
[15:50:59.612] Timer tick, asking Buildbucket for the build status
[15:50:59.681] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:59.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:57:0) after 1m0s
[15:51:59.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:57:0)
[15:51:59.663] Timer tick, asking Buildbucket for the build status
[15:51:59.813] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:59.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:58:0) after 1m0s
[15:52:59.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:58:0)
[15:52:59.840] Timer tick, asking Buildbucket for the build status
[15:52:59.965] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:59.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:59:0) after 1m0s
[15:54:00.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:59:0)
[15:54:00.054] Timer tick, asking Buildbucket for the build status
[15:54:00.330] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:00.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:60:0) after 1m0s
[15:55:00.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:60:0)
[15:55:00.397] Timer tick, asking Buildbucket for the build status
[15:55:00.517] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:00.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:61:0) after 1m0s
[15:56:00.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:61:0)
[15:56:00.541] Timer tick, asking Buildbucket for the build status
[15:56:00.611] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:00.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:62:0) after 1m0s
[15:57:01.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:62:0)
[15:57:01.071] Timer tick, asking Buildbucket for the build status
[15:57:01.168] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:01.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:63:0) after 1m0s
[15:58:01.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:63:0)
[15:58:01.507] Timer tick, asking Buildbucket for the build status
[15:58:01.615] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:01.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:64:0) after 1m0s
[15:59:01.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:64:0)
[15:59:01.642] Timer tick, asking Buildbucket for the build status
[15:59:01.888] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:01.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:65:0) after 1m0s
[16:00:01.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:65:0)
[16:00:01.975] Timer tick, asking Buildbucket for the build status
[16:00:02.079] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:02.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:66:0) after 1m0s
[16:01:02.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:66:0)
[16:01:02.427] Timer tick, asking Buildbucket for the build status
[16:01:02.538] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:02.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:67:0) after 1m0s
[16:02:02.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:67:0)
[16:02:02.613] Timer tick, asking Buildbucket for the build status
[16:02:03.191] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:03.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:68:0) after 1m0s
[16:03:03.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:68:0)
[16:03:03.215] Timer tick, asking Buildbucket for the build status
[16:03:03.346] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:03.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:69:0) after 1m0s
[16:04:03.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:69:0)
[16:04:03.428] Timer tick, asking Buildbucket for the build status
[16:04:03.539] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:03.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:70:0) after 1m0s
[16:05:03.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:70:0)
[16:05:03.715] Timer tick, asking Buildbucket for the build status
[16:05:03.832] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:03.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:71:0) after 1m0s
[16:06:04.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:71:0)
[16:06:04.284] Timer tick, asking Buildbucket for the build status
[16:06:04.621] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:04.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:72:0) after 1m0s
[16:07:04.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:72:0)
[16:07:04.718] Timer tick, asking Buildbucket for the build status
[16:07:04.985] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:04.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:73:0) after 1m0s
[16:08:05.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:73:0)
[16:08:05.197] Timer tick, asking Buildbucket for the build status
[16:08:05.330] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:05.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:74:0) after 1m0s
[16:09:05.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:74:0)
[16:09:05.502] Timer tick, asking Buildbucket for the build status
[16:09:05.660] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:05.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:75:0) after 1m0s
[16:10:05.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:75:0)
[16:10:05.733] Timer tick, asking Buildbucket for the build status
[16:10:05.818] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:05.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:76:0) after 1m0s
[16:11:06.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:76:0)
[16:11:06.435] Timer tick, asking Buildbucket for the build status
[16:11:06.540] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:06.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:77:0) after 1m0s
[16:12:06.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:77:0)
[16:12:06.670] Timer tick, asking Buildbucket for the build status
[16:12:06.780] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:06.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:78:0) after 1m0s
[16:13:06.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:78:0)
[16:13:06.899] Timer tick, asking Buildbucket for the build status
[16:13:07.045] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:07.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:79:0) after 1m0s
[16:14:07.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:79:0)
[16:14:07.182] Timer tick, asking Buildbucket for the build status
[16:14:08.997] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:08.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:80:0) after 1m0s
[16:15:09.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:80:0)
[16:15:09.118] Timer tick, asking Buildbucket for the build status
[16:15:09.683] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:09.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:81:0) after 1m0s
[16:16:09.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:81:0)
[16:16:09.748] Timer tick, asking Buildbucket for the build status
[16:16:09.958] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:09.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:82:0) after 1m0s
[16:17:10.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:82:0)
[16:17:10.004] Timer tick, asking Buildbucket for the build status
[16:17:10.118] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:10.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:83:0) after 1m0s
[16:18:10.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:83:0)
[16:18:10.165] Timer tick, asking Buildbucket for the build status
[16:18:10.238] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:10.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:84:0) after 1m0s
[16:19:10.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:84:0)
[16:19:10.268] Timer tick, asking Buildbucket for the build status
[16:19:10.347] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:10.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:85:0) after 1m0s
[16:20:10.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:85:0)
[16:20:10.463] Timer tick, asking Buildbucket for the build status
[16:20:10.907] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:10.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:86:0) after 1m0s
[16:21:11.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:86:0)
[16:21:11.112] Timer tick, asking Buildbucket for the build status
[16:21:11.190] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:11.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:87:0) after 1m0s
[16:22:11.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:87:0)
[16:22:11.404] Timer tick, asking Buildbucket for the build status
[16:22:12.097] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:12.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:88:0) after 1m0s
[16:23:12.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:88:0)
[16:23:12.166] Timer tick, asking Buildbucket for the build status
[16:23:12.429] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:12.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:89:0) after 1m0s
[16:24:12.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:89:0)
[16:24:12.448] Timer tick, asking Buildbucket for the build status
[16:24:12.545] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:12.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:90:0) after 1m0s
[16:25:12.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:90:0)
[16:25:12.563] Timer tick, asking Buildbucket for the build status
[16:25:12.910] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:12.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:91:0) after 1m0s
[16:26:12.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:91:0)
[16:26:12.942] Timer tick, asking Buildbucket for the build status
[16:26:13.036] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:13.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:92:0) after 1m0s
[16:27:13.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:92:0)
[16:27:13.061] Timer tick, asking Buildbucket for the build status
[16:27:13.395] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:13.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:93:0) after 1m0s
[16:28:13.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:93:0)
[16:28:13.493] Timer tick, asking Buildbucket for the build status
[16:28:13.708] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:13.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:94:0) after 1m0s
[16:29:13.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:94:0)
[16:29:13.824] Timer tick, asking Buildbucket for the build status
[16:29:14.031] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:14.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:95:0) after 1m0s
[16:30:14.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:95:0)
[16:30:14.049] Timer tick, asking Buildbucket for the build status
[16:30:14.171] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:14.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:96:0) after 1m0s
[16:31:14.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:96:0)
[16:31:14.222] Timer tick, asking Buildbucket for the build status
[16:31:14.294] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:14.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:97:0) after 1m0s
[16:32:14.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:97:0)
[16:32:14.294] Timer tick, asking Buildbucket for the build status
[16:32:14.466] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:14.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:98:0) after 1m0s
[16:33:14.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:98:0)
[16:33:14.558] Timer tick, asking Buildbucket for the build status
[16:33:14.642] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:14.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:99:0) after 1m0s
[16:34:14.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:99:0)
[16:34:14.662] Timer tick, asking Buildbucket for the build status
[16:34:15.173] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:15.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:100:0) after 1m0s
[16:35:15.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:100:0)
[16:35:15.198] Timer tick, asking Buildbucket for the build status
[16:35:15.297] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:15.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:101:0) after 1m0s
[16:36:15.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:101:0)
[16:36:15.323] Timer tick, asking Buildbucket for the build status
[16:36:15.409] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:15.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:102:0) after 1m0s
[16:37:15.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:102:0)
[16:37:15.436] Timer tick, asking Buildbucket for the build status
[16:37:15.562] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:15.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:103:0) after 1m0s
[16:38:15.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:103:0)
[16:38:15.589] Timer tick, asking Buildbucket for the build status
[16:38:15.668] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:15.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:104:0) after 1m0s
[16:39:15.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:104:0)
[16:39:15.719] Timer tick, asking Buildbucket for the build status
[16:39:15.789] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:15.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:105:0) after 1m0s
[16:40:15.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:105:0)
[16:40:15.839] Timer tick, asking Buildbucket for the build status
[16:40:15.943] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:15.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:106:0) after 1m0s
[16:41:15.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:106:0)
[16:41:15.999] Timer tick, asking Buildbucket for the build status
[16:41:16.194] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:16.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:107:0) after 1m0s
[16:42:16.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:107:0)
[16:42:16.354] Timer tick, asking Buildbucket for the build status
[16:42:16.479] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:16.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:108:0) after 1m0s
[16:43:16.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:108:0)
[16:43:16.727] Timer tick, asking Buildbucket for the build status
[16:43:16.858] Build 8861471331142156528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:16.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026901393341576688:109:0) after 1m0s
[16:44:01.462] Received PubSub notification, asking Buildbucket for the build status
[16:44:01.833] Build 8861471331142156528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:44:01.833] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607445840429488",
"created_by": "project:chromium",
"created_ts": "1607439408127746",
"id": "8861471331142156528",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5dd4678c54e781f36824a37249d2fbf7ad5c0908\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"5dd4678c54e781f36824a37249d2fbf7ad5c0908\", \"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-z6mr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16101, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"a13f6847238e7e74fcb9a81caeb810800c8a8fef\", \"got_dawn_revision\": \"61355d416d9c568bcfbed6518516fbe5ef219e42\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"5dd4678c54e781f36824a37249d2fbf7ad5c0908\", \"got_revision_cp\": \"refs/heads/master@{#834708}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"84b9bb6621c7eae8da034d31aceab780d8b1abdc\", \"got_v8_revision\": \"53a11922d89285a3f15744d59e0cfcff2dd33f91\", \"got_v8_revision_cp\": \"refs/heads/8.9.120@{#1}\", \"got_webrtc_revision\": \"bb1a28de3cf17476142e9de8ee12fe6c567d06c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32789}\", \"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\": \"5dd4678c54e781f36824a37249d2fbf7ad5c0908\", \"swarm_hashes_refs/heads/master(at){#834708}_without_patch\": {\"absl_hardening_tests\": \"2f48598522621c87886edf03118514e4833559d2\", \"accessibility_unittests\": \"bc4f8e7909019a6cdf57f9dd3339d3666725535e\", \"angle_unittests\": \"31a1e8d5527612deab5bc5df47baf21326df0017\", \"app_shell_unittests\": \"b8c93541dfc5061eca26c903007fbd52659f5f1b\", \"aura_unittests\": \"18d54960715e1e87a577eaf5b58100c084212792\", \"base_unittests\": \"21bccd50804b19261e16d8365a8dda2c0a2fe209\", \"base_util_unittests\": \"589452762fb420728f1a0dc850842c8ea8d45bdb\", \"blink_common_unittests\": \"86d2e2e37a42cbccf25ade5f70f826cb63278327\", \"blink_fuzzer_unittests\": \"00f71fb6e78072bd1a8a9fe663562bb48839717a\", \"blink_heap_unittests\": \"6b58550b0c77902fb13d8cc06d1fbf5ee085a0e6\", \"blink_platform_unittests\": \"91b4b8047b5803b53cf6c6254c8d67e3afaae971\", \"blink_unittests\": \"2223f6d5392ce4e56ee17aa1c5012c8e780655c1\", \"boringssl_crypto_tests\": \"93c1021797e45dea907be7f47ed420353fcf0278\", \"boringssl_ssl_tests\": \"dc08da3eca46433ab1978383a04323505c2f71a1\", \"browser_switcher_bho_unittests\": \"a75b90f2c894b66e990c63c7ca627e03f9de3017\", \"browser_tests\": \"aa852078b7512c64060479b35a0f525945419eeb\", \"capture_unittests\": \"6966226dc89fec008a7d8dc91e38b0f6dbe58531\", \"cast_unittests\": \"614fa92fabc862dcb7f35ca2a9cfdc6d1b9bdc67\", \"cc_unittests\": \"bd164ee5e5f99bbbcad5a9f90bb5e2fe4dc10cf1\", \"chrome_app_unittests\": \"a4cf54d08cd01d6ed667734fd4b9b5077978269c\", \"chrome_cleaner_unittests\": \"aaf6c8587c2b451b44629d6c76eb386c5c43c08c\", \"chrome_elf_unittests\": \"b809b210a42de9a835f2e45eb886321bbaf7d993\", \"chromedriver_unittests\": \"325506c9d9a54779bea01f09b51559184211a5e4\", \"components_browsertests\": \"f1c18fa60d4f1c3cfdffe6b2b08aa17d173ac607\", \"components_unittests\": \"6b50d64432f8c048b45f8f1e13ec91fb9ff004c3\", \"compositor_unittests\": \"81252affdb76159c771cec4bdf624273a28b17ef\", \"content_browsertests\": \"ecf60ac68038b945a8da435c599381f6fe5967f3\", \"content_unittests\": \"a66a1f507b3aaf5e4d3a8fea5614cfd805601c43\", \"courgette_unittests\": \"8f418bbd71cdbf2baf42c46962004f68dc715c37\", \"crashpad_tests\": \"568e1be747b46173c575cfc7a1260eee60873604\", \"cronet_tests\": \"7a8f161e6566689d7227f0220fff7ca92ca18c62\", \"cronet_unittests\": \"43126e7220b82a1ab0e184f04696b1f246dd326e\", \"crypto_unittests\": \"e71ce40856d68627c7ddfc6b8b1f759ea43965f0\", \"delayloads_unittests\": \"7d179aa64b18a7ba914038260008140a29cb619f\", \"device_unittests\": \"cc10c773fe351bc0c18433e82f8043dbb60cfb1b\", \"display_unittests\": \"13e0116745c5057eddb4fca3aca35150a59f826c\", \"elevation_service_unittests\": \"65a74fa1174aaf55bee5b782e0b386bdfae91ae8\", \"events_unittests\": \"0179f6371fb09cf20cea4591c48007779ef75d4e\", \"extensions_browsertests\": \"a1ca2148a6a7a7c788029aaaac37e83bdbc5bae8\", \"extensions_unittests\": \"e3ca8b19b2caf0ad6d18e21f069fd15bdf9defe0\", \"filesystem_service_unittests\": \"7fd836c823dd7c3edc06cb98997cb9b591f3511f\", \"gcm_unit_tests\": \"ca0223bbd7a6ad130d8e397c7a23251424ae4154\", \"gcp_unittests\": \"047fcd5aadbfc2e271658587fed5c048293c91c2\", \"gfx_unittests\": \"5223d3c0665aa03a32a8b4aa2d28e457b7b39ae9\", \"gin_unittests\": \"7c261d22e9a355c946cc0826636d8d6be33adc45\", \"google_apis_unittests\": \"822277c8459fad294d1c60fc468c271bbd407b49\", \"gpu_unittests\": \"163475dc66de0bc90f9a5b983cec629b548db563\", \"gwp_asan_unittests\": \"227915b6f90ee81dcbf226dbcc48ed0e77b39873\", \"headless_browsertests\": \"a93252701bcd0684e1dc36416a3cb244edf5864e\", \"headless_unittests\": \"3a6aa887ac8ee232fb541ccdbf7cc004978e3c8d\", \"install_static_unittests\": \"8bb8d0aae532c6d5dde1e4213a05016774f54db6\", \"installer_util_unittests\": \"76274e960dcae6b2066ee1a4eb02a9b49b253e09\", \"interactive_ui_tests\": \"af344b7ed62af0249056a4eb22d3e54545f0e13f\", \"ipc_tests\": \"e5ebe5f7419aeb9dbacfb748a368fd6f4a38cd43\", \"jingle_unittests\": \"43245ddf22acda412566251fd878b7fa92c94047\", \"latency_unittests\": \"48fa919869c725a298f546af83819686a80b2e27\", \"libjingle_xmpp_unittests\": \"b6d5c4fac8e406364dfc867be6ca29ab8a30e7c5\", \"liburlpattern_unittests\": \"fbf454f9656925a9226679522f056b020b5e7a53\", \"media_blink_unittests\": \"703c5d6a56cbcf5de48f5c36f226ffa35207c8a1\", \"media_unittests\": \"34662b24990f0352b7662959f4fa1b9048c525ee\", \"message_center_unittests\": \"ad6885748d0e4bc6147c555ac01c7dde797b02c2\", \"midi_unittests\": \"09194330081ba93fde78e53317b9dc4da276defb\", \"mojo_core_unittests\": \"b295b3e25393b6bec1df1931d997b19a19c6d1e6\", \"mojo_unittests\": \"689beb6b3bd58c029ab092055be7d340ab4b1798\", \"nacl_loader_unittests\": \"12bf7d77362dd6cf69dbe6c06832c1c7862cbab9\", \"native_theme_unittests\": \"165cafc0ee5195b58a87ed7ed5ec07029ffa8f18\", \"net_unittests\": \"80213f5e9dee5b05d256a09b8d8f485b458315de\", \"notification_helper_unittests\": \"8f6cd21d86125489b97200208f3a9b3dcd1a8af6\", \"pdf_unittests\": \"62eeaf88cca99c28ba81e854fec63f0b79e45593\", \"perfetto_unittests\": \"7941ae818e497d76045506ac9a84d62406a5b7b3\", \"ppapi_unittests\": \"a7a797a2ce2a191d70587ded35a0663124e19c12\", \"printing_unittests\": \"ab0f0579cee9f912e3112fdfb5384fd76eb2d36a\", \"remoting_unittests\": \"482e1cdb18fb95049a51fc6d1c8dbee3fd4d4d46\", \"sbox_integration_tests\": \"a9dcd9a93055c4386a50fffc87820e3ef1b008f0\", \"sbox_unittests\": \"59e6429be4189b82253dde5139efbfb1daca8cf1\", \"sbox_validation_tests\": \"81255c0458bab267d8335512835a16e9eccdebd2\", \"service_manager_unittests\": \"3749cb9ba7c3390ed47e08f85f4438529fad5766\", \"services_unittests\": \"9d360ef7a1f64f4fa4240988c8378a9dc977526f\", \"setup_unittests\": \"38cf92c3a40ed78c00b1164de900389369644970\", \"shell_dialogs_unittests\": \"6bae11b32edb54b8a3596ed66ad0624fd1687ce6\", \"skia_unittests\": \"569367c0989f4000bbe21b67167d91e5d4a04a03\", \"snapshot_unittests\": \"457e7142dd3da4f95db9edab99f37e45d8989b90\", \"sql_unittests\": \"70a62189b6cddbfebb5466d28a649707c65d9a2b\", \"storage_unittests\": \"3d8924c6c0f72864d298f946b7f7295d3c9084da\", \"sync_integration_tests\": \"7b7e6c6301b6e10c13cdb8e974518f6a13734e43\", \"traffic_annotation_auditor_unittests\": \"4f44dd6d6a32a9a2f276223857614bce475bfda5\", \"ui_base_unittests\": \"d5e44c838fc49ab76c0a2a985c3cc85cea8a3f41\", \"ui_touch_selection_unittests\": \"06185c1401beb560982833d898caf25b375677f5\", \"unit_tests\": \"f3447212ee355d19bfd793ed0d9fb6bc80aad8dd\", \"updater_tests\": \"ba1b57f9c23106f2beab3d1f1ef8eb8d3ffb50ba\", \"url_unittests\": \"b612bb8cfefe3f0290287dd5f85c340bb6fd5bf2\", \"views_unittests\": \"92fdf02e3c59050ff2eebc445a1274e9d799056f\", \"viz_unittests\": \"b2ec565e0be4fdd38cc28cb453bfe96651d219ca\", \"vr_common_unittests\": \"1150688d3f4b119bc87cceb61670e4ef0e2a5b52\", \"vr_pixeltests\": \"6873e8732b2bf3658259556a2048513aae8eaa40\", \"weblayer_browsertests\": \"99fce77f7f2b1c46b76794d2a9e9d08da07002e1\", \"weblayer_unittests\": \"4b6a7bdf4cdc311c8249035008e595e73a695890\", \"wm_unittests\": \"c7fabb387a2a152c100dac947a996f2dbe74bbc1\", \"wtf_unittests\": \"489b84b158127dadba650ba1740102230a01d8b4\", \"zlib_unittests\": \"2b1a6aa0dd79a978368cfbb9c045d29615bb49c9\", \"zucchini_unittests\": \"637e98e80f9e718a18d0435875e94b20a681d5e8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-z6mr\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607439447468474",
"status": "COMPLETED",
"status_changed_ts": "1607445840736354",
"tags": [
"build_address:luci.chromium.ci/win-asan/16101",
"builder:win-asan",
"buildset:commit/git/5dd4678c54e781f36824a37249d2fbf7ad5c0908",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dd4678c54e781f36824a37249d2fbf7ad5c0908",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026901393341576688",
"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/8861471331142156528/+/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:505bac684fc4ef10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607445840744093",
"url": "https://ci.chromium.org/b/8861471331142156528",
"utcnow_ts": "1607445841813448"
}
}
[16:44:01.833] Invocation finished in 1h47m15.187209732s with status SUCCEEDED