[23:16:04.522] New invocation is queued and will start shortly
[23:16:05.755] Starting the invocation (attempt 1)
[23:16:05.793] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:16:05.794] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:16:05.794] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030312664972898864",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a55038d103667a7011f699ffb6f080e6370d88e7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030312664972898864",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a55038d103667a7011f699ffb6f080e6370d88e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a55038d103667a7011f699ffb6f080e6370d88e7",
"gitiles_ref:refs/heads/master"
]
}
[23:16:06.979] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604186165946713",
"id": "8864882602815752528",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a55038d103667a7011f699ffb6f080e6370d88e7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604186166847952",
"tags": [
"build_address:luci.chromium.ci/win-asan/15386",
"builder:win-asan",
"buildset:commit/git/a55038d103667a7011f699ffb6f080e6370d88e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a55038d103667a7011f699ffb6f080e6370d88e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030312664972898864",
"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/8864882602815752528/+/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": "1604186166848061",
"url": "https://ci.chromium.org/b/8864882602815752528",
"utcnow_ts": "1604186166971838"
}
}
[23:16:06.979] Task URL: https://ci.chromium.org/b/8864882602815752528
[23:16:06.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:2:0) after 1m0s
[23:17:07.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:2:0)
[23:17:07.031] Timer tick, asking Buildbucket for the build status
[23:17:07.137] Build 8864882602815752528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:07.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:3:0) after 1m0s
[23:18:07.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:3:0)
[23:18:07.175] Timer tick, asking Buildbucket for the build status
[23:18:07.406] Build 8864882602815752528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:07.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:4:0) after 1m0s
[23:19:07.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:4:0)
[23:19:07.406] Timer tick, asking Buildbucket for the build status
[23:19:07.583] Build 8864882602815752528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:07.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:5:0) after 1m0s
[23:19:25.113] Received PubSub notification, asking Buildbucket for the build status
[23:19:25.223] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:07.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:5:0)
[23:20:07.655] Timer tick, asking Buildbucket for the build status
[23:20:08.182] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:08.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:7:0) after 1m0s
[23:21:08.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:7:0)
[23:21:08.205] Timer tick, asking Buildbucket for the build status
[23:21:08.327] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:08.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:8:0) after 1m0s
[23:22:08.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:8:0)
[23:22:08.345] Timer tick, asking Buildbucket for the build status
[23:22:08.689] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:08.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:9:0) after 1m0s
[23:23:08.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:9:0)
[23:23:08.708] Timer tick, asking Buildbucket for the build status
[23:23:08.813] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:08.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:10:0) after 1m0s
[23:24:08.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:10:0)
[23:24:08.855] Timer tick, asking Buildbucket for the build status
[23:24:08.926] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:08.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:11:0) after 1m0s
[23:25:08.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:11:0)
[23:25:08.968] Timer tick, asking Buildbucket for the build status
[23:25:09.033] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:09.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:12:0) after 1m0s
[23:26:09.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:12:0)
[23:26:09.050] Timer tick, asking Buildbucket for the build status
[23:26:09.387] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:09.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:13:0) after 1m0s
[23:27:09.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:13:0)
[23:27:09.410] Timer tick, asking Buildbucket for the build status
[23:27:13.414] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:13.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:14:0) after 1m0s
[23:28:13.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:14:0)
[23:28:13.524] Timer tick, asking Buildbucket for the build status
[23:28:13.623] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:13.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:15:0) after 1m0s
[23:29:13.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:15:0)
[23:29:13.701] Timer tick, asking Buildbucket for the build status
[23:29:13.809] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:13.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:16:0) after 1m0s
[23:30:13.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:16:0)
[23:30:13.902] Timer tick, asking Buildbucket for the build status
[23:30:14.099] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:14.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:17:0) after 1m0s
[23:31:14.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:17:0)
[23:31:14.121] Timer tick, asking Buildbucket for the build status
[23:31:14.256] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:14.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:18:0) after 1m0s
[23:32:14.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:18:0)
[23:32:14.297] Timer tick, asking Buildbucket for the build status
[23:32:14.640] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:14.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:19:0) after 1m0s
[23:33:14.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:19:0)
[23:33:14.752] Timer tick, asking Buildbucket for the build status
[23:33:14.854] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:14.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:20:0) after 1m0s
[23:34:14.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:20:0)
[23:34:14.933] Timer tick, asking Buildbucket for the build status
[23:34:15.493] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:15.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:21:0) after 1m0s
[23:35:15.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:21:0)
[23:35:15.509] Timer tick, asking Buildbucket for the build status
[23:35:15.655] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:15.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:22:0) after 1m0s
[23:36:15.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:22:0)
[23:36:15.798] Timer tick, asking Buildbucket for the build status
[23:36:15.978] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:15.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:23:0) after 1m0s
[23:37:16.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:23:0)
[23:37:16.025] Timer tick, asking Buildbucket for the build status
[23:37:16.083] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:16.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:24:0) after 1m0s
[23:38:16.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:24:0)
[23:38:16.396] Timer tick, asking Buildbucket for the build status
[23:38:16.457] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:16.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:25:0) after 1m0s
[23:39:16.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:25:0)
[23:39:16.745] Timer tick, asking Buildbucket for the build status
[23:39:16.934] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:16.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:26:0) after 1m0s
[23:40:17.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:26:0)
[23:40:17.063] Timer tick, asking Buildbucket for the build status
[23:40:17.230] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:17.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:27:0) after 1m0s
[23:41:17.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:27:0)
[23:41:17.375] Timer tick, asking Buildbucket for the build status
[23:41:17.812] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:17.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:28:0) after 1m0s
[23:42:18.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:28:0)
[23:42:18.018] Timer tick, asking Buildbucket for the build status
[23:42:18.306] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:18.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:29:0) after 1m0s
[23:43:18.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:29:0)
[23:43:18.303] Timer tick, asking Buildbucket for the build status
[23:43:18.539] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:18.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:30:0) after 1m0s
[23:44:18.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:30:0)
[23:44:18.577] Timer tick, asking Buildbucket for the build status
[23:44:18.889] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:18.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:31:0) after 1m0s
[23:45:18.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:31:0)
[23:45:18.967] Timer tick, asking Buildbucket for the build status
[23:45:19.052] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:19.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:32:0) after 1m0s
[23:46:19.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:32:0)
[23:46:19.038] Timer tick, asking Buildbucket for the build status
[23:46:19.178] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:19.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:33:0) after 1m0s
[23:47:19.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:33:0)
[23:47:19.202] Timer tick, asking Buildbucket for the build status
[23:47:19.862] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:19.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:34:0) after 1m0s
[23:48:20.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:34:0)
[23:48:20.085] Timer tick, asking Buildbucket for the build status
[23:48:20.238] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:20.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:35:0) after 1m0s
[23:49:20.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:35:0)
[23:49:20.368] Timer tick, asking Buildbucket for the build status
[23:49:20.783] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:20.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:36:0) after 1m0s
[23:50:20.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:36:0)
[23:50:20.804] Timer tick, asking Buildbucket for the build status
[23:50:20.880] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:20.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:37:0) after 1m0s
[23:51:20.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:37:0)
[23:51:20.952] Timer tick, asking Buildbucket for the build status
[23:51:21.125] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:21.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:38:0) after 1m0s
[23:52:21.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:38:0)
[23:52:21.184] Timer tick, asking Buildbucket for the build status
[23:52:21.390] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:21.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:39:0) after 1m0s
[23:53:21.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:39:0)
[23:53:21.413] Timer tick, asking Buildbucket for the build status
[23:53:21.485] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:21.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:40:0) after 1m0s
[23:54:21.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:40:0)
[23:54:21.509] Timer tick, asking Buildbucket for the build status
[23:54:21.614] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:21.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:41:0) after 1m0s
[23:55:21.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:41:0)
[23:55:21.634] Timer tick, asking Buildbucket for the build status
[23:55:21.732] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:21.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:42:0) after 1m0s
[23:56:21.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:42:0)
[23:56:21.761] Timer tick, asking Buildbucket for the build status
[23:56:22.262] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:22.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:43:0) after 1m0s
[23:57:22.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:43:0)
[23:57:22.421] Timer tick, asking Buildbucket for the build status
[23:57:22.652] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:22.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:44:0) after 1m0s
[23:58:22.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:44:0)
[23:58:22.720] Timer tick, asking Buildbucket for the build status
[23:58:22.795] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:22.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:45:0) after 1m0s
[23:59:22.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:45:0)
[23:59:22.817] Timer tick, asking Buildbucket for the build status
[23:59:22.901] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:22.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:46:0) after 1m0s
[00:00:22.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:46:0)
[00:00:22.973] Timer tick, asking Buildbucket for the build status
[00:00:23.318] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:23.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:47:0) after 1m0s
[00:01:23.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:47:0)
[00:01:23.525] Timer tick, asking Buildbucket for the build status
[00:01:23.629] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:23.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:48:0) after 1m0s
[00:02:23.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:48:0)
[00:02:23.646] Timer tick, asking Buildbucket for the build status
[00:02:23.989] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:23.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:49:0) after 1m0s
[00:03:24.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:49:0)
[00:03:24.064] Timer tick, asking Buildbucket for the build status
[00:03:24.145] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:24.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:50:0) after 1m0s
[00:04:24.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:50:0)
[00:04:24.216] Timer tick, asking Buildbucket for the build status
[00:04:24.328] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:24.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:51:0) after 1m0s
[00:05:24.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:51:0)
[00:05:24.353] Timer tick, asking Buildbucket for the build status
[00:05:24.738] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:24.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:52:0) after 1m0s
[00:06:24.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:52:0)
[00:06:24.724] Timer tick, asking Buildbucket for the build status
[00:06:24.831] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:24.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:53:0) after 1m0s
[00:07:24.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:53:0)
[00:07:24.855] Timer tick, asking Buildbucket for the build status
[00:07:25.244] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:25.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:54:0) after 1m0s
[00:08:25.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:54:0)
[00:08:25.384] Timer tick, asking Buildbucket for the build status
[00:08:25.546] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:25.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:55:0) after 1m0s
[00:09:25.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:55:0)
[00:09:25.673] Timer tick, asking Buildbucket for the build status
[00:09:26.507] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:26.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:56:0) after 1m0s
[00:10:26.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:56:0)
[00:10:26.519] Timer tick, asking Buildbucket for the build status
[00:10:27.099] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:27.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:57:0) after 1m0s
[00:11:27.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:57:0)
[00:11:27.158] Timer tick, asking Buildbucket for the build status
[00:11:27.280] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:27.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:58:0) after 1m0s
[00:12:27.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:58:0)
[00:12:27.355] Timer tick, asking Buildbucket for the build status
[00:12:27.546] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:27.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:59:0) after 1m0s
[00:13:27.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:59:0)
[00:13:27.606] Timer tick, asking Buildbucket for the build status
[00:13:27.831] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:27.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:60:0) after 1m0s
[00:14:27.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:60:0)
[00:14:27.850] Timer tick, asking Buildbucket for the build status
[00:14:27.963] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:27.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:61:0) after 1m0s
[00:15:28.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:61:0)
[00:15:28.043] Timer tick, asking Buildbucket for the build status
[00:15:28.490] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:28.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:62:0) after 1m0s
[00:16:28.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:62:0)
[00:16:28.493] Timer tick, asking Buildbucket for the build status
[00:16:28.613] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:28.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:63:0) after 1m0s
[00:17:28.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:63:0)
[00:17:28.636] Timer tick, asking Buildbucket for the build status
[00:17:28.832] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:28.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:64:0) after 1m0s
[00:18:28.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:64:0)
[00:18:28.859] Timer tick, asking Buildbucket for the build status
[00:18:28.975] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:28.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:65:0) after 1m0s
[00:19:29.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:65:0)
[00:19:29.009] Timer tick, asking Buildbucket for the build status
[00:19:29.121] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:29.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:66:0) after 1m0s
[00:20:29.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:66:0)
[00:20:29.175] Timer tick, asking Buildbucket for the build status
[00:20:29.284] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:29.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:67:0) after 1m0s
[00:21:29.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:67:0)
[00:21:29.354] Timer tick, asking Buildbucket for the build status
[00:21:29.471] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:29.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:68:0) after 1m0s
[00:22:29.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:68:0)
[00:22:29.494] Timer tick, asking Buildbucket for the build status
[00:22:29.611] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:29.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:69:0) after 1m0s
[00:23:29.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:69:0)
[00:23:29.740] Timer tick, asking Buildbucket for the build status
[00:23:30.226] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:30.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:70:0) after 1m0s
[00:24:30.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:70:0)
[00:24:30.420] Timer tick, asking Buildbucket for the build status
[00:24:30.524] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:30.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:71:0) after 1m0s
[00:25:30.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:71:0)
[00:25:30.651] Timer tick, asking Buildbucket for the build status
[00:25:30.859] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:30.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:72:0) after 1m0s
[00:26:30.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:72:0)
[00:26:30.930] Timer tick, asking Buildbucket for the build status
[00:26:31.045] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:31.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:73:0) after 1m0s
[00:27:31.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:73:0)
[00:27:31.119] Timer tick, asking Buildbucket for the build status
[00:27:31.497] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:31.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:74:0) after 1m0s
[00:28:31.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:74:0)
[00:28:31.544] Timer tick, asking Buildbucket for the build status
[00:28:31.615] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:31.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:75:0) after 1m0s
[00:29:31.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:75:0)
[00:29:31.695] Timer tick, asking Buildbucket for the build status
[00:29:33.294] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:33.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:76:0) after 1m0s
[00:30:33.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:76:0)
[00:30:33.322] Timer tick, asking Buildbucket for the build status
[00:30:33.402] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:33.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:77:0) after 1m0s
[00:31:33.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:77:0)
[00:31:33.521] Timer tick, asking Buildbucket for the build status
[00:31:33.627] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:33.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:78:0) after 1m0s
[00:32:33.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:78:0)
[00:32:33.649] Timer tick, asking Buildbucket for the build status
[00:32:33.756] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:33.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:79:0) after 1m0s
[00:33:33.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:79:0)
[00:33:33.783] Timer tick, asking Buildbucket for the build status
[00:33:33.871] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:33.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:80:0) after 1m0s
[00:34:33.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:80:0)
[00:34:33.893] Timer tick, asking Buildbucket for the build status
[00:34:33.992] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:33.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:81:0) after 1m0s
[00:35:34.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:81:0)
[00:35:34.149] Timer tick, asking Buildbucket for the build status
[00:35:34.688] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:34.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:82:0) after 1m0s
[00:36:34.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:82:0)
[00:36:34.712] Timer tick, asking Buildbucket for the build status
[00:36:34.857] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:34.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:83:0) after 1m0s
[00:37:34.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:83:0)
[00:37:34.925] Timer tick, asking Buildbucket for the build status
[00:37:35.042] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:35.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:84:0) after 1m0s
[00:38:35.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:84:0)
[00:38:35.109] Timer tick, asking Buildbucket for the build status
[00:38:35.212] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:35.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:85:0) after 1m0s
[00:39:35.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:85:0)
[00:39:35.312] Timer tick, asking Buildbucket for the build status
[00:39:35.747] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:35.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:86:0) after 1m0s
[00:40:35.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:86:0)
[00:40:35.917] Timer tick, asking Buildbucket for the build status
[00:40:36.247] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:36.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:87:0) after 1m0s
[00:41:36.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:87:0)
[00:41:36.321] Timer tick, asking Buildbucket for the build status
[00:41:36.477] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:36.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:88:0) after 1m0s
[00:42:36.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:88:0)
[00:42:36.549] Timer tick, asking Buildbucket for the build status
[00:42:36.665] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:36.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:89:0) after 1m0s
[00:43:36.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:89:0)
[00:43:36.725] Timer tick, asking Buildbucket for the build status
[00:43:37.153] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:37.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:90:0) after 1m0s
[00:44:37.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:90:0)
[00:44:37.151] Timer tick, asking Buildbucket for the build status
[00:44:37.261] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:37.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:91:0) after 1m0s
[00:45:37.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:91:0)
[00:45:37.342] Timer tick, asking Buildbucket for the build status
[00:45:37.517] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:37.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:92:0) after 1m0s
[00:46:37.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:92:0)
[00:46:37.517] Timer tick, asking Buildbucket for the build status
[00:46:38.256] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:38.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:93:0) after 1m0s
[00:47:38.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:93:0)
[00:47:38.274] Timer tick, asking Buildbucket for the build status
[00:47:39.006] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:39.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:94:0) after 1m0s
[00:48:39.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:94:0)
[00:48:39.084] Timer tick, asking Buildbucket for the build status
[00:48:39.285] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:39.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:95:0) after 1m0s
[00:49:39.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:95:0)
[00:49:39.309] Timer tick, asking Buildbucket for the build status
[00:49:39.394] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:39.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:96:0) after 1m0s
[00:50:39.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:96:0)
[00:50:39.395] Timer tick, asking Buildbucket for the build status
[00:50:39.490] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:39.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:97:0) after 1m0s
[00:51:39.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:97:0)
[00:51:39.567] Timer tick, asking Buildbucket for the build status
[00:51:39.675] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:39.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:98:0) after 1m0s
[00:52:39.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:98:0)
[00:52:39.769] Timer tick, asking Buildbucket for the build status
[00:52:39.846] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:39.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:99:0) after 1m0s
[00:53:40.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:99:0)
[00:53:40.498] Timer tick, asking Buildbucket for the build status
[00:53:40.759] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:40.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:100:0) after 1m0s
[00:54:40.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:100:0)
[00:54:40.850] Timer tick, asking Buildbucket for the build status
[00:54:40.918] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:40.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:101:0) after 1m0s
[00:55:41.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:101:0)
[00:55:41.104] Timer tick, asking Buildbucket for the build status
[00:55:41.198] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:41.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:102:0) after 1m0s
[00:56:41.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:102:0)
[00:56:41.332] Timer tick, asking Buildbucket for the build status
[00:56:41.619] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:41.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:103:0) after 1m0s
[00:57:41.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:103:0)
[00:57:41.663] Timer tick, asking Buildbucket for the build status
[00:57:42.013] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:42.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:104:0) after 1m0s
[00:58:42.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:104:0)
[00:58:42.468] Timer tick, asking Buildbucket for the build status
[00:58:42.749] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:42.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:105:0) after 1m0s
[00:59:42.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:105:0)
[00:59:42.902] Timer tick, asking Buildbucket for the build status
[00:59:43.212] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:43.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:106:0) after 1m0s
[01:00:43.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:106:0)
[01:00:43.298] Timer tick, asking Buildbucket for the build status
[01:00:43.546] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:43.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:107:0) after 1m0s
[01:01:43.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:107:0)
[01:01:43.585] Timer tick, asking Buildbucket for the build status
[01:01:44.101] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:44.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:108:0) after 1m0s
[01:02:44.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:108:0)
[01:02:44.159] Timer tick, asking Buildbucket for the build status
[01:02:44.263] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:44.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:109:0) after 1m0s
[01:03:44.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:109:0)
[01:03:44.327] Timer tick, asking Buildbucket for the build status
[01:03:44.399] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:44.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:110:0) after 1m0s
[01:04:44.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:110:0)
[01:04:44.449] Timer tick, asking Buildbucket for the build status
[01:04:44.613] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:44.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:111:0) after 1m0s
[01:05:44.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:111:0)
[01:05:44.727] Timer tick, asking Buildbucket for the build status
[01:05:44.806] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:44.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:112:0) after 1m0s
[01:06:44.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:112:0)
[01:06:44.931] Timer tick, asking Buildbucket for the build status
[01:06:45.063] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:45.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:113:0) after 1m0s
[01:07:45.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:113:0)
[01:07:45.059] Timer tick, asking Buildbucket for the build status
[01:07:45.254] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:45.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:114:0) after 1m0s
[01:08:45.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:114:0)
[01:08:45.298] Timer tick, asking Buildbucket for the build status
[01:08:45.405] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:45.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:115:0) after 1m0s
[01:09:45.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:115:0)
[01:09:45.427] Timer tick, asking Buildbucket for the build status
[01:09:45.511] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:45.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:116:0) after 1m0s
[01:10:45.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:116:0)
[01:10:45.534] Timer tick, asking Buildbucket for the build status
[01:10:45.634] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:45.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:117:0) after 1m0s
[01:11:45.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:117:0)
[01:11:45.660] Timer tick, asking Buildbucket for the build status
[01:11:46.477] Build 8864882602815752528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:46.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030312664972898864:118:0) after 1m0s
[01:12:28.688] Received PubSub notification, asking Buildbucket for the build status
[01:12:29.132] Build 8864882602815752528: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:12:29.132] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604193147948077",
"created_by": "project:chromium",
"created_ts": "1604186165946713",
"failure_reason": "BUILD_FAILURE",
"id": "8864882602815752528",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a55038d103667a7011f699ffb6f080e6370d88e7\"}}",
"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\": \"a55038d103667a7011f699ffb6f080e6370d88e7\", \"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\": 15386, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"42ad027982404039e6c9a921b2f6354f55b65a2e\", \"got_dawn_revision\": \"0a374dce665328391ab3b68fd9e667b018dd9dcd\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"a55038d103667a7011f699ffb6f080e6370d88e7\", \"got_revision_cp\": \"refs/heads/master@{#822986}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"69c856dc816ff1dd65fcc536095b44399a7434b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.214@{#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\": \"a55038d103667a7011f699ffb6f080e6370d88e7\", \"swarm_hashes_refs/heads/master(at){#822986}_without_patch\": {\"absl_hardening_tests\": \"1ac2261a715bd557d14b95c8b621e2109f0c402e\", \"accessibility_unittests\": \"545920a9402961f7e788ad0c1da20d0288164716\", \"angle_unittests\": \"33395b448e66aadc399261688a673d2da603399f\", \"app_shell_unittests\": \"83a2d88f02387a7a5b620ffc6cb477c4bfcaff0c\", \"aura_unittests\": \"dd87c3cbf10bc715c0db99ed4d6400fb7412cd1f\", \"base_unittests\": \"9c11198c8ce3150685f08899eed06b97d5257aa3\", \"base_util_unittests\": \"81c574f1769fcdec01402c93a4593feabf01b4b1\", \"blink_common_unittests\": \"6612011e3d72bd5840fabc6276b45299a47920d4\", \"blink_fuzzer_unittests\": \"1f1e636db1d7b81e34c6752b43a4af43d8a9dbb7\", \"blink_heap_unittests\": \"70cefd2e91b7c6c6ec0a3ced5a1bd2395f0873af\", \"blink_platform_unittests\": \"41d05041834354dd014681b71193b678eaa5e7a4\", \"blink_unittests\": \"f97f481521278e7b177d52ff1e03bad97142c0a0\", \"boringssl_crypto_tests\": \"3782536d0d63df158f867dc23ae2b42a1d9c30b3\", \"boringssl_ssl_tests\": \"37392521fea667fe76d980a60328af0ddf7bd725\", \"browser_switcher_bho_unittests\": \"98096cbfbec64e8457ac7f88a806f21fd590370e\", \"browser_tests\": \"18fc1df774bb359febd1699df61047f03f36f633\", \"capture_unittests\": \"9899ef9885cde31f0fbb8d34631ef9414a1e1cc1\", \"cast_unittests\": \"5c0d191e9fd08ae3b90f43cc13fb1f90019dac2e\", \"cc_unittests\": \"ac7f248db23b52fac35feb297bcace54dd66aa4d\", \"chrome_app_unittests\": \"933c1dbb766f307a00f749c9921a2c3564c56deb\", \"chrome_cleaner_unittests\": \"39458f433f0df8b176823f2c7b787e74180d681a\", \"chrome_elf_unittests\": \"1e90819e78d116f41b26306ecd3c0e937ebd8cfd\", \"chromedriver_unittests\": \"db3bf01770765ec7d69871d4f8f7f7dc44f5d46f\", \"components_browsertests\": \"e8ccebf4242073a2a1883a7d2276685e47656c94\", \"components_unittests\": \"b0cc17b5ff9c53757ad7055d6d8379a0f31d7cbc\", \"compositor_unittests\": \"2946e29869a6f2fe60a1397abd67c80c752edbd8\", \"content_browsertests\": \"e3e2da68a63c76b4a2922cc27c095c0bb6fdfa50\", \"content_unittests\": \"6703f36b64a7b9c0a5943605a8a95e96cb2ab747\", \"courgette_unittests\": \"e363875d11449ce2d3d36105987a7feee5937a34\", \"crashpad_tests\": \"75eddf82329186543af07b7b2fb85a23e89f0174\", \"cronet_tests\": \"6be3c15a47e1ad51dd234022fbacc3b6cc0e89d2\", \"cronet_unittests\": \"f90084f164b6f138a05d314ee168bc373130e262\", \"crypto_unittests\": \"e4235e4279a7eb9ff52a201b5f04c7564cc99246\", \"delayloads_unittests\": \"e4590ab4e1b78166e37aa255fd4cebbcdb5141d6\", \"device_unittests\": \"e98fcea35bfa7a35960e2ddfba1da7db40f59507\", \"display_unittests\": \"e64e83a31a46edfe5f782b9893bd3f422cb4f572\", \"elevation_service_unittests\": \"675d3d8436edd42667244e4d2afca55a43b58954\", \"events_unittests\": \"9084817a9ed500ddb5c65fe893756712f0db2877\", \"extensions_browsertests\": \"1180a2bb2e5b8052c6df593e1319006092a3de25\", \"extensions_unittests\": \"820bce17a04f830ad139ae9cfe93df0fbdc073e3\", \"filesystem_service_unittests\": \"64ced338483410c6af77a37839219d1783f62b67\", \"gcm_unit_tests\": \"0260c437ce821e8564b6d1c0703b247727772897\", \"gcp_unittests\": \"49f34811e494dc7ee4a28c85d9a7f11ed7cf9f35\", \"gfx_unittests\": \"77709d10788bfe38d420aecdba4a6f60c62940f9\", \"gin_unittests\": \"c99ce24bf53ca538f95415409b2ae441ad0ed97c\", \"google_apis_unittests\": \"e4d487ea2b9cb44e822a830d248882615582bf73\", \"gpu_unittests\": \"b50fc233343198817901eaccc67cd877724c7a7f\", \"gwp_asan_unittests\": \"80034b5780a4e9d252ce687f178a5d4dcd1fa34f\", \"headless_browsertests\": \"ba20f22d2b4e041e52e61f13100dc527b036db7b\", \"headless_unittests\": \"d56275b8568093c79bee82aae8930ae9b31288b1\", \"install_static_unittests\": \"6b121b564ea9adb4ee813e956f3343b9bbcfeec8\", \"installer_util_unittests\": \"079fd55bc47f063facc839ec27699a3d8e93cb70\", \"interactive_ui_tests\": \"464f362a1843b1136a41ea73f4503963685fc344\", \"ipc_tests\": \"572fa455121dbe8a096e271533b3d45521dc0e6a\", \"jingle_unittests\": \"131ac18f11998c90f9c2e48770e68be706423b7b\", \"latency_unittests\": \"e728efc961f6b2aac347e911091f73c73aeb7dc0\", \"libjingle_xmpp_unittests\": \"c1d82978b2934118ef1a044adb0f11b7209e9f6b\", \"liburlpattern_unittests\": \"0a7583bbaaf493bf63f1fbabd5e35dce462cc4fc\", \"media_blink_unittests\": \"587cb44651771764b005fb783890d855fb103c54\", \"media_unittests\": \"1df437ee64ed25795eb0ec5f3d16e623107d856e\", \"message_center_unittests\": \"ea9f873a9165f51b4dbe76a3ddba186ef30c6abc\", \"midi_unittests\": \"2519a750f60d5cfba5d77b9669d24c60a6b6df49\", \"mojo_core_unittests\": \"57cc277e23e3610566725ed797c6dd9a94a9b0cc\", \"mojo_unittests\": \"1eb174c0450a4f7625b42fcf5ca70613483e6ac7\", \"nacl_loader_unittests\": \"8e2def49b6eb20699633a80fec6b4b83a4c92289\", \"native_theme_unittests\": \"f3977e23e3317f00742ad32faddf26ffec357baf\", \"net_unittests\": \"598863405bfc5b27ba024198a02e266fc3e04b5a\", \"notification_helper_unittests\": \"ad7e64b1e308779e770625855783ed2dd0f23929\", \"pdf_unittests\": \"51a052ba8246f93dbe90d3d48af98e270283e212\", \"perfetto_unittests\": \"585a50a97f93feb4888e17cb58e84141be2a5fef\", \"ppapi_unittests\": \"1999b10deaf13f9e72c27fa7d7110bdde913f157\", \"printing_unittests\": \"07ed71ca4214d6d9c3ed5e43751eb7e7f849da3e\", \"remoting_unittests\": \"c7b5395f7f26db83b3a06e30c232b0a71a5c5637\", \"sbox_integration_tests\": \"7a76d8be714ded0adc2afbe775954ba1dc04bc7a\", \"sbox_unittests\": \"c2692ce6a5d9a3de4cf889a96651f8f92ed138af\", \"sbox_validation_tests\": \"19ac4e8595c5558a3fa93913f4ed378e113dea7d\", \"service_manager_unittests\": \"0fc14066e49d4de6f74032289c7a270f0c5ed3aa\", \"services_unittests\": \"fcc1843b995e2f0e03f0821443cfe30a5e5e5179\", \"setup_unittests\": \"bc864f337e81903c33ebd148f874d95890f75a77\", \"shell_dialogs_unittests\": \"17b23ac04253f8abd513d0763d89052ee1ee4e1d\", \"skia_unittests\": \"790168c10a54be7f6443944b52be35be5d65dabb\", \"snapshot_unittests\": \"f0d1f83cc207989a7805c5e9b41ec0e256a5f700\", \"sql_unittests\": \"20e79853d8e151ea7ba893291bd85bf7ac45f7af\", \"storage_unittests\": \"0c88a925fd510a472e51fe10e7eeea0242642734\", \"sync_integration_tests\": \"d5687fb5e55d11ec6e79a86e4574a26de905ecda\", \"traffic_annotation_auditor_unittests\": \"8ea47cab357d7a82878ccec402acc331568e46d9\", \"ui_base_unittests\": \"6131dd77ce67e4555b7a4473f8ba97864a5ca6c0\", \"ui_touch_selection_unittests\": \"ba8b2e1bfcfd181a4f9fe13fbd4ca253c7c97c11\", \"unit_tests\": \"4b4d33dba358b464ca47c060725e7bd21103f0f1\", \"updater_tests\": \"53536fac678f0f1ec35c9a7d3ff839945c553b58\", \"url_unittests\": \"51cdb8c5048ec64f824a0d57b3b7ee53aa7ddd69\", \"views_unittests\": \"f1518d218aaea05984fb0771c3bffb0b5f1cd4b1\", \"viz_unittests\": \"3f1b1e89c03440d0c5ba55f0e05714a796521bd9\", \"vr_common_unittests\": \"96e4487a895b27879088b0cfc63dd10a858ebb9c\", \"vr_pixeltests\": \"447697ed499fb44e0a3482e84098956b04b56a42\", \"weblayer_browsertests\": \"6b98259ff7a19d91af902d34391e11a31cc8a45d\", \"weblayer_unittests\": \"519a225448318d6c4248750293986ba138eed647\", \"wm_unittests\": \"33caa175caecc6679c1a048dda48ea6ad30b68d4\", \"wtf_unittests\": \"9ae40889583933bb6c80a2b8b94ba90a15634901\", \"zlib_unittests\": \"4e89312ff9b1b327de71e5f6970a38fa03ec07a4\", \"zucchini_unittests\": \"0c5b070b848bd0ca1ce645bfa4a90fbda2a6b37b\"}}, \"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": "1604186364214959",
"status": "COMPLETED",
"status_changed_ts": "1604186364585453",
"tags": [
"build_address:luci.chromium.ci/win-asan/15386",
"builder:win-asan",
"buildset:commit/git/a55038d103667a7011f699ffb6f080e6370d88e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a55038d103667a7011f699ffb6f080e6370d88e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030312664972898864",
"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/8864882602815752528/+/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:4f99c3dfb57a1010",
"user_agent:luci-scheduler"
],
"updated_ts": "1604193148329036",
"url": "https://ci.chromium.org/b/8864882602815752528",
"utcnow_ts": "1604193149110769"
}
}
[01:12:29.132] Invocation finished in 1h56m24.627205215s with status FAILED