[00:42:28.364] New invocation is queued and will start shortly
[00:42:29.429] Starting the invocation (attempt 1)
[00:42:29.472] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:42:29.472] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:42:29.472] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030397826287041280",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030397826287041280",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f",
"gitiles_ref:refs/heads/master"
]
}
[00:42:30.343] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1604104949565955",
"id": "8864967764363246976",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604104950098596",
"tags": [
"build_address:luci.chromium.ci/win-asan/15375",
"builder:win-asan",
"buildset:commit/git/9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030397826287041280",
"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/8864967764363246976/+/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": "1604104950098745",
"url": "https://ci.chromium.org/b/8864967764363246976",
"utcnow_ts": "1604104950333773"
}
}
[00:42:30.343] Task URL: https://ci.chromium.org/b/8864967764363246976
[00:42:30.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:2:0) after 1m0s
[00:43:30.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:2:0)
[00:43:30.387] Timer tick, asking Buildbucket for the build status
[00:43:30.579] Build 8864967764363246976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:30.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:3:0) after 1m0s
[00:44:30.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:3:0)
[00:44:30.627] Timer tick, asking Buildbucket for the build status
[00:44:32.326] Build 8864967764363246976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:32.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:4:0) after 1m0s
[00:45:32.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:4:0)
[00:45:32.326] Timer tick, asking Buildbucket for the build status
[00:45:32.571] Build 8864967764363246976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:32.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:5:0) after 1m0s
[00:45:54.514] Received PubSub notification, asking Buildbucket for the build status
[00:45:54.584] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:32.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:5:0)
[00:46:32.610] Timer tick, asking Buildbucket for the build status
[00:46:32.701] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:32.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:7:0) after 1m0s
[00:47:32.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:7:0)
[00:47:32.766] Timer tick, asking Buildbucket for the build status
[00:47:32.877] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:32.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:8:0) after 1m0s
[00:48:32.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:8:0)
[00:48:32.933] Timer tick, asking Buildbucket for the build status
[00:48:33.006] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:33.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:9:0) after 1m0s
[00:49:33.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:9:0)
[00:49:33.162] Timer tick, asking Buildbucket for the build status
[00:49:33.231] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:33.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:10:0) after 1m0s
[00:50:33.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:10:0)
[00:50:33.208] Timer tick, asking Buildbucket for the build status
[00:50:33.346] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:33.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:11:0) after 1m0s
[00:51:33.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:11:0)
[00:51:33.391] Timer tick, asking Buildbucket for the build status
[00:51:33.465] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:33.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:12:0) after 1m0s
[00:52:33.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:12:0)
[00:52:33.497] Timer tick, asking Buildbucket for the build status
[00:52:33.990] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:33.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:13:0) after 1m0s
[00:53:34.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:13:0)
[00:53:34.041] Timer tick, asking Buildbucket for the build status
[00:53:34.114] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:34.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:14:0) after 1m0s
[00:54:34.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:14:0)
[00:54:34.176] Timer tick, asking Buildbucket for the build status
[00:54:34.251] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:34.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:15:0) after 1m0s
[00:55:34.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:15:0)
[00:55:34.296] Timer tick, asking Buildbucket for the build status
[00:55:34.440] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:34.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:16:0) after 1m0s
[00:56:34.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:16:0)
[00:56:34.458] Timer tick, asking Buildbucket for the build status
[00:56:34.568] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:34.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:17:0) after 1m0s
[00:57:34.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:17:0)
[00:57:34.584] Timer tick, asking Buildbucket for the build status
[00:57:34.729] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:34.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:18:0) after 1m0s
[00:58:34.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:18:0)
[00:58:34.754] Timer tick, asking Buildbucket for the build status
[00:58:34.892] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:34.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:19:0) after 1m0s
[00:59:34.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:19:0)
[00:59:34.917] Timer tick, asking Buildbucket for the build status
[00:59:35.027] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:35.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:20:0) after 1m0s
[01:00:35.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:20:0)
[01:00:35.119] Timer tick, asking Buildbucket for the build status
[01:00:35.269] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:35.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:21:0) after 1m0s
[01:01:35.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:21:0)
[01:01:35.327] Timer tick, asking Buildbucket for the build status
[01:01:35.662] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:35.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:22:0) after 1m0s
[01:02:35.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:22:0)
[01:02:35.796] Timer tick, asking Buildbucket for the build status
[01:02:36.882] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:36.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:23:0) after 1m0s
[01:03:37.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:23:0)
[01:03:37.017] Timer tick, asking Buildbucket for the build status
[01:03:37.124] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:37.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:24:0) after 1m0s
[01:04:37.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:24:0)
[01:04:37.106] Timer tick, asking Buildbucket for the build status
[01:04:37.183] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:37.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:25:0) after 1m0s
[01:05:37.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:25:0)
[01:05:37.333] Timer tick, asking Buildbucket for the build status
[01:05:37.435] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:37.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:26:0) after 1m0s
[01:06:37.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:26:0)
[01:06:37.459] Timer tick, asking Buildbucket for the build status
[01:06:37.528] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:37.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:27:0) after 1m0s
[01:07:37.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:27:0)
[01:07:37.619] Timer tick, asking Buildbucket for the build status
[01:07:37.773] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:37.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:28:0) after 1m0s
[01:08:37.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:28:0)
[01:08:37.849] Timer tick, asking Buildbucket for the build status
[01:08:37.993] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:37.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:29:0) after 1m0s
[01:09:38.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:29:0)
[01:09:38.423] Timer tick, asking Buildbucket for the build status
[01:09:38.761] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:38.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:30:0) after 1m0s
[01:10:38.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:30:0)
[01:10:38.787] Timer tick, asking Buildbucket for the build status
[01:10:38.880] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:38.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:31:0) after 1m0s
[01:11:38.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:31:0)
[01:11:38.960] Timer tick, asking Buildbucket for the build status
[01:11:39.105] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:39.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:32:0) after 1m0s
[01:12:39.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:32:0)
[01:12:39.167] Timer tick, asking Buildbucket for the build status
[01:12:39.334] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:39.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:33:0) after 1m0s
[01:13:39.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:33:0)
[01:13:39.478] Timer tick, asking Buildbucket for the build status
[01:13:39.542] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:39.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:34:0) after 1m0s
[01:14:39.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:34:0)
[01:14:39.620] Timer tick, asking Buildbucket for the build status
[01:14:39.918] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:39.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:35:0) after 1m0s
[01:15:39.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:35:0)
[01:15:39.925] Timer tick, asking Buildbucket for the build status
[01:15:39.984] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:39.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:36:0) after 1m0s
[01:16:40.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:36:0)
[01:16:40.039] Timer tick, asking Buildbucket for the build status
[01:16:40.108] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:40.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:37:0) after 1m0s
[01:17:40.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:37:0)
[01:17:40.181] Timer tick, asking Buildbucket for the build status
[01:17:40.327] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:40.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:38:0) after 1m0s
[01:18:40.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:38:0)
[01:18:40.466] Timer tick, asking Buildbucket for the build status
[01:18:40.542] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:40.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:39:0) after 1m0s
[01:19:40.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:39:0)
[01:19:40.607] Timer tick, asking Buildbucket for the build status
[01:19:40.688] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:40.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:40:0) after 1m0s
[01:20:40.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:40:0)
[01:20:40.743] Timer tick, asking Buildbucket for the build status
[01:20:40.817] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:40.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:41:0) after 1m0s
[01:21:40.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:41:0)
[01:21:40.896] Timer tick, asking Buildbucket for the build status
[01:21:41.027] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:41.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:42:0) after 1m0s
[01:22:41.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:42:0)
[01:22:41.064] Timer tick, asking Buildbucket for the build status
[01:22:41.198] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:41.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:43:0) after 1m0s
[01:23:41.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:43:0)
[01:23:41.208] Timer tick, asking Buildbucket for the build status
[01:23:41.512] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:41.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:44:0) after 1m0s
[01:24:41.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:44:0)
[01:24:41.540] Timer tick, asking Buildbucket for the build status
[01:24:41.636] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:41.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:45:0) after 1m0s
[01:25:41.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:45:0)
[01:25:41.767] Timer tick, asking Buildbucket for the build status
[01:25:41.861] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:41.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:46:0) after 1m0s
[01:26:41.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:46:0)
[01:26:41.958] Timer tick, asking Buildbucket for the build status
[01:26:42.649] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:42.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:47:0) after 1m0s
[01:27:42.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:47:0)
[01:27:42.847] Timer tick, asking Buildbucket for the build status
[01:27:42.965] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:42.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:48:0) after 1m0s
[01:28:43.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:48:0)
[01:28:43.068] Timer tick, asking Buildbucket for the build status
[01:28:43.178] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:43.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:49:0) after 1m0s
[01:29:43.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:49:0)
[01:29:43.212] Timer tick, asking Buildbucket for the build status
[01:29:43.355] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:43.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:50:0) after 1m0s
[01:30:43.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:50:0)
[01:30:43.423] Timer tick, asking Buildbucket for the build status
[01:30:43.596] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:43.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:51:0) after 1m0s
[01:31:43.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:51:0)
[01:31:43.752] Timer tick, asking Buildbucket for the build status
[01:31:43.888] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:43.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:52:0) after 1m0s
[01:32:43.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:52:0)
[01:32:43.973] Timer tick, asking Buildbucket for the build status
[01:32:44.133] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:44.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:53:0) after 1m0s
[01:33:44.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:53:0)
[01:33:44.156] Timer tick, asking Buildbucket for the build status
[01:33:44.427] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:44.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:54:0) after 1m0s
[01:34:44.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:54:0)
[01:34:44.490] Timer tick, asking Buildbucket for the build status
[01:34:44.675] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:44.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:55:0) after 1m0s
[01:35:44.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:55:0)
[01:35:44.756] Timer tick, asking Buildbucket for the build status
[01:35:44.967] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:44.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:56:0) after 1m0s
[01:36:45.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:56:0)
[01:36:45.018] Timer tick, asking Buildbucket for the build status
[01:36:45.636] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:45.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:57:0) after 1m0s
[01:37:45.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:57:0)
[01:37:45.715] Timer tick, asking Buildbucket for the build status
[01:37:45.840] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:45.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:58:0) after 1m0s
[01:38:45.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:58:0)
[01:38:45.829] Timer tick, asking Buildbucket for the build status
[01:38:46.320] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:46.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:59:0) after 1m0s
[01:39:46.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:59:0)
[01:39:46.411] Timer tick, asking Buildbucket for the build status
[01:39:46.893] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:46.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:60:0) after 1m0s
[01:40:47.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:60:0)
[01:40:47.022] Timer tick, asking Buildbucket for the build status
[01:40:47.132] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:47.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:61:0) after 1m0s
[01:41:47.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:61:0)
[01:41:47.279] Timer tick, asking Buildbucket for the build status
[01:41:47.405] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:47.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:62:0) after 1m0s
[01:42:47.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:62:0)
[01:42:47.518] Timer tick, asking Buildbucket for the build status
[01:42:48.207] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:48.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:63:0) after 1m0s
[01:43:48.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:63:0)
[01:43:48.251] Timer tick, asking Buildbucket for the build status
[01:43:48.459] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:48.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:64:0) after 1m0s
[01:44:48.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:64:0)
[01:44:48.498] Timer tick, asking Buildbucket for the build status
[01:44:48.642] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:48.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:65:0) after 1m0s
[01:45:48.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:65:0)
[01:45:48.692] Timer tick, asking Buildbucket for the build status
[01:45:48.871] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:48.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:66:0) after 1m0s
[01:46:48.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:66:0)
[01:46:48.896] Timer tick, asking Buildbucket for the build status
[01:46:48.979] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:48.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:67:0) after 1m0s
[01:47:49.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:67:0)
[01:47:49.033] Timer tick, asking Buildbucket for the build status
[01:47:49.497] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:68:0) after 1m0s
[01:48:49.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:68:0)
[01:48:49.532] Timer tick, asking Buildbucket for the build status
[01:48:49.669] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:49.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:69:0) after 1m0s
[01:49:49.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:69:0)
[01:49:49.742] Timer tick, asking Buildbucket for the build status
[01:49:49.878] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:49.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:70:0) after 1m0s
[01:50:49.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:70:0)
[01:50:49.932] Timer tick, asking Buildbucket for the build status
[01:50:50.082] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:50.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:71:0) after 1m0s
[01:51:50.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:71:0)
[01:51:50.112] Timer tick, asking Buildbucket for the build status
[01:51:50.186] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:50.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:72:0) after 1m0s
[01:52:50.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:72:0)
[01:52:50.232] Timer tick, asking Buildbucket for the build status
[01:52:50.689] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:50.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:73:0) after 1m0s
[01:53:50.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:73:0)
[01:53:50.728] Timer tick, asking Buildbucket for the build status
[01:53:50.877] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:50.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:74:0) after 1m0s
[01:54:50.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:74:0)
[01:54:50.901] Timer tick, asking Buildbucket for the build status
[01:54:51.013] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:51.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:75:0) after 1m0s
[01:55:51.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:75:0)
[01:55:51.079] Timer tick, asking Buildbucket for the build status
[01:55:51.197] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:51.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:76:0) after 1m0s
[01:56:51.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:76:0)
[01:56:51.251] Timer tick, asking Buildbucket for the build status
[01:56:51.744] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:51.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:77:0) after 1m0s
[01:57:51.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:77:0)
[01:57:51.798] Timer tick, asking Buildbucket for the build status
[01:57:51.920] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:51.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:78:0) after 1m0s
[01:58:51.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:78:0)
[01:58:51.976] Timer tick, asking Buildbucket for the build status
[01:58:52.064] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:52.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:79:0) after 1m0s
[01:59:52.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:79:0)
[01:59:52.233] Timer tick, asking Buildbucket for the build status
[01:59:52.542] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:52.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:80:0) after 1m0s
[02:00:52.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:80:0)
[02:00:52.683] Timer tick, asking Buildbucket for the build status
[02:00:52.841] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:52.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:81:0) after 1m0s
[02:01:52.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:81:0)
[02:01:52.999] Timer tick, asking Buildbucket for the build status
[02:01:53.180] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:53.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:82:0) after 1m0s
[02:02:53.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:82:0)
[02:02:53.211] Timer tick, asking Buildbucket for the build status
[02:02:53.354] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:53.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:83:0) after 1m0s
[02:03:53.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:83:0)
[02:03:53.517] Timer tick, asking Buildbucket for the build status
[02:03:53.633] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:53.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:84:0) after 1m0s
[02:04:53.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:84:0)
[02:04:53.715] Timer tick, asking Buildbucket for the build status
[02:04:54.194] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:54.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:85:0) after 1m0s
[02:05:54.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:85:0)
[02:05:54.325] Timer tick, asking Buildbucket for the build status
[02:05:54.396] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:54.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:86:0) after 1m0s
[02:06:54.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:86:0)
[02:06:54.415] Timer tick, asking Buildbucket for the build status
[02:06:54.539] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:54.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:87:0) after 1m0s
[02:07:54.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:87:0)
[02:07:54.653] Timer tick, asking Buildbucket for the build status
[02:07:54.938] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:54.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:88:0) after 1m0s
[02:08:55.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:88:0)
[02:08:55.023] Timer tick, asking Buildbucket for the build status
[02:08:55.397] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:55.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:89:0) after 1m0s
[02:09:55.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:89:0)
[02:09:55.500] Timer tick, asking Buildbucket for the build status
[02:09:55.616] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:55.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:90:0) after 1m0s
[02:10:55.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:90:0)
[02:10:55.638] Timer tick, asking Buildbucket for the build status
[02:10:55.740] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:55.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:91:0) after 1m0s
[02:11:55.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:91:0)
[02:11:55.796] Timer tick, asking Buildbucket for the build status
[02:11:55.869] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:55.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:92:0) after 1m0s
[02:12:55.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:92:0)
[02:12:55.894] Timer tick, asking Buildbucket for the build status
[02:12:56.173] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:56.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:93:0) after 1m0s
[02:13:56.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:93:0)
[02:13:56.249] Timer tick, asking Buildbucket for the build status
[02:13:56.338] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:56.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:94:0) after 1m0s
[02:14:56.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:94:0)
[02:14:56.413] Timer tick, asking Buildbucket for the build status
[02:14:56.796] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:56.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:95:0) after 1m0s
[02:15:56.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:95:0)
[02:15:56.822] Timer tick, asking Buildbucket for the build status
[02:15:56.907] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:56.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:96:0) after 1m0s
[02:16:56.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:96:0)
[02:16:56.974] Timer tick, asking Buildbucket for the build status
[02:16:57.047] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:57.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:97:0) after 1m0s
[02:17:57.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:97:0)
[02:17:57.065] Timer tick, asking Buildbucket for the build status
[02:17:57.515] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:57.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:98:0) after 1m0s
[02:18:57.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:98:0)
[02:18:57.586] Timer tick, asking Buildbucket for the build status
[02:18:57.663] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:57.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:99:0) after 1m0s
[02:19:57.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:99:0)
[02:19:57.663] Timer tick, asking Buildbucket for the build status
[02:19:58.332] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:58.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:100:0) after 1m0s
[02:20:58.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:100:0)
[02:20:58.357] Timer tick, asking Buildbucket for the build status
[02:20:58.443] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:58.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:101:0) after 1m0s
[02:21:58.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:101:0)
[02:21:58.521] Timer tick, asking Buildbucket for the build status
[02:21:59.065] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:59.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:102:0) after 1m0s
[02:22:59.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:102:0)
[02:22:59.095] Timer tick, asking Buildbucket for the build status
[02:22:59.227] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:59.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:103:0) after 1m0s
[02:23:59.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:103:0)
[02:23:59.245] Timer tick, asking Buildbucket for the build status
[02:23:59.319] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:59.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:104:0) after 1m0s
[02:24:59.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:104:0)
[02:24:59.380] Timer tick, asking Buildbucket for the build status
[02:24:59.600] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:59.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:105:0) after 1m0s
[02:25:59.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:105:0)
[02:25:59.680] Timer tick, asking Buildbucket for the build status
[02:25:59.763] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:59.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:106:0) after 1m0s
[02:26:59.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:106:0)
[02:26:59.796] Timer tick, asking Buildbucket for the build status
[02:26:59.867] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:59.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:107:0) after 1m0s
[02:27:59.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:107:0)
[02:27:59.882] Timer tick, asking Buildbucket for the build status
[02:27:59.997] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:59.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:108:0) after 1m0s
[02:29:00.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:108:0)
[02:29:00.024] Timer tick, asking Buildbucket for the build status
[02:29:00.494] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:00.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:109:0) after 1m0s
[02:30:00.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:109:0)
[02:30:00.541] Timer tick, asking Buildbucket for the build status
[02:30:00.970] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:00.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:110:0) after 1m0s
[02:31:02.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:110:0)
[02:31:02.189] Timer tick, asking Buildbucket for the build status
[02:31:02.271] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:02.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:111:0) after 1m0s
[02:32:02.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:111:0)
[02:32:02.299] Timer tick, asking Buildbucket for the build status
[02:32:02.427] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:02.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:112:0) after 1m0s
[02:33:02.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:112:0)
[02:33:02.504] Timer tick, asking Buildbucket for the build status
[02:33:02.711] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:02.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:113:0) after 1m0s
[02:34:02.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:113:0)
[02:34:02.757] Timer tick, asking Buildbucket for the build status
[02:34:02.901] Build 8864967764363246976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:02.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030397826287041280:114:0) after 1m0s
[02:34:40.136] Received PubSub notification, asking Buildbucket for the build status
[02:34:40.232] Build 8864967764363246976: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:34:40.232] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1604111679603150",
"created_by": "project:chromium",
"created_ts": "1604104949565955",
"failure_reason": "BUILD_FAILURE",
"id": "8864967764363246976",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**gcp_unittests** failed because of:\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21519\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21518\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21515\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21514\\n\\n- ...27471 more failure(s) (27475 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\": \"9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f\", \"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-9-6o02\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15375, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"42ad027982404039e6c9a921b2f6354f55b65a2e\", \"got_dawn_revision\": \"5a1d786754ad865c1b72c46e4f9c0d3dcc19aa72\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f\", \"got_revision_cp\": \"refs/heads/master@{#822908}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"96789f0aa67d93ea125191724666dab1be5a639c\", \"got_v8_revision_cp\": \"refs/heads/8.8.213@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f\", \"swarm_hashes_refs/heads/master(at){#822908}_without_patch\": {\"absl_hardening_tests\": \"2e87095e0cfd1a019732f4e3df2144ba0d1d1193\", \"accessibility_unittests\": \"81e4ed3667a39b5f56f95f611544a31a0e79f33d\", \"angle_unittests\": \"e7c519629863f357e86274b60a8f9645b7e2aa6a\", \"app_shell_unittests\": \"3eb6d640943ba88e354e3c14f792bd976dc34f96\", \"aura_unittests\": \"2a3d9e37243246046f5aad5ba7215995d514e6a6\", \"base_unittests\": \"135d627fc108413670741f797f7d4ec839401137\", \"base_util_unittests\": \"2f655ed18611b9e4f9479582570ce148a5318f10\", \"blink_common_unittests\": \"14ab1efc75830a75984744f8fe9812b8810bceb7\", \"blink_fuzzer_unittests\": \"e4f5e0aa98a332d7a9269739b45a2995764023a1\", \"blink_heap_unittests\": \"d5ac85215ce8fda1df353b40119e9b820cec256c\", \"blink_platform_unittests\": \"96c088e4ddb469d852f9c9f1568262c6d4f2ca8b\", \"blink_unittests\": \"e9f90342a0707d73a24fb88c2021788c67a9d20e\", \"boringssl_crypto_tests\": \"f954a0474e0c951eabf71a0ecd3ace7945442ac1\", \"boringssl_ssl_tests\": \"25afe0e19165337e666298d56c5f05655a054e61\", \"browser_switcher_bho_unittests\": \"e8449b36a0d138079c37936084f57e2d505af067\", \"browser_tests\": \"14f42ce90d39fec9b980640c7c25aa56fd37586b\", \"capture_unittests\": \"111b8abcf7aeb2728aa3a447b56e50cdbdbd77d1\", \"cast_unittests\": \"3689d332bf9d5214a555f9435b9fcb03269df286\", \"cc_unittests\": \"4eed670f7b88720608bf74c029a1733d976ec82b\", \"chrome_app_unittests\": \"0b1528943ab28b09e6b9d6a5495d843a4da7e039\", \"chrome_cleaner_unittests\": \"2820c868eac44bab925d2fef1febf953a5aff504\", \"chrome_elf_unittests\": \"e0bbf9d6100da71f4f673fb5ad9e961effcc545e\", \"chromedriver_unittests\": \"9bf5876e8380f279ac9649d51365e3355eebe46a\", \"components_browsertests\": \"bb1d9ffb4e6d2bd25f7c4a876e9c84109eec9c45\", \"components_unittests\": \"4ad7baa800d375df043014448e7b5d34f82c1f34\", \"compositor_unittests\": \"4e11da818d6a11660936254e46d7b333916cba81\", \"content_browsertests\": \"0843aa163e695806c475c06a43d9145eecfa247f\", \"content_unittests\": \"f6577fd4e1e25cd8928e086166e742b18f266a71\", \"courgette_unittests\": \"e1851874035fad09eede300f69e440e426cc9a84\", \"crashpad_tests\": \"6adbadaf0746ee76b1be2857abd178e23a6ac968\", \"cronet_tests\": \"024ef5b05141dc0c1af8e183015b584b03a5fbf1\", \"cronet_unittests\": \"335920b99b58a47b0907ad902a326532d6282de8\", \"crypto_unittests\": \"97c26779384144af2f8ef63ce6d0317cecc21130\", \"delayloads_unittests\": \"b8b3a747e574face3d56cd071cb3a04d1de48d53\", \"device_unittests\": \"e86e5fc7a6954db5d3f3bd3df14779c1aeaaa7fa\", \"display_unittests\": \"9c86e7e599a9ca8e846e0873ee5236b9c6ad0345\", \"elevation_service_unittests\": \"0e7162ddf927a8b86990a6d3bbab1e7f1a30a3f7\", \"events_unittests\": \"2efc252adee37c51e0ee026c79ec950c227e5861\", \"extensions_browsertests\": \"64ae81007c67a112d71406afc8304b4bee1acef7\", \"extensions_unittests\": \"f82445ee0deca30cf4622c688781db76f90d3553\", \"filesystem_service_unittests\": \"d15fe72eb08faf348dbbdf307cfe5fd648f87915\", \"gcm_unit_tests\": \"b49924b1fdb1ccccad035fd0dd9f82119bd43b3f\", \"gcp_unittests\": \"68e78b5fb2bf37de845ea60e72902c4ae5b691f4\", \"gfx_unittests\": \"44c5837d36eba6b18d47ba9f76703a9c9d0fe0be\", \"gin_unittests\": \"ff7b75cfb89aec54dc51fb25c56bf0bbd32ee597\", \"google_apis_unittests\": \"f76ab500329bb910f394fb463488e70f158f49a8\", \"gpu_unittests\": \"61f6b511022cd7238b982c733f2be777782581ea\", \"gwp_asan_unittests\": \"f9d8bd400a75a882e1db54b1ccfc7bdf09c893c7\", \"headless_browsertests\": \"e4196a86260bdf027be0bf2813d063b79802be50\", \"headless_unittests\": \"b7288e8b9e41a1b8c79c27f2455d4c9424e635ee\", \"install_static_unittests\": \"2e4f35970ad34784dbfb5673ede6872569982636\", \"installer_util_unittests\": \"2331571f2456883d42b960860d9bb319db85b310\", \"interactive_ui_tests\": \"8d682abf0fbfdf360421ee9bbc98ad7c7001ffba\", \"ipc_tests\": \"ec9c7e77369afd93901baf4dd4a2053b3f2c5644\", \"jingle_unittests\": \"6c1e759c321bdb5e417b0358d037feea2f88732c\", \"latency_unittests\": \"5eb5d837fde7a8fa422702ee5e338d9dfbcd2e4c\", \"libjingle_xmpp_unittests\": \"d5b38e2a48632994941b722a9f847a1c4351acd9\", \"liburlpattern_unittests\": \"571557cd2869ce025cae59516e1568c8e727f443\", \"media_blink_unittests\": \"64987affb1f008d02834d85f78522cdedad28500\", \"media_unittests\": \"6fe8e602f6f1df4e4b1d37054f8dadc6ac9c3d01\", \"message_center_unittests\": \"ef10b184b3ce306b89a93e42f2bd0195f19605bf\", \"midi_unittests\": \"812fefecc99d63bda09bf1357deb70046e562f5a\", \"mojo_core_unittests\": \"036371f1d20a0aaf7c0687c8c67c3d752aeef641\", \"mojo_unittests\": \"c3af107a541811c3422cc06eec6e189ea6806653\", \"nacl_loader_unittests\": \"b94466d9aa2b1a9cd6595ee249ceaf6b66a4cef4\", \"native_theme_unittests\": \"73fefb6f27191bb414d338f13962710101476512\", \"net_unittests\": \"ad8ef919a84abfb52dcec3be9a5070de62ad6de9\", \"notification_helper_unittests\": \"4295f5ba8cf5b2e6117d57d3642e16e7974a287f\", \"pdf_unittests\": \"42ec56f83e56d4369da29b4c5abb6862ca36b054\", \"perfetto_unittests\": \"02bc4e682caffe0df7a165fa5d5cbca04ae3a334\", \"ppapi_unittests\": \"b784ca6919a3e3de10cce16406ea3b5b404f4909\", \"printing_unittests\": \"b0b394fce980ccc414939e1667000999d187d7a4\", \"remoting_unittests\": \"9c72f4fb109d53faf34455e24a6da594ca0e7180\", \"sbox_integration_tests\": \"70079cef1b6e6d92358e111d64b4fca831d53218\", \"sbox_unittests\": \"7bc463a504b666d16a56e2d8ef385ef7eadfa45b\", \"sbox_validation_tests\": \"13ffa381e23f0df3dffd5c54d87330229d90de26\", \"service_manager_unittests\": \"2b96a1bc174a892150dbf24693ee1173cd80dcbb\", \"services_unittests\": \"71a835d9e71f2788e800866a4f82ad53e0437042\", \"setup_unittests\": \"3df998232b686ef3707f92c9396665701feaf768\", \"shell_dialogs_unittests\": \"4f689fa63a7d4a13f95b713b246302d5cf082a28\", \"skia_unittests\": \"f9e0906e54459cefd4a84e595933c74b3006c15c\", \"snapshot_unittests\": \"0216c4a05d599905fdb5fa7cf295bbbd9370c2e0\", \"sql_unittests\": \"99a34037fc236b9e774fdda1c598529f8f61666b\", \"storage_unittests\": \"575f93252559c96dacee05f9c2ec87971b604bc1\", \"sync_integration_tests\": \"c5599a11c5b72b73d8e5038959676720c7115c52\", \"traffic_annotation_auditor_unittests\": \"c20080a1dbd8ff6ae66f82a1b6ae04da9c1b5855\", \"ui_base_unittests\": \"6ad04b8e364cb85b416d45d0fdaafa698c960960\", \"ui_touch_selection_unittests\": \"084903e6950db90cbac1ccda01e862bbf97c1baa\", \"unit_tests\": \"8dd437bac339cfdb421cc5e2b18105bd44bc654a\", \"updater_tests\": \"9255dc9e64abfd59e6f3e5df34ca62f533c0a91b\", \"url_unittests\": \"f200faac86020c16f13b51293e548cdbd1e21026\", \"views_unittests\": \"0901963e5ff252c05e3cc660a456774c026e10de\", \"viz_unittests\": \"d67eb3c8e563065a4d7dc7bd78e5694b6a9fce28\", \"vr_common_unittests\": \"aca5eceaf54fcc695424d24320aef191578409e3\", \"vr_pixeltests\": \"6c91939a2e163076baa19cd977dbe4658f68c343\", \"weblayer_browsertests\": \"a84ecb9a4f60dcf83cd24b846e420cb24c7b3c61\", \"weblayer_unittests\": \"49dc3e98427b29f2a680f4accd406ac94cd3ac0a\", \"wm_unittests\": \"f8eba6475362c52c89534e2b47a81d0963cc207a\", \"wtf_unittests\": \"88ebaeb1f7e31e41be3b70f659e627e48dcb6c72\", \"zlib_unittests\": \"8fe9ed9c79fd1ffe3851bdfe39929c98a15a5da0\", \"zucchini_unittests\": \"6f1776b1906677f2577d20dd753e7b8b83883057\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-9-6o02\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**gcp_unittests** failed because of:\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21519\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21518\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21515\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21514\\n\\n- ...27471 more failure(s) (27475 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604105153225281",
"status": "COMPLETED",
"status_changed_ts": "1604105153613691",
"tags": [
"build_address:luci.chromium.ci/win-asan/15375",
"builder:win-asan",
"buildset:commit/git/9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9287e2cd3a6b75b0f640a1c0b4c198aa5d7c9c1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030397826287041280",
"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/8864967764363246976/+/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:4f94ec9b49d61010",
"user_agent:luci-scheduler"
],
"updated_ts": "1604111679861369",
"url": "https://ci.chromium.org/b/8864967764363246976",
"utcnow_ts": "1604111680221666"
}
}
[02:34:40.232] Invocation finished in 1h52m11.884641324s with status FAILED