[04:19:17.227] New invocation is queued and will start shortly
[04:19:18.343] Starting the invocation (attempt 1)
[04:19:18.382] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:19:18.383] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:19:18.383] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027938067410865728",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1a8306e9a6584285499c32ddd8112749a15783f3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027938067410865728",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1a8306e9a6584285499c32ddd8112749a15783f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a8306e9a6584285499c32ddd8112749a15783f3",
"gitiles_ref:refs/heads/master"
]
}
[04:19:19.873] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606450758505370",
"id": "8862508005408571296",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a8306e9a6584285499c32ddd8112749a15783f3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606450759668094",
"tags": [
"build_address:luci.chromium.ci/win-asan/15877",
"builder:win-asan",
"buildset:commit/git/1a8306e9a6584285499c32ddd8112749a15783f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a8306e9a6584285499c32ddd8112749a15783f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027938067410865728",
"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/8862508005408571296/+/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": "1606450759668201",
"url": "https://ci.chromium.org/b/8862508005408571296",
"utcnow_ts": "1606450759859624"
}
}
[04:19:19.873] Task URL: https://ci.chromium.org/b/8862508005408571296
[04:19:19.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:2:0) after 1m0s
[04:20:20.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:2:0)
[04:20:20.023] Timer tick, asking Buildbucket for the build status
[04:20:20.152] Build 8862508005408571296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:20.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:3:0) after 1m0s
[04:21:20.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:3:0)
[04:21:20.277] Timer tick, asking Buildbucket for the build status
[04:21:20.443] Build 8862508005408571296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:20.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:4:0) after 1m0s
[04:22:20.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:4:0)
[04:22:20.584] Timer tick, asking Buildbucket for the build status
[04:22:20.683] Build 8862508005408571296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:20.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:5:0) after 1m0s
[04:23:20.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:5:0)
[04:23:20.709] Timer tick, asking Buildbucket for the build status
[04:23:20.804] Build 8862508005408571296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:20.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:6:0) after 1m0s
[04:24:20.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:6:0)
[04:24:20.917] Timer tick, asking Buildbucket for the build status
[04:24:21.409] Build 8862508005408571296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:24:21.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:7:0) after 1m0s
[04:25:03.006] Received PubSub notification, asking Buildbucket for the build status
[04:25:03.128] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:21.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:7:0)
[04:25:21.451] Timer tick, asking Buildbucket for the build status
[04:25:21.554] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:21.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:9:0) after 1m0s
[04:26:21.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:9:0)
[04:26:21.611] Timer tick, asking Buildbucket for the build status
[04:26:21.731] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:21.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:10:0) after 1m0s
[04:27:21.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:10:0)
[04:27:21.807] Timer tick, asking Buildbucket for the build status
[04:27:22.083] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:22.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:11:0) after 1m0s
[04:28:22.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:11:0)
[04:28:22.148] Timer tick, asking Buildbucket for the build status
[04:28:22.255] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:22.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:12:0) after 1m0s
[04:29:22.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:12:0)
[04:29:22.355] Timer tick, asking Buildbucket for the build status
[04:29:22.939] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:22.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:13:0) after 1m0s
[04:30:22.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:13:0)
[04:30:22.961] Timer tick, asking Buildbucket for the build status
[04:30:23.045] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:23.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:14:0) after 1m0s
[04:31:23.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:14:0)
[04:31:23.129] Timer tick, asking Buildbucket for the build status
[04:31:23.211] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:23.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:15:0) after 1m0s
[04:32:23.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:15:0)
[04:32:23.299] Timer tick, asking Buildbucket for the build status
[04:32:23.381] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:23.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:16:0) after 1m0s
[04:33:23.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:16:0)
[04:33:23.402] Timer tick, asking Buildbucket for the build status
[04:33:23.472] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:23.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:17:0) after 1m0s
[04:34:23.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:17:0)
[04:34:23.525] Timer tick, asking Buildbucket for the build status
[04:34:23.664] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:23.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:18:0) after 1m0s
[04:35:23.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:18:0)
[04:35:23.692] Timer tick, asking Buildbucket for the build status
[04:35:23.872] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:23.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:19:0) after 1m0s
[04:36:23.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:19:0)
[04:36:23.881] Timer tick, asking Buildbucket for the build status
[04:36:24.085] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:24.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:20:0) after 1m0s
[04:37:24.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:20:0)
[04:37:24.126] Timer tick, asking Buildbucket for the build status
[04:37:24.241] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:24.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:21:0) after 1m0s
[04:38:24.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:21:0)
[04:38:24.309] Timer tick, asking Buildbucket for the build status
[04:38:24.422] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:24.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:22:0) after 1m0s
[04:39:24.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:22:0)
[04:39:24.442] Timer tick, asking Buildbucket for the build status
[04:39:24.534] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:24.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:23:0) after 1m0s
[04:40:24.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:23:0)
[04:40:24.556] Timer tick, asking Buildbucket for the build status
[04:40:25.007] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:25.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:24:0) after 1m0s
[04:41:24.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:24:0)
[04:41:24.994] Timer tick, asking Buildbucket for the build status
[04:41:25.068] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:25.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:25:0) after 1m0s
[04:42:25.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:25:0)
[04:42:25.102] Timer tick, asking Buildbucket for the build status
[04:42:25.184] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:25.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:26:0) after 1m0s
[04:43:25.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:26:0)
[04:43:25.209] Timer tick, asking Buildbucket for the build status
[04:43:25.273] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:25.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:27:0) after 1m0s
[04:44:25.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:27:0)
[04:44:25.296] Timer tick, asking Buildbucket for the build status
[04:44:25.410] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:25.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:28:0) after 1m0s
[04:45:25.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:28:0)
[04:45:25.432] Timer tick, asking Buildbucket for the build status
[04:45:25.496] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:25.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:29:0) after 1m0s
[04:46:25.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:29:0)
[04:46:25.520] Timer tick, asking Buildbucket for the build status
[04:46:25.611] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:25.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:30:0) after 1m0s
[04:47:25.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:30:0)
[04:47:25.646] Timer tick, asking Buildbucket for the build status
[04:47:25.737] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:25.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:31:0) after 1m0s
[04:48:25.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:31:0)
[04:48:25.773] Timer tick, asking Buildbucket for the build status
[04:48:25.944] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:25.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:32:0) after 1m0s
[04:49:25.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:32:0)
[04:49:25.935] Timer tick, asking Buildbucket for the build status
[04:49:26.084] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:26.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:33:0) after 1m0s
[04:50:26.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:33:0)
[04:50:26.104] Timer tick, asking Buildbucket for the build status
[04:50:27.564] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:27.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:34:0) after 1m0s
[04:51:27.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:34:0)
[04:51:27.644] Timer tick, asking Buildbucket for the build status
[04:51:27.739] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:27.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:35:0) after 1m0s
[04:52:27.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:35:0)
[04:52:27.737] Timer tick, asking Buildbucket for the build status
[04:52:27.796] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:27.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:36:0) after 1m0s
[04:53:27.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:36:0)
[04:53:27.847] Timer tick, asking Buildbucket for the build status
[04:53:28.000] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:28.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:37:0) after 1m0s
[04:54:28.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:37:0)
[04:54:28.013] Timer tick, asking Buildbucket for the build status
[04:54:28.103] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:28.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:38:0) after 1m0s
[04:55:28.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:38:0)
[04:55:28.125] Timer tick, asking Buildbucket for the build status
[04:55:28.185] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:28.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:39:0) after 1m0s
[04:56:28.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:39:0)
[04:56:28.181] Timer tick, asking Buildbucket for the build status
[04:56:28.316] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:28.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:40:0) after 1m0s
[04:57:28.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:40:0)
[04:57:28.346] Timer tick, asking Buildbucket for the build status
[04:57:28.401] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:28.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:41:0) after 1m0s
[04:58:28.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:41:0)
[04:58:28.466] Timer tick, asking Buildbucket for the build status
[04:58:29.412] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:29.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:42:0) after 1m0s
[04:59:29.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:42:0)
[04:59:29.466] Timer tick, asking Buildbucket for the build status
[04:59:29.554] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:29.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:43:0) after 1m0s
[05:00:29.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:43:0)
[05:00:29.550] Timer tick, asking Buildbucket for the build status
[05:00:29.706] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:29.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:44:0) after 1m0s
[05:01:29.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:44:0)
[05:01:29.733] Timer tick, asking Buildbucket for the build status
[05:01:29.827] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:29.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:45:0) after 1m0s
[05:02:29.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:45:0)
[05:02:29.847] Timer tick, asking Buildbucket for the build status
[05:02:29.946] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:29.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:46:0) after 1m0s
[05:03:30.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:46:0)
[05:03:30.003] Timer tick, asking Buildbucket for the build status
[05:03:30.084] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:30.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:47:0) after 1m0s
[05:04:30.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:47:0)
[05:04:30.108] Timer tick, asking Buildbucket for the build status
[05:04:30.204] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:30.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:48:0) after 1m0s
[05:05:30.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:48:0)
[05:05:30.224] Timer tick, asking Buildbucket for the build status
[05:05:30.297] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:30.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:49:0) after 1m0s
[05:06:30.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:49:0)
[05:06:30.298] Timer tick, asking Buildbucket for the build status
[05:06:30.387] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:30.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:50:0) after 1m0s
[05:07:30.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:50:0)
[05:07:30.408] Timer tick, asking Buildbucket for the build status
[05:07:30.572] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:30.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:51:0) after 1m0s
[05:08:30.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:51:0)
[05:08:30.596] Timer tick, asking Buildbucket for the build status
[05:08:30.681] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:30.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:52:0) after 1m0s
[05:09:30.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:52:0)
[05:09:30.736] Timer tick, asking Buildbucket for the build status
[05:09:30.863] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:30.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:53:0) after 1m0s
[05:10:30.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:53:0)
[05:10:30.910] Timer tick, asking Buildbucket for the build status
[05:10:30.972] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:30.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:54:0) after 1m0s
[05:11:31.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:54:0)
[05:11:31.043] Timer tick, asking Buildbucket for the build status
[05:11:31.108] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:31.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:55:0) after 1m0s
[05:12:31.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:55:0)
[05:12:31.132] Timer tick, asking Buildbucket for the build status
[05:12:31.336] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:31.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:56:0) after 1m0s
[05:13:31.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:56:0)
[05:13:31.360] Timer tick, asking Buildbucket for the build status
[05:13:31.697] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:31.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:57:0) after 1m0s
[05:14:31.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:57:0)
[05:14:31.808] Timer tick, asking Buildbucket for the build status
[05:14:32.318] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:32.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:58:0) after 1m0s
[05:15:32.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:58:0)
[05:15:32.349] Timer tick, asking Buildbucket for the build status
[05:15:32.770] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:32.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:59:0) after 1m0s
[05:16:32.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:59:0)
[05:16:32.798] Timer tick, asking Buildbucket for the build status
[05:16:33.021] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:33.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:60:0) after 1m0s
[05:17:33.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:60:0)
[05:17:33.117] Timer tick, asking Buildbucket for the build status
[05:17:33.361] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:33.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:61:0) after 1m0s
[05:18:33.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:61:0)
[05:18:33.415] Timer tick, asking Buildbucket for the build status
[05:18:33.634] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:33.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:62:0) after 1m0s
[05:19:33.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:62:0)
[05:19:33.682] Timer tick, asking Buildbucket for the build status
[05:19:33.805] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:33.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:63:0) after 1m0s
[05:20:33.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:63:0)
[05:20:33.866] Timer tick, asking Buildbucket for the build status
[05:20:33.974] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:33.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:64:0) after 1m0s
[05:21:33.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:64:0)
[05:21:33.991] Timer tick, asking Buildbucket for the build status
[05:21:34.119] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:34.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:65:0) after 1m0s
[05:22:34.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:65:0)
[05:22:34.210] Timer tick, asking Buildbucket for the build status
[05:22:34.314] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:34.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:66:0) after 1m0s
[05:23:34.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:66:0)
[05:23:34.370] Timer tick, asking Buildbucket for the build status
[05:23:34.531] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:34.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:67:0) after 1m0s
[05:24:34.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:67:0)
[05:24:34.541] Timer tick, asking Buildbucket for the build status
[05:24:35.023] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:35.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:68:0) after 1m0s
[05:25:35.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:68:0)
[05:25:35.025] Timer tick, asking Buildbucket for the build status
[05:25:35.240] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:35.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:69:0) after 1m0s
[05:26:35.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:69:0)
[05:26:35.260] Timer tick, asking Buildbucket for the build status
[05:26:35.536] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:35.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:70:0) after 1m0s
[05:27:35.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:70:0)
[05:27:35.550] Timer tick, asking Buildbucket for the build status
[05:27:35.648] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:35.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:71:0) after 1m0s
[05:28:35.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:71:0)
[05:28:35.743] Timer tick, asking Buildbucket for the build status
[05:28:36.510] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:36.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:72:0) after 1m0s
[05:29:36.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:72:0)
[05:29:36.536] Timer tick, asking Buildbucket for the build status
[05:29:36.631] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:36.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:73:0) after 1m0s
[05:30:36.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:73:0)
[05:30:36.764] Timer tick, asking Buildbucket for the build status
[05:30:36.891] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:36.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:74:0) after 1m0s
[05:31:36.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:74:0)
[05:31:36.953] Timer tick, asking Buildbucket for the build status
[05:31:37.097] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:37.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:75:0) after 1m0s
[05:32:37.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:75:0)
[05:32:37.177] Timer tick, asking Buildbucket for the build status
[05:32:37.375] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:37.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:76:0) after 1m0s
[05:33:37.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:76:0)
[05:33:37.492] Timer tick, asking Buildbucket for the build status
[05:33:37.650] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:37.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:77:0) after 1m0s
[05:34:37.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:77:0)
[05:34:37.876] Timer tick, asking Buildbucket for the build status
[05:34:38.024] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:38.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:78:0) after 1m0s
[05:35:38.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:78:0)
[05:35:38.064] Timer tick, asking Buildbucket for the build status
[05:35:38.437] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:38.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:79:0) after 1m0s
[05:36:38.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:79:0)
[05:36:38.464] Timer tick, asking Buildbucket for the build status
[05:36:38.748] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:38.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:80:0) after 1m0s
[05:37:38.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:80:0)
[05:37:38.811] Timer tick, asking Buildbucket for the build status
[05:37:38.874] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:38.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:81:0) after 1m0s
[05:38:38.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:81:0)
[05:38:38.898] Timer tick, asking Buildbucket for the build status
[05:38:39.124] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:39.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:82:0) after 1m0s
[05:39:39.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:82:0)
[05:39:39.146] Timer tick, asking Buildbucket for the build status
[05:39:39.293] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:39.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:83:0) after 1m0s
[05:40:39.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:83:0)
[05:40:39.288] Timer tick, asking Buildbucket for the build status
[05:40:39.855] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:39.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:84:0) after 1m0s
[05:41:39.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:84:0)
[05:41:39.910] Timer tick, asking Buildbucket for the build status
[05:41:40.242] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:40.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:85:0) after 1m0s
[05:42:40.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:85:0)
[05:42:40.278] Timer tick, asking Buildbucket for the build status
[05:42:40.380] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:40.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:86:0) after 1m0s
[05:43:40.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:86:0)
[05:43:40.452] Timer tick, asking Buildbucket for the build status
[05:43:40.670] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:40.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:87:0) after 1m0s
[05:44:40.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:87:0)
[05:44:40.701] Timer tick, asking Buildbucket for the build status
[05:44:40.816] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:40.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:88:0) after 1m0s
[05:45:40.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:88:0)
[05:45:40.880] Timer tick, asking Buildbucket for the build status
[05:45:40.994] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:40.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:89:0) after 1m0s
[05:46:41.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:89:0)
[05:46:41.177] Timer tick, asking Buildbucket for the build status
[05:46:41.265] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:41.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:90:0) after 1m0s
[05:47:41.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:90:0)
[05:47:41.288] Timer tick, asking Buildbucket for the build status
[05:47:41.372] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:41.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:91:0) after 1m0s
[05:48:41.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:91:0)
[05:48:41.385] Timer tick, asking Buildbucket for the build status
[05:48:41.774] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:41.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:92:0) after 1m0s
[05:49:41.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:92:0)
[05:49:41.808] Timer tick, asking Buildbucket for the build status
[05:49:41.922] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:41.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:93:0) after 1m0s
[05:50:41.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:93:0)
[05:50:41.921] Timer tick, asking Buildbucket for the build status
[05:50:42.429] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:42.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:94:0) after 1m0s
[05:51:42.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:94:0)
[05:51:42.459] Timer tick, asking Buildbucket for the build status
[05:51:42.556] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:42.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:95:0) after 1m0s
[05:52:42.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:95:0)
[05:52:42.615] Timer tick, asking Buildbucket for the build status
[05:52:42.852] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:42.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:96:0) after 1m0s
[05:53:42.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:96:0)
[05:53:42.879] Timer tick, asking Buildbucket for the build status
[05:53:43.131] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:43.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:97:0) after 1m0s
[05:54:43.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:97:0)
[05:54:43.127] Timer tick, asking Buildbucket for the build status
[05:54:43.672] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:43.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:98:0) after 1m0s
[05:55:43.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:98:0)
[05:55:43.743] Timer tick, asking Buildbucket for the build status
[05:55:44.845] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:44.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:99:0) after 1m0s
[05:56:44.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:99:0)
[05:56:44.875] Timer tick, asking Buildbucket for the build status
[05:56:44.990] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:44.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:100:0) after 1m0s
[05:57:45.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:100:0)
[05:57:45.164] Timer tick, asking Buildbucket for the build status
[05:57:45.253] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:45.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:101:0) after 1m0s
[05:58:45.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:101:0)
[05:58:45.296] Timer tick, asking Buildbucket for the build status
[05:58:45.667] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:45.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:102:0) after 1m0s
[05:59:45.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:102:0)
[05:59:45.654] Timer tick, asking Buildbucket for the build status
[05:59:45.756] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:45.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:103:0) after 1m0s
[06:00:45.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:103:0)
[06:00:45.787] Timer tick, asking Buildbucket for the build status
[06:00:45.972] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:45.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:104:0) after 1m0s
[06:01:46.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:104:0)
[06:01:46.069] Timer tick, asking Buildbucket for the build status
[06:01:46.172] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:46.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:105:0) after 1m0s
[06:02:46.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:105:0)
[06:02:46.192] Timer tick, asking Buildbucket for the build status
[06:02:46.277] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:46.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:106:0) after 1m0s
[06:03:46.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:106:0)
[06:03:46.316] Timer tick, asking Buildbucket for the build status
[06:03:46.414] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:46.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:107:0) after 1m0s
[06:04:46.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:107:0)
[06:04:46.480] Timer tick, asking Buildbucket for the build status
[06:04:46.637] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:46.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:108:0) after 1m0s
[06:05:46.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:108:0)
[06:05:46.675] Timer tick, asking Buildbucket for the build status
[06:05:46.849] Build 8862508005408571296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:46.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027938067410865728:109:0) after 1m0s
[06:06:03.099] Received PubSub notification, asking Buildbucket for the build status
[06:06:04.542] Build 8862508005408571296: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:06:04.542] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606457161751213",
"created_by": "project:chromium",
"created_ts": "1606450758505370",
"id": "8862508005408571296",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a8306e9a6584285499c32ddd8112749a15783f3\"}}",
"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\": \"1a8306e9a6584285499c32ddd8112749a15783f3\", \"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-4-qhsy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15877, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"fcb6b5a5c55ee35d232f35bcfa9c35aefc00f89e\", \"got_dawn_revision\": \"4814bdbdeae40186a61dfb7494390e64820daa4f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"1a8306e9a6584285499c32ddd8112749a15783f3\", \"got_revision_cp\": \"refs/heads/master@{#831469}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"961f93287f84e7e7a7f5ff8a43c7adf14ca7fc42\", \"got_v8_revision_cp\": \"refs/heads/8.9.63@{#1}\", \"got_webrtc_revision\": \"0d863f72a8c747c1b41f2798e5201e1abcdaec2b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32704}\", \"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\": \"1a8306e9a6584285499c32ddd8112749a15783f3\", \"swarm_hashes_refs/heads/master(at){#831469}_without_patch\": {\"absl_hardening_tests\": \"82aa67fbb5885bc90653b5daa2d0c7c71f6c2dba\", \"accessibility_unittests\": \"fe4d9cc5fac4bf2d76b213235a92dcfd8ba79c57\", \"angle_unittests\": \"9f0a260d5f9a3f52193277c7df9d258e334435bc\", \"app_shell_unittests\": \"539e29cf43eb29ee425542c61df98adecd75e6d6\", \"aura_unittests\": \"be6399cf6820a0c92fe904ea76acf63dbad9278c\", \"base_unittests\": \"df4fea60ca530be369fee24a55dcf0d032c37148\", \"base_util_unittests\": \"22dae98eec4f8488849be3196607efa28f5e429e\", \"blink_common_unittests\": \"7efdaea3cf88523c0f4a098ec404f08cc1df61f5\", \"blink_fuzzer_unittests\": \"dcd30f415b0aa15f2d12ab817060f6f754d05c56\", \"blink_heap_unittests\": \"e5c99e381852f975fe27cf9a2abc011c5fde42a9\", \"blink_platform_unittests\": \"8174b032317c38448c22c213f69e0a8a5870f57f\", \"blink_unittests\": \"4e146e1ecafcd6c160d7a6f822fcc5d4d3b1bf6d\", \"boringssl_crypto_tests\": \"70879457f1fc7b8ba0f90aabe885b4877537f0c8\", \"boringssl_ssl_tests\": \"74d4cfac1c832b14ffe6dd5695a476b5a4421b09\", \"browser_switcher_bho_unittests\": \"af49d9ab209cf4b3b436608eb48c48acd746b828\", \"browser_tests\": \"dadd3b23f39f597420e2cf1f54be62338b624c39\", \"capture_unittests\": \"efcf65d80b11a775fd472919ed58dc3270d8e548\", \"cast_unittests\": \"bc3ec40b303b24570805045ca5ce40fd64313b9e\", \"cc_unittests\": \"c1701af1b4c325c9ba584fa17c6f8376c876afc3\", \"chrome_app_unittests\": \"bb72825a5d2feb8b7872158c24ac28d44bbd581a\", \"chrome_cleaner_unittests\": \"ee274c354118f778a935753839b143e5438f2750\", \"chrome_elf_unittests\": \"3425257316efeb7d21654fc3112af66cb1ebfda4\", \"chromedriver_unittests\": \"b4a126b740c6af48233c0279dec8ca4c8ae083f0\", \"components_browsertests\": \"d16034fda66b42db41c7bcd1fb49d3fc867619fb\", \"components_unittests\": \"9f0d41337572f4031232e966fdde803bd19203f8\", \"compositor_unittests\": \"361dd342515719a2f5badc12332f7095a096d412\", \"content_browsertests\": \"f9be1dc008bcceea68f83815d55458a7b2e3a16c\", \"content_unittests\": \"ab05ff9eb1711f8c43356aa4f0f1df7fcfbebfcb\", \"courgette_unittests\": \"73e4c0e4357ffe6f9c0bda3b1aa8053c4a350d1e\", \"crashpad_tests\": \"730d05a025601d65d9be64c43a90dd56b9896ff9\", \"cronet_tests\": \"7fe1b131bec5e6eb993bd8f87a319cc8adab371e\", \"cronet_unittests\": \"9915e97f2921a29ace934f157fe2434c7db78daf\", \"crypto_unittests\": \"1542c0096b5a673dafa8cdbdde8605ab40d8c7b6\", \"delayloads_unittests\": \"72804d26476444b36afd26d870f94352529d50b3\", \"device_unittests\": \"95817c0cbe4b40b31d0c4f6b3c7813320342d73d\", \"display_unittests\": \"263383b4efe4715a3b16bdbcae959a778aa5a2c0\", \"elevation_service_unittests\": \"33ff423db26866a53908c91dc78bc7ff48725d1f\", \"events_unittests\": \"acfed96167114211b519b85e15615c8122fa545f\", \"extensions_browsertests\": \"47b7c627639b19392767449fa6f8dbf8a27ccb6e\", \"extensions_unittests\": \"ccac610b528dcd8d45bb248fa9d0a4d20726bbe0\", \"filesystem_service_unittests\": \"2b9d6174380df17284c3b6097638632de7184cfe\", \"gcm_unit_tests\": \"07de3a46a762f64e716b5f6e44c70e88596e62d7\", \"gcp_unittests\": \"f8ba9575dced6d4b5470948ef4b5bb1c8261fdd0\", \"gfx_unittests\": \"9939ba6c7d5bad85cab4308a7f4b69460080f360\", \"gin_unittests\": \"ea193d6d53370394c007fe60f2119969cf1c6f8d\", \"google_apis_unittests\": \"05d775da96ba80d758b27d16eaf517149a4cbd57\", \"gpu_unittests\": \"db52991956bf051e8bdd247db8058204d05609f6\", \"gwp_asan_unittests\": \"fb9e8672dd9392410d8f62def0ac7de9cbe49b04\", \"headless_browsertests\": \"a79fc76774f826e5ddf91703ee81887ea95aa26b\", \"headless_unittests\": \"251e3e227bc0e224558ed2422e7b2462a2419cb6\", \"install_static_unittests\": \"c5eeb68b34873c43bc8bbf57ddd1b19a4bb4960e\", \"installer_util_unittests\": \"02d997cbdef4f009f6af93ea8464086c0ab0d647\", \"interactive_ui_tests\": \"66d0ce2874422acbde1546d1284609b838eb1f24\", \"ipc_tests\": \"d03d6ab83a72a59c5c25227c60f6ecf71128d17d\", \"jingle_unittests\": \"f4c246627b9030145bdd5cb340b3601d69f0a5cc\", \"latency_unittests\": \"e5dce329e9b74f271d8bfb44effeca1fd3b24d9b\", \"libjingle_xmpp_unittests\": \"c01e4e51af2611a8081f973e0ba2f1c42a936c71\", \"liburlpattern_unittests\": \"fd05ee5b31addaf5047a0e010bb9f6a5a3b1f3d0\", \"media_blink_unittests\": \"372f083b1216a8813665d231ece36c9e64879fc6\", \"media_unittests\": \"1bfae2f798af5dee7a389b7e2927f8c8581c0d8f\", \"message_center_unittests\": \"215c3ab0ec9037d51861061ef74ca4afd1ca225a\", \"midi_unittests\": \"426849819e62b467ba935f7b7b40f4fd5a570f51\", \"mojo_core_unittests\": \"5cd7d119197acac3bc5028d8c948ade749d80ab3\", \"mojo_unittests\": \"068b089c731a1b782e23b79e2b3800f23bc6c0a6\", \"nacl_loader_unittests\": \"07cd35d4dbb95f5ffb521336a22f94e857c785e2\", \"native_theme_unittests\": \"b9b92e2a7efc7fe3470afbcb9d83a7165b9341d1\", \"net_unittests\": \"5455e1094c834abe756d6aea029b9dd3d56df418\", \"notification_helper_unittests\": \"b0d7ca96c4c8ab3ba13352e095761f13b80f3126\", \"pdf_unittests\": \"c922e05d469cbf19d5eb5fb5da5fecf94a6df144\", \"perfetto_unittests\": \"f7bed5bba44cdf165d662b7e0342643e9cd3d2f5\", \"ppapi_unittests\": \"347c9f47fc733f29ae0981f986e75d32f5540c6a\", \"printing_unittests\": \"32a3ed4d0b899998f08d292bc5614f32bb49e19e\", \"remoting_unittests\": \"2e0b5a713d18a5b86611b3d181f5fd34bd4d74c6\", \"sbox_integration_tests\": \"0823695b4ba828c8c3dcf040649143cd5ee72128\", \"sbox_unittests\": \"d25055c36e66b930aa4d72e0df5e1f84b37a68c9\", \"sbox_validation_tests\": \"a00ad8915b9b94bc6871d3347749ea8accaeb5b0\", \"service_manager_unittests\": \"24c67585b3ff0cb1faea516307012d1f91be1208\", \"services_unittests\": \"32d0f7e334209b1ff804ee7dfa8386077b3726bb\", \"setup_unittests\": \"ea85e7fdabc30c84118f9b2669928a7fa636ccea\", \"shell_dialogs_unittests\": \"71f05cf88a88d20acc6a080509821053050d09c8\", \"skia_unittests\": \"ad4c6344d256d3459a3d0c2b09f8d95a6a71a3b6\", \"snapshot_unittests\": \"04ad69e9badb50481dfb514e5ac5f4e1766b93c8\", \"sql_unittests\": \"190498fc256713750ba125c38236710735059915\", \"storage_unittests\": \"8313fa449d11ac23579ebe246a05f70ae17b25a9\", \"sync_integration_tests\": \"6838634fdbbb6b3fc592efa9e50f0dc93bb5aa5b\", \"traffic_annotation_auditor_unittests\": \"a751bf21a7ac72ffb81ddb3f72ee3a8378f44cba\", \"ui_base_unittests\": \"d0a5264a10c6e4be4e342086e9115d86eacd714b\", \"ui_touch_selection_unittests\": \"7e34115e3637625c81f04d9ddcc55a8eefffe67c\", \"unit_tests\": \"f817cd5e76bc2fb7f09c3d5d9a037ab937c2b4be\", \"updater_tests\": \"5038492b0226f8973dd0f370be5dda3e32bdb9bd\", \"url_unittests\": \"eea1aba62a0a470fe61554f4564fabf9ee541bc3\", \"views_unittests\": \"4612163273a80b8aedc163dda7fa309e6190ef07\", \"viz_unittests\": \"1b9931db230f64391bf6fe38f91c38a21adc56b9\", \"vr_common_unittests\": \"32edf1ed15b265497d29df0e78ccfb3905ae71c1\", \"vr_pixeltests\": \"ccd7e74f1dd84fc76d5fdb85115f6699f419211c\", \"weblayer_browsertests\": \"1b75066f1c5b388e81c316b1945ef6362da58819\", \"weblayer_unittests\": \"093849d71685d22263b38a93715a457a703e54f5\", \"wm_unittests\": \"0817aa0ed2bc8685678144a199625891d97f9acb\", \"wtf_unittests\": \"5286ab570f1d962cc42c016cfb585d0712cf22aa\", \"zlib_unittests\": \"827cd81e434ea291b3382deacf14a071d3870896\", \"zucchini_unittests\": \"5fc74bbf6409902855f80c97fb4a1f73fc22a570\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_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-4-qhsy\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606451102031777",
"status": "COMPLETED",
"status_changed_ts": "1606457162465057",
"tags": [
"build_address:luci.chromium.ci/win-asan/15877",
"builder:win-asan",
"buildset:commit/git/1a8306e9a6584285499c32ddd8112749a15783f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a8306e9a6584285499c32ddd8112749a15783f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027938067410865728",
"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/8862508005408571296/+/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:5020bed16ca79310",
"user_agent:luci-scheduler"
],
"updated_ts": "1606457162470118",
"url": "https://ci.chromium.org/b/8862508005408571296",
"utcnow_ts": "1606457163258627"
}
}
[06:06:04.542] Invocation finished in 1h46m47.331333171s with status SUCCEEDED