[16:53:53.585] New invocation is queued and will start shortly
[16:53:54.788] Starting the invocation (attempt 1)
[16:53:54.822] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:53:54.823] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:53:54.823] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024538503950272320",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"13552d9caef0598b59e02d232a6f46924d5d2772\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024538503950272320",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/13552d9caef0598b59e02d232a6f46924d5d2772",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13552d9caef0598b59e02d232a6f46924d5d2772",
"gitiles_ref:refs/heads/master"
]
}
[16:53:55.396] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609692834900945",
"id": "8859108441910096016",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"13552d9caef0598b59e02d232a6f46924d5d2772\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609692835279124",
"tags": [
"build_address:luci.chromium.ci/win-asan/16612",
"builder:win-asan",
"buildset:commit/git/13552d9caef0598b59e02d232a6f46924d5d2772",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13552d9caef0598b59e02d232a6f46924d5d2772",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024538503950272320",
"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/8859108441910096016/+/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": "1609692835279227",
"url": "https://ci.chromium.org/b/8859108441910096016",
"utcnow_ts": "1609692835388022"
}
}
[16:53:55.396] Task URL: https://ci.chromium.org/b/8859108441910096016
[16:53:55.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:2:0) after 1m0s
[16:54:55.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:2:0)
[16:54:55.445] Timer tick, asking Buildbucket for the build status
[16:54:55.525] Build 8859108441910096016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:54:55.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:3:0) after 1m0s
[16:55:55.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:3:0)
[16:55:55.593] Timer tick, asking Buildbucket for the build status
[16:55:55.666] Build 8859108441910096016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:55:55.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:4:0) after 1m0s
[16:56:55.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:4:0)
[16:56:55.686] Timer tick, asking Buildbucket for the build status
[16:56:55.757] Build 8859108441910096016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:56:55.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:5:0) after 1m0s
[16:57:55.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:5:0)
[16:57:55.820] Timer tick, asking Buildbucket for the build status
[16:57:55.933] Build 8859108441910096016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:57:55.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:6:0) after 1m0s
[16:58:08.856] Received PubSub notification, asking Buildbucket for the build status
[16:58:09.092] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:55.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:6:0)
[16:58:55.980] Timer tick, asking Buildbucket for the build status
[16:58:56.268] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:56.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:8:0) after 1m0s
[16:59:56.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:8:0)
[16:59:56.283] Timer tick, asking Buildbucket for the build status
[16:59:56.392] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:56.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:9:0) after 1m0s
[17:00:56.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:9:0)
[17:00:56.446] Timer tick, asking Buildbucket for the build status
[17:00:56.504] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:56.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:10:0) after 1m0s
[17:01:56.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:10:0)
[17:01:56.606] Timer tick, asking Buildbucket for the build status
[17:01:56.812] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:56.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:11:0) after 1m0s
[17:02:56.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:11:0)
[17:02:56.828] Timer tick, asking Buildbucket for the build status
[17:02:57.082] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:57.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:12:0) after 1m0s
[17:03:57.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:12:0)
[17:03:57.104] Timer tick, asking Buildbucket for the build status
[17:03:57.249] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:57.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:13:0) after 1m0s
[17:04:57.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:13:0)
[17:04:57.289] Timer tick, asking Buildbucket for the build status
[17:04:57.592] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:57.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:14:0) after 1m0s
[17:05:57.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:14:0)
[17:05:57.611] Timer tick, asking Buildbucket for the build status
[17:05:57.725] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:57.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:15:0) after 1m0s
[17:06:57.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:15:0)
[17:06:57.746] Timer tick, asking Buildbucket for the build status
[17:06:57.935] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:57.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:16:0) after 1m0s
[17:07:57.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:16:0)
[17:07:57.966] Timer tick, asking Buildbucket for the build status
[17:07:58.172] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:58.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:17:0) after 1m0s
[17:08:58.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:17:0)
[17:08:58.192] Timer tick, asking Buildbucket for the build status
[17:08:58.386] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:58.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:18:0) after 1m0s
[17:09:58.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:18:0)
[17:09:58.377] Timer tick, asking Buildbucket for the build status
[17:09:58.516] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:58.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:19:0) after 1m0s
[17:10:58.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:19:0)
[17:10:58.533] Timer tick, asking Buildbucket for the build status
[17:10:59.520] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:59.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:20:0) after 1m0s
[17:11:59.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:20:0)
[17:11:59.538] Timer tick, asking Buildbucket for the build status
[17:11:59.820] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:59.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:21:0) after 1m0s
[17:12:59.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:21:0)
[17:12:59.861] Timer tick, asking Buildbucket for the build status
[17:12:59.960] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:59.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:22:0) after 1m0s
[17:13:59.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:22:0)
[17:13:59.980] Timer tick, asking Buildbucket for the build status
[17:14:00.247] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:00.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:23:0) after 1m0s
[17:15:00.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:23:0)
[17:15:00.269] Timer tick, asking Buildbucket for the build status
[17:15:00.325] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:00.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:24:0) after 1m0s
[17:16:00.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:24:0)
[17:16:00.353] Timer tick, asking Buildbucket for the build status
[17:16:00.416] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:00.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:25:0) after 1m0s
[17:17:00.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:25:0)
[17:17:00.434] Timer tick, asking Buildbucket for the build status
[17:17:00.499] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:00.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:26:0) after 1m0s
[17:18:01.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:26:0)
[17:18:01.043] Timer tick, asking Buildbucket for the build status
[17:18:01.182] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:01.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:27:0) after 1m0s
[17:19:01.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:27:0)
[17:19:01.202] Timer tick, asking Buildbucket for the build status
[17:19:01.519] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:01.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:28:0) after 1m0s
[17:20:01.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:28:0)
[17:20:01.540] Timer tick, asking Buildbucket for the build status
[17:20:01.617] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:01.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:29:0) after 1m0s
[17:21:01.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:29:0)
[17:21:01.685] Timer tick, asking Buildbucket for the build status
[17:21:02.180] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:02.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:30:0) after 1m0s
[17:22:02.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:30:0)
[17:22:02.267] Timer tick, asking Buildbucket for the build status
[17:22:02.319] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:02.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:31:0) after 1m0s
[17:23:02.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:31:0)
[17:23:02.503] Timer tick, asking Buildbucket for the build status
[17:23:02.568] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:02.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:32:0) after 1m0s
[17:24:02.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:32:0)
[17:24:02.591] Timer tick, asking Buildbucket for the build status
[17:24:02.686] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:02.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:33:0) after 1m0s
[17:25:02.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:33:0)
[17:25:02.828] Timer tick, asking Buildbucket for the build status
[17:25:03.132] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:03.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:34:0) after 1m0s
[17:26:03.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:34:0)
[17:26:03.153] Timer tick, asking Buildbucket for the build status
[17:26:03.418] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:03.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:35:0) after 1m0s
[17:27:03.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:35:0)
[17:27:03.474] Timer tick, asking Buildbucket for the build status
[17:27:03.588] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:03.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:36:0) after 1m0s
[17:28:03.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:36:0)
[17:28:03.608] Timer tick, asking Buildbucket for the build status
[17:28:03.668] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:03.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:37:0) after 1m0s
[17:29:03.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:37:0)
[17:29:03.716] Timer tick, asking Buildbucket for the build status
[17:29:04.196] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:04.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:38:0) after 1m0s
[17:30:04.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:38:0)
[17:30:04.218] Timer tick, asking Buildbucket for the build status
[17:30:04.285] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:04.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:39:0) after 1m0s
[17:31:04.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:39:0)
[17:31:04.314] Timer tick, asking Buildbucket for the build status
[17:31:04.378] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:04.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:40:0) after 1m0s
[17:32:04.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:40:0)
[17:32:04.459] Timer tick, asking Buildbucket for the build status
[17:32:04.699] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:04.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:41:0) after 1m0s
[17:33:04.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:41:0)
[17:33:04.753] Timer tick, asking Buildbucket for the build status
[17:33:04.813] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:04.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:42:0) after 1m0s
[17:34:04.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:42:0)
[17:34:04.873] Timer tick, asking Buildbucket for the build status
[17:34:05.361] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:05.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:43:0) after 1m0s
[17:35:05.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:43:0)
[17:35:05.499] Timer tick, asking Buildbucket for the build status
[17:35:05.582] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:05.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:44:0) after 1m0s
[17:36:05.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:44:0)
[17:36:05.691] Timer tick, asking Buildbucket for the build status
[17:36:05.990] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:05.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:45:0) after 1m0s
[17:37:06.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:45:0)
[17:37:06.004] Timer tick, asking Buildbucket for the build status
[17:37:06.183] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:06.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:46:0) after 1m0s
[17:38:06.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:46:0)
[17:38:06.230] Timer tick, asking Buildbucket for the build status
[17:38:06.287] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:06.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:47:0) after 1m0s
[17:39:06.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:47:0)
[17:39:06.371] Timer tick, asking Buildbucket for the build status
[17:39:06.451] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:06.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:48:0) after 1m0s
[17:40:06.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:48:0)
[17:40:06.503] Timer tick, asking Buildbucket for the build status
[17:40:06.555] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:06.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:49:0) after 1m0s
[17:41:06.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:49:0)
[17:41:06.584] Timer tick, asking Buildbucket for the build status
[17:41:06.652] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:06.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:50:0) after 1m0s
[17:42:06.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:50:0)
[17:42:06.702] Timer tick, asking Buildbucket for the build status
[17:42:06.798] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:06.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:51:0) after 1m0s
[17:43:06.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:51:0)
[17:43:06.885] Timer tick, asking Buildbucket for the build status
[17:43:07.078] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:07.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:52:0) after 1m0s
[17:44:07.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:52:0)
[17:44:07.128] Timer tick, asking Buildbucket for the build status
[17:44:07.997] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:07.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:53:0) after 1m0s
[17:45:08.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:53:0)
[17:45:08.119] Timer tick, asking Buildbucket for the build status
[17:45:08.335] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:08.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:54:0) after 1m0s
[17:46:08.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:54:0)
[17:46:08.356] Timer tick, asking Buildbucket for the build status
[17:46:08.454] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:08.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:55:0) after 1m0s
[17:47:08.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:55:0)
[17:47:08.561] Timer tick, asking Buildbucket for the build status
[17:47:08.797] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:08.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:56:0) after 1m0s
[17:48:08.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:56:0)
[17:48:08.817] Timer tick, asking Buildbucket for the build status
[17:48:08.911] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:08.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:57:0) after 1m0s
[17:49:08.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:57:0)
[17:49:08.969] Timer tick, asking Buildbucket for the build status
[17:49:09.112] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:09.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:58:0) after 1m0s
[17:50:09.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:58:0)
[17:50:09.132] Timer tick, asking Buildbucket for the build status
[17:50:09.304] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:09.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:59:0) after 1m0s
[17:51:09.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:59:0)
[17:51:09.359] Timer tick, asking Buildbucket for the build status
[17:51:09.509] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:09.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:60:0) after 1m0s
[17:52:09.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:60:0)
[17:52:09.531] Timer tick, asking Buildbucket for the build status
[17:52:09.738] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:09.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:61:0) after 1m0s
[17:53:09.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:61:0)
[17:53:09.777] Timer tick, asking Buildbucket for the build status
[17:53:10.025] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:10.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:62:0) after 1m0s
[17:54:10.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:62:0)
[17:54:10.122] Timer tick, asking Buildbucket for the build status
[17:54:10.460] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:10.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:63:0) after 1m0s
[17:55:10.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:63:0)
[17:55:10.582] Timer tick, asking Buildbucket for the build status
[17:55:10.673] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:10.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:64:0) after 1m0s
[17:56:10.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:64:0)
[17:56:10.675] Timer tick, asking Buildbucket for the build status
[17:56:10.764] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:10.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:65:0) after 1m0s
[17:57:10.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:65:0)
[17:57:10.849] Timer tick, asking Buildbucket for the build status
[17:57:10.917] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:10.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:66:0) after 1m0s
[17:58:10.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:66:0)
[17:58:10.993] Timer tick, asking Buildbucket for the build status
[17:58:11.077] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:11.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:67:0) after 1m0s
[17:59:11.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:67:0)
[17:59:11.357] Timer tick, asking Buildbucket for the build status
[17:59:12.108] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:12.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:68:0) after 1m0s
[18:00:12.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:68:0)
[18:00:12.371] Timer tick, asking Buildbucket for the build status
[18:00:12.623] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:12.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:69:0) after 1m0s
[18:01:13.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:69:0)
[18:01:13.203] Timer tick, asking Buildbucket for the build status
[18:01:13.293] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:13.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:70:0) after 1m0s
[18:02:13.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:70:0)
[18:02:13.639] Timer tick, asking Buildbucket for the build status
[18:02:13.798] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:13.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:71:0) after 1m0s
[18:03:13.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:71:0)
[18:03:13.982] Timer tick, asking Buildbucket for the build status
[18:03:14.129] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:14.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:72:0) after 1m0s
[18:04:14.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:72:0)
[18:04:14.156] Timer tick, asking Buildbucket for the build status
[18:04:14.271] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:14.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:73:0) after 1m0s
[18:05:14.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:73:0)
[18:05:14.355] Timer tick, asking Buildbucket for the build status
[18:05:15.040] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:15.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:74:0) after 1m0s
[18:06:15.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:74:0)
[18:06:15.265] Timer tick, asking Buildbucket for the build status
[18:06:15.789] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:15.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:75:0) after 1m0s
[18:07:16.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:75:0)
[18:07:16.440] Timer tick, asking Buildbucket for the build status
[18:07:16.547] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:16.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:76:0) after 1m0s
[18:08:16.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:76:0)
[18:08:16.772] Timer tick, asking Buildbucket for the build status
[18:08:16.957] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:16.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:77:0) after 1m0s
[18:09:17.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:77:0)
[18:09:17.071] Timer tick, asking Buildbucket for the build status
[18:09:17.202] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:17.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:78:0) after 1m0s
[18:10:17.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:78:0)
[18:10:17.247] Timer tick, asking Buildbucket for the build status
[18:10:17.332] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:17.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:79:0) after 1m0s
[18:11:17.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:79:0)
[18:11:17.400] Timer tick, asking Buildbucket for the build status
[18:11:17.495] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:17.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:80:0) after 1m0s
[18:12:17.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:80:0)
[18:12:17.524] Timer tick, asking Buildbucket for the build status
[18:12:17.612] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:17.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:81:0) after 1m0s
[18:13:17.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:81:0)
[18:13:17.686] Timer tick, asking Buildbucket for the build status
[18:13:17.802] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:17.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:82:0) after 1m0s
[18:14:17.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:82:0)
[18:14:17.872] Timer tick, asking Buildbucket for the build status
[18:14:18.032] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:18.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:83:0) after 1m0s
[18:15:18.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:83:0)
[18:15:18.056] Timer tick, asking Buildbucket for the build status
[18:15:18.235] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:18.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:84:0) after 1m0s
[18:16:18.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:84:0)
[18:16:18.383] Timer tick, asking Buildbucket for the build status
[18:16:18.499] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:18.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:85:0) after 1m0s
[18:17:18.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:85:0)
[18:17:18.563] Timer tick, asking Buildbucket for the build status
[18:17:18.988] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:18.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:86:0) after 1m0s
[18:18:19.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:86:0)
[18:18:19.086] Timer tick, asking Buildbucket for the build status
[18:18:19.184] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:19.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:87:0) after 1m0s
[18:19:19.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:87:0)
[18:19:19.250] Timer tick, asking Buildbucket for the build status
[18:19:19.618] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:19.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:88:0) after 1m0s
[18:20:19.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:88:0)
[18:20:19.685] Timer tick, asking Buildbucket for the build status
[18:20:19.938] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:19.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:89:0) after 1m0s
[18:21:19.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:89:0)
[18:21:19.959] Timer tick, asking Buildbucket for the build status
[18:21:20.047] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:20.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:90:0) after 1m0s
[18:22:20.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:90:0)
[18:22:20.211] Timer tick, asking Buildbucket for the build status
[18:22:20.509] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:20.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:91:0) after 1m0s
[18:23:20.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:91:0)
[18:23:20.597] Timer tick, asking Buildbucket for the build status
[18:23:20.720] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:20.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:92:0) after 1m0s
[18:24:20.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:92:0)
[18:24:20.760] Timer tick, asking Buildbucket for the build status
[18:24:20.863] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:20.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:93:0) after 1m0s
[18:25:20.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:93:0)
[18:25:20.934] Timer tick, asking Buildbucket for the build status
[18:25:21.183] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:21.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:94:0) after 1m0s
[18:26:21.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:94:0)
[18:26:21.246] Timer tick, asking Buildbucket for the build status
[18:26:21.705] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:21.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:95:0) after 1m0s
[18:27:21.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:95:0)
[18:27:21.717] Timer tick, asking Buildbucket for the build status
[18:27:22.084] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:22.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:96:0) after 1m0s
[18:28:22.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:96:0)
[18:28:22.121] Timer tick, asking Buildbucket for the build status
[18:28:22.319] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:22.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:97:0) after 1m0s
[18:29:22.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:97:0)
[18:29:22.341] Timer tick, asking Buildbucket for the build status
[18:29:22.547] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:22.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:98:0) after 1m0s
[18:30:22.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:98:0)
[18:30:22.578] Timer tick, asking Buildbucket for the build status
[18:30:22.681] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:22.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:99:0) after 1m0s
[18:31:22.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:99:0)
[18:31:22.705] Timer tick, asking Buildbucket for the build status
[18:31:23.013] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:23.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:100:0) after 1m0s
[18:32:23.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:100:0)
[18:32:23.040] Timer tick, asking Buildbucket for the build status
[18:32:23.108] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:23.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:101:0) after 1m0s
[18:33:23.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:101:0)
[18:33:23.143] Timer tick, asking Buildbucket for the build status
[18:33:23.424] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:23.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:102:0) after 1m0s
[18:34:23.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:102:0)
[18:34:23.448] Timer tick, asking Buildbucket for the build status
[18:34:23.991] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:23.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:103:0) after 1m0s
[18:35:24.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:103:0)
[18:35:24.044] Timer tick, asking Buildbucket for the build status
[18:35:24.164] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:24.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:104:0) after 1m0s
[18:36:24.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:104:0)
[18:36:24.224] Timer tick, asking Buildbucket for the build status
[18:36:24.340] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:24.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:105:0) after 1m0s
[18:37:24.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:105:0)
[18:37:24.411] Timer tick, asking Buildbucket for the build status
[18:37:24.598] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:24.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:106:0) after 1m0s
[18:38:24.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:106:0)
[18:38:24.671] Timer tick, asking Buildbucket for the build status
[18:38:24.919] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:24.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:107:0) after 1m0s
[18:39:24.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:107:0)
[18:39:24.940] Timer tick, asking Buildbucket for the build status
[18:39:25.310] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:25.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:108:0) after 1m0s
[18:40:25.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:108:0)
[18:40:25.430] Timer tick, asking Buildbucket for the build status
[18:40:25.496] Build 8859108441910096016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:25.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024538503950272320:109:0) after 1m0s
[18:41:21.092] Received PubSub notification, asking Buildbucket for the build status
[18:41:21.360] Build 8859108441910096016: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:41:21.361] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609699276100533",
"created_by": "project:chromium",
"created_ts": "1609692834900945",
"failure_reason": "BUILD_FAILURE",
"id": "8859108441910096016",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"13552d9caef0598b59e02d232a6f46924d5d2772\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**net_unittests** failed because of:\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.IntermediateFromAia200Pem/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.RevocationSoftFailLeafCrlDpHttpError/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalTest.ValidityJustAfterNotBefore/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.RevocationHardFailLeafCrlDpHttpError/CertVerifyProcWin\\n\\n- ...13 more failure(s) (17 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"13552d9caef0598b59e02d232a6f46924d5d2772\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-1-aof9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16612, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"bf02c31bec07a549cc8eea26ad0e63143954643d\", \"got_dawn_revision\": \"86980018b3f2786aeb5ded1220ec94bc927fa6b2\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"13552d9caef0598b59e02d232a6f46924d5d2772\", \"got_revision_cp\": \"refs/heads/master@{#839981}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"ff29e249d3179766a40c9486183559fe3e5c1456\", \"got_v8_revision\": \"a5786bb381dd6ae4d1b936320a5fc00481566700\", \"got_v8_revision_cp\": \"refs/heads/8.9.202@{#1}\", \"got_webrtc_revision\": \"8673643bc2e357ca7b6dc01c0018ed05d20ffd72\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32900}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"13552d9caef0598b59e02d232a6f46924d5d2772\", \"swarm_hashes_refs/heads/master(at){#839981}_without_patch\": {\"absl_hardening_tests\": \"e9d11936c365cf0f5b108a7b7d2db2cb46ce5e7a\", \"accessibility_unittests\": \"da7c0944fe11d19d8907974af7ba78b5430ad42c\", \"angle_unittests\": \"b9d5634918b30ac6bf0ab98e5662fcd912efb0cc\", \"app_shell_unittests\": \"dbdec8c81890ebca443548cef91966786e9f5028\", \"aura_unittests\": \"e20e66622a35baff291ed52919995cfedb82c48f\", \"base_unittests\": \"2472d94333eb850a5e9dc4af9771f0628cd7e490\", \"base_util_unittests\": \"495bd5cff554bf0b1aacada963d9823a1c740585\", \"blink_common_unittests\": \"ce28e165c41b75a933f2af7b797dff53f779bc9d\", \"blink_fuzzer_unittests\": \"5cbcab9793dfc09272a8709b7bee400c62375e61\", \"blink_heap_unittests\": \"18b416fd9d89353ecf50af6066baad30881f34e6\", \"blink_platform_unittests\": \"a8b15b135cb5f61bcfcc9421b10be7fce98e7c06\", \"blink_unittests\": \"b54369b0fe556604dfd952772d09f457320f9660\", \"boringssl_crypto_tests\": \"6800a3061437530f52cafffae8445d1a9aea66a8\", \"boringssl_ssl_tests\": \"a65e6f68ca4808a667cc8c9733d4704a6932c16b\", \"browser_switcher_bho_unittests\": \"d29bb6005af10740a62533c4e593e7ca292401c1\", \"browser_tests\": \"235b2ebc3329ac306ec361ed18ac77c326621734\", \"capture_unittests\": \"a04f8c1d707bbac4ddfd4320fa1d91292c9f823b\", \"cast_unittests\": \"0761fc35488721839348cc62fecbb51f41d4909a\", \"cc_unittests\": \"7985dcdc18ed1811fe54368405fe7e762a4e5369\", \"chrome_app_unittests\": \"35936e15d818d11c10024d1afbdfbc54c42e6077\", \"chrome_cleaner_unittests\": \"cf2100bb37fdf3dc676b648a1a8c4daeccd63ba2\", \"chrome_elf_unittests\": \"4bec3a79dce9f3fcca2deba7f986c83d0c717895\", \"chromedriver_unittests\": \"e197590d0b7dde8e6195e53c698bc05cedb69d25\", \"components_browsertests\": \"02eb8200283cade0a73670f1dc261a0238e99f90\", \"components_unittests\": \"ba4cff5febebaa4cfbd43db2f804df8a682f38d2\", \"compositor_unittests\": \"02e6e9489a07f94181442b0d71308d1d3a9a469a\", \"content_browsertests\": \"80a859104e77587e8807650af146ebede22f740a\", \"content_unittests\": \"eb076c669781857c4bf5c74a6b9874bd545d61e2\", \"courgette_unittests\": \"1e0c8df484f53f58565ce5d19689dabcf494a7e2\", \"crashpad_tests\": \"bba8543eddaad4bdf8513401b6937b6ee116f904\", \"cronet_tests\": \"1ebd9bb464173ca3a3352a4b3f930f6b8cccc024\", \"cronet_unittests\": \"d85dfa9a1ebbfcdff18307a248b571dc10ec429d\", \"crypto_unittests\": \"16109dd2c8f673af8d0d1cddf035534df499646b\", \"delayloads_unittests\": \"5c3de0ee58405d93d2408d4ff437b5e5113d7042\", \"device_unittests\": \"80c52d06d0c5e017c321667b74bde034e5f79c38\", \"display_unittests\": \"55f16368773c2569593aa84c2996b8c42b9790fc\", \"elevation_service_unittests\": \"1b48ff94477f00971ed551128f7fcbd99bd2b47d\", \"events_unittests\": \"431022a47691564402760a182390886f66c19487\", \"extensions_browsertests\": \"adeda83c3135a80c80866a289b94723c9d0e3fd1\", \"extensions_unittests\": \"e6922ab4f1dc5d872958e12025bfc20f57204256\", \"filesystem_service_unittests\": \"5fcd077a6d3ec877ba41856ca1115e545bb1b8e0\", \"gcm_unit_tests\": \"a4bed128af41cc9936c019b5e632fcea08f06afe\", \"gcp_unittests\": \"474615c89ba66af29af848dd3cf55159331b2b97\", \"gfx_unittests\": \"215f3e0c872affc803f19a965228ae17fe20e76a\", \"gin_unittests\": \"7c9255db1d28624ffd6f229eb18dde3777583657\", \"google_apis_unittests\": \"5abceae0e1a5efc879ab99c414f24149d3b9ae8d\", \"gpu_unittests\": \"0004482ef6213aa6d875f99d3d9ae7618ef1e41b\", \"gwp_asan_unittests\": \"8d34bcc4b3938135778176f1e9190fb6809f5b83\", \"headless_browsertests\": \"dc75917d955114ba43f5b1365eda1fc266a4a79c\", \"headless_unittests\": \"3bcc1efa12a95410ec116a243fb42e996267e8ba\", \"install_static_unittests\": \"960a38631877b9018e1ba50b526fd21fd7c2d3b7\", \"installer_util_unittests\": \"593e6ad4f78c23a450ea26fdc77cd0be7153954c\", \"interactive_ui_tests\": \"5b924f373879344fccafe899252ce7d2fd3a0a6f\", \"ipc_tests\": \"b7bf40d72aab3f20443d7a4646bdf69e5e685d2e\", \"jingle_unittests\": \"f001d1662170c246cb15c36677c5b2a82ad298d3\", \"latency_unittests\": \"be38e728591a093b55ada8b5d7b86616f57be8ea\", \"libjingle_xmpp_unittests\": \"862656bd614eb685aa1cedb8f94fd54896daa164\", \"liburlpattern_unittests\": \"325d347b65d604f900ff2d6d4d619fe2bd7473d2\", \"media_blink_unittests\": \"40cc277dd7d2d4c05ef0539ecce85fc253116bb0\", \"media_unittests\": \"10b9a9d175a8577137e13d7b13ae067dc3fd6357\", \"message_center_unittests\": \"3f88eba7ffa7415f57979245345dab245d6060fe\", \"midi_unittests\": \"68860ea5438d54fc25c25289b81c7795297244ae\", \"mojo_core_unittests\": \"e8c671589c3c8a5e070343991e1c749e8efa909b\", \"mojo_unittests\": \"15c26b707ec319fbcce6b7f0549ad09e3ff7f80c\", \"nacl_loader_unittests\": \"0e695df93063689ddd7dc53bf8d390e02a2f1fec\", \"native_theme_unittests\": \"c4a520830586b2fc4e8a7f8e42c63a4cb39be61e\", \"net_unittests\": \"81db6964d715593ba5a616f0939bf8fc872eb75c\", \"notification_helper_unittests\": \"0fe47f3a2c00ec73d67b281de8f238cb98e49a5b\", \"pdf_unittests\": \"911fd515aef26b0eaca6485ad5a469fa3fa880a1\", \"perfetto_unittests\": \"c71e3596d0011509b3801defa0647d7aea411d83\", \"ppapi_unittests\": \"29784176d525bd0ff166d95439d46ceff0f99db4\", \"printing_unittests\": \"cf67ced35da1b7df4c4dfdccb3afb7b9b152ad10\", \"remoting_unittests\": \"713dc89354029b356db333ea6cab549d5b90ddcf\", \"sbox_integration_tests\": \"318d2e17a71028e564010f2b105830696f2c53a7\", \"sbox_unittests\": \"c07ab0f97ada0b972bd4c10a0a92e2ee77e31abe\", \"sbox_validation_tests\": \"ba9523bbb4467cc570263e8ef76c6088afdc8662\", \"service_manager_unittests\": \"3c8bb449a08b0d0b0366c48656fd83a90cbfe977\", \"services_unittests\": \"dfb9a69e0f873ea11b974a136eff9a3993e89a54\", \"setup_unittests\": \"c4fe6c42468d1950b22963f147c9c78166fb4244\", \"shell_dialogs_unittests\": \"dcb8fde63212d75cd8afbbdec0ce9f183c8fc48b\", \"skia_unittests\": \"9b3423a1edaa3fddf41cdd4fcdf59a9faa08c26f\", \"snapshot_unittests\": \"bdb66ccacba0f46e5b58d445e7ded9545138526e\", \"sql_unittests\": \"d0bbacc17a08cf09bc7ed656fda84839074fe385\", \"storage_unittests\": \"bfa6dc4a953868151210858d9b1126a6e30a887e\", \"sync_integration_tests\": \"d007502a3937012751aa856beb02a3cce34fb984\", \"traffic_annotation_auditor_unittests\": \"08a63655575f80c433935486b7884eaceeb824da\", \"ui_base_unittests\": \"064ce3b9f6d69120ade8112c83696b4fd288fa61\", \"ui_touch_selection_unittests\": \"b877568e5751350482126c838b53019f561d20a1\", \"unit_tests\": \"62b7f33445d2b24012b81e563c25e769f2cb404f\", \"updater_tests\": \"13f7d48ec276a5af7937f643d330940b5a7d4b92\", \"url_unittests\": \"efa11e379ce85c066b8681e984ed17551d05ee06\", \"views_unittests\": \"90e50b1e06428e96e33676d5c072172ce46f4d05\", \"viz_unittests\": \"625da7cb77e1203e6acca242e7434468932ee6a8\", \"vr_common_unittests\": \"1f682cf9924e0e71adeff40d2fdb7ce6b58bfb68\", \"vr_pixeltests\": \"c4190b773c42ee96f170412f626dc0a37a0795b8\", \"weblayer_browsertests\": \"20b0831c47a2cb472b49252b4d1a9e873336c320\", \"weblayer_unittests\": \"25b59ca36aa2b582afa209de5e5e2c2560924452\", \"wm_unittests\": \"023e91aec53ec246b30154f6a404766e39605382\", \"wtf_unittests\": \"bb4f48a17f6b1cbc32f59783305ee8cdd68012bc\", \"zlib_unittests\": \"d5df90c6dee42e9c8111b3285cd5204d00870d92\", \"zucchini_unittests\": \"3a03b86e3dc9d6c32be03bff74c1d9b2c48ccbf8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-aof9\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**net_unittests** failed because of:\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.IntermediateFromAia200Pem/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.RevocationSoftFailLeafCrlDpHttpError/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalTest.ValidityJustAfterNotBefore/CertVerifyProcWin\\n\\n- All/CertVerifyProcInternalWithNetFetchingTest.RevocationHardFailLeafCrlDpHttpError/CertVerifyProcWin\\n\\n- ...13 more failure(s) (17 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609693088294449",
"status": "COMPLETED",
"status_changed_ts": "1609693088610213",
"tags": [
"build_address:luci.chromium.ci/win-asan/16612",
"builder:win-asan",
"buildset:commit/git/13552d9caef0598b59e02d232a6f46924d5d2772",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13552d9caef0598b59e02d232a6f46924d5d2772",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024538503950272320",
"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/8859108441910096016/+/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:50e1fcf8fe7ce110",
"user_agent:luci-scheduler"
],
"updated_ts": "1609699277612477",
"url": "https://ci.chromium.org/b/8859108441910096016",
"utcnow_ts": "1609699281341780"
}
}
[18:41:21.362] Invocation finished in 1h47m27.790075461s with status FAILED