[07:32:34.219] New invocation is queued and will start shortly
[07:32:35.292] Starting the invocation (attempt 1)
[07:32:35.326] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:32:35.326] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:32:35.326] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025026804158486288",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b87c5701cec358b5474a287fd9b61649316f4ea8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025026804158486288",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b87c5701cec358b5474a287fd9b61649316f4ea8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b87c5701cec358b5474a287fd9b61649316f4ea8",
"gitiles_ref:refs/heads/master"
]
}
[07:32:36.103] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609227155428396",
"id": "8859596742228508560",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b87c5701cec358b5474a287fd9b61649316f4ea8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609227155901137",
"tags": [
"build_address:luci.chromium.ci/win-asan/16524",
"builder:win-asan",
"buildset:commit/git/b87c5701cec358b5474a287fd9b61649316f4ea8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b87c5701cec358b5474a287fd9b61649316f4ea8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025026804158486288",
"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/8859596742228508560/+/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": "1609227155901242",
"url": "https://ci.chromium.org/b/8859596742228508560",
"utcnow_ts": "1609227156080297"
}
}
[07:32:36.104] Task URL: https://ci.chromium.org/b/8859596742228508560
[07:32:36.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:2:0) after 1m0s
[07:32:38.890] Received PubSub notification, asking Buildbucket for the build status
[07:32:39.304] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:36.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:2:0)
[07:33:36.253] Timer tick, asking Buildbucket for the build status
[07:33:36.785] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:36.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:4:0) after 1m0s
[07:34:36.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:4:0)
[07:34:36.909] Timer tick, asking Buildbucket for the build status
[07:34:37.101] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:37.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:5:0) after 1m0s
[07:35:37.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:5:0)
[07:35:37.213] Timer tick, asking Buildbucket for the build status
[07:35:37.331] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:37.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:6:0) after 1m0s
[07:36:37.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:6:0)
[07:36:37.361] Timer tick, asking Buildbucket for the build status
[07:36:37.523] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:37.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:7:0) after 1m0s
[07:37:37.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:7:0)
[07:37:37.506] Timer tick, asking Buildbucket for the build status
[07:37:37.656] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:37.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:8:0) after 1m0s
[07:38:37.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:8:0)
[07:38:37.714] Timer tick, asking Buildbucket for the build status
[07:38:37.881] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:37.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:9:0) after 1m0s
[07:39:37.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:9:0)
[07:39:37.899] Timer tick, asking Buildbucket for the build status
[07:39:38.273] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:38.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:10:0) after 1m0s
[07:40:38.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:10:0)
[07:40:38.402] Timer tick, asking Buildbucket for the build status
[07:40:38.491] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:38.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:11:0) after 1m0s
[07:41:38.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:11:0)
[07:41:38.523] Timer tick, asking Buildbucket for the build status
[07:41:38.612] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:38.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:12:0) after 1m0s
[07:42:38.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:12:0)
[07:42:38.609] Timer tick, asking Buildbucket for the build status
[07:42:38.723] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:38.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:13:0) after 1m0s
[07:43:38.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:13:0)
[07:43:38.775] Timer tick, asking Buildbucket for the build status
[07:43:38.862] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:38.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:14:0) after 1m0s
[07:44:38.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:14:0)
[07:44:38.833] Timer tick, asking Buildbucket for the build status
[07:44:38.900] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:38.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:15:0) after 1m0s
[07:45:38.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:15:0)
[07:45:38.922] Timer tick, asking Buildbucket for the build status
[07:45:38.999] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:38.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:16:0) after 1m0s
[07:46:39.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:16:0)
[07:46:39.019] Timer tick, asking Buildbucket for the build status
[07:46:39.083] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:39.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:17:0) after 1m0s
[07:47:39.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:17:0)
[07:47:39.099] Timer tick, asking Buildbucket for the build status
[07:47:39.199] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:39.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:18:0) after 1m0s
[07:48:39.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:18:0)
[07:48:39.207] Timer tick, asking Buildbucket for the build status
[07:48:39.274] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:39.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:19:0) after 1m0s
[07:49:39.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:19:0)
[07:49:39.325] Timer tick, asking Buildbucket for the build status
[07:49:39.437] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:39.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:20:0) after 1m0s
[07:50:39.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:20:0)
[07:50:39.527] Timer tick, asking Buildbucket for the build status
[07:50:39.796] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:39.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:21:0) after 1m0s
[07:51:39.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:21:0)
[07:51:39.895] Timer tick, asking Buildbucket for the build status
[07:51:40.014] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:40.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:22:0) after 1m0s
[07:52:40.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:22:0)
[07:52:40.186] Timer tick, asking Buildbucket for the build status
[07:52:40.335] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:40.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:23:0) after 1m0s
[07:53:40.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:23:0)
[07:53:40.403] Timer tick, asking Buildbucket for the build status
[07:53:40.526] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:40.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:24:0) after 1m0s
[07:54:40.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:24:0)
[07:54:40.635] Timer tick, asking Buildbucket for the build status
[07:54:40.790] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:40.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:25:0) after 1m0s
[07:55:40.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:25:0)
[07:55:40.841] Timer tick, asking Buildbucket for the build status
[07:55:41.225] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:41.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:26:0) after 1m0s
[07:56:41.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:26:0)
[07:56:41.270] Timer tick, asking Buildbucket for the build status
[07:56:41.382] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:41.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:27:0) after 1m0s
[07:57:41.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:27:0)
[07:57:41.400] Timer tick, asking Buildbucket for the build status
[07:57:41.539] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:41.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:28:0) after 1m0s
[07:58:41.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:28:0)
[07:58:41.558] Timer tick, asking Buildbucket for the build status
[07:58:41.666] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:41.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:29:0) after 1m0s
[07:59:41.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:29:0)
[07:59:41.686] Timer tick, asking Buildbucket for the build status
[07:59:41.799] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:41.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:30:0) after 1m0s
[08:00:41.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:30:0)
[08:00:41.882] Timer tick, asking Buildbucket for the build status
[08:00:42.011] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:42.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:31:0) after 1m0s
[08:01:42.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:31:0)
[08:01:42.246] Timer tick, asking Buildbucket for the build status
[08:01:42.596] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:42.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:32:0) after 1m0s
[08:02:42.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:32:0)
[08:02:42.585] Timer tick, asking Buildbucket for the build status
[08:02:42.705] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:42.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:33:0) after 1m0s
[08:03:42.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:33:0)
[08:03:42.732] Timer tick, asking Buildbucket for the build status
[08:03:43.174] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:43.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:34:0) after 1m0s
[08:04:43.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:34:0)
[08:04:43.305] Timer tick, asking Buildbucket for the build status
[08:04:43.413] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:43.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:35:0) after 1m0s
[08:05:43.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:35:0)
[08:05:43.432] Timer tick, asking Buildbucket for the build status
[08:05:43.525] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:43.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:36:0) after 1m0s
[08:06:43.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:36:0)
[08:06:43.644] Timer tick, asking Buildbucket for the build status
[08:06:43.743] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:43.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:37:0) after 1m0s
[08:07:43.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:37:0)
[08:07:43.769] Timer tick, asking Buildbucket for the build status
[08:07:44.436] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:44.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:38:0) after 1m0s
[08:08:44.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:38:0)
[08:08:44.531] Timer tick, asking Buildbucket for the build status
[08:08:44.668] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:44.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:39:0) after 1m0s
[08:09:44.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:39:0)
[08:09:44.707] Timer tick, asking Buildbucket for the build status
[08:09:44.845] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:44.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:40:0) after 1m0s
[08:10:45.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:40:0)
[08:10:45.013] Timer tick, asking Buildbucket for the build status
[08:10:45.148] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:45.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:41:0) after 1m0s
[08:11:45.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:41:0)
[08:11:45.224] Timer tick, asking Buildbucket for the build status
[08:11:45.341] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:45.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:42:0) after 1m0s
[08:12:45.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:42:0)
[08:12:45.366] Timer tick, asking Buildbucket for the build status
[08:12:45.827] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:45.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:43:0) after 1m0s
[08:13:45.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:43:0)
[08:13:45.879] Timer tick, asking Buildbucket for the build status
[08:13:46.203] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:46.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:44:0) after 1m0s
[08:14:46.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:44:0)
[08:14:46.252] Timer tick, asking Buildbucket for the build status
[08:14:46.345] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:46.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:45:0) after 1m0s
[08:15:46.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:45:0)
[08:15:46.414] Timer tick, asking Buildbucket for the build status
[08:15:46.598] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:46.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:46:0) after 1m0s
[08:16:46.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:46:0)
[08:16:46.689] Timer tick, asking Buildbucket for the build status
[08:16:46.839] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:46.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:47:0) after 1m0s
[08:17:46.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:47:0)
[08:17:46.856] Timer tick, asking Buildbucket for the build status
[08:17:47.008] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:47.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:48:0) after 1m0s
[08:18:47.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:48:0)
[08:18:47.008] Timer tick, asking Buildbucket for the build status
[08:18:47.172] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:47.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:49:0) after 1m0s
[08:19:47.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:49:0)
[08:19:47.217] Timer tick, asking Buildbucket for the build status
[08:19:47.324] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:47.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:50:0) after 1m0s
[08:20:47.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:50:0)
[08:20:47.305] Timer tick, asking Buildbucket for the build status
[08:20:47.481] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:47.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:51:0) after 1m0s
[08:21:47.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:51:0)
[08:21:47.510] Timer tick, asking Buildbucket for the build status
[08:21:47.674] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:47.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:52:0) after 1m0s
[08:22:47.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:52:0)
[08:22:47.745] Timer tick, asking Buildbucket for the build status
[08:22:47.844] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:47.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:53:0) after 1m0s
[08:23:47.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:53:0)
[08:23:47.849] Timer tick, asking Buildbucket for the build status
[08:23:48.264] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:48.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:54:0) after 1m0s
[08:24:48.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:54:0)
[08:24:48.392] Timer tick, asking Buildbucket for the build status
[08:24:48.475] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:48.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:55:0) after 1m0s
[08:25:48.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:55:0)
[08:25:48.498] Timer tick, asking Buildbucket for the build status
[08:25:48.600] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:48.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:56:0) after 1m0s
[08:26:48.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:56:0)
[08:26:48.622] Timer tick, asking Buildbucket for the build status
[08:26:49.617] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:49.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:57:0) after 1m0s
[08:27:49.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:57:0)
[08:27:49.706] Timer tick, asking Buildbucket for the build status
[08:27:49.835] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:49.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:58:0) after 1m0s
[08:28:49.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:58:0)
[08:28:49.854] Timer tick, asking Buildbucket for the build status
[08:28:50.042] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:50.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:59:0) after 1m0s
[08:29:50.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:59:0)
[08:29:50.048] Timer tick, asking Buildbucket for the build status
[08:29:50.189] Build 8859596742228508560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:50.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025026804158486288:60:0) after 1m0s
[08:29:59.560] Received PubSub notification, asking Buildbucket for the build status
[08:29:59.654] Build 8859596742228508560: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:29:59.654] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609230599102336",
"created_by": "project:chromium",
"created_ts": "1609227155428396",
"failure_reason": "BUILD_FAILURE",
"id": "8859596742228508560",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b87c5701cec358b5474a287fd9b61649316f4ea8\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**accessibility_unittests** failed.\"}, \"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\": \"b87c5701cec358b5474a287fd9b61649316f4ea8\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-1-2sgf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16524, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"220642a93da1a6e98b6e6c94f1c7767eb267bf58\", \"got_dawn_revision\": \"0eb61724c8f115bc5f26bd9850f14a1dfcae4216\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"b87c5701cec358b5474a287fd9b61649316f4ea8\", \"got_revision_cp\": \"refs/heads/master@{#839550}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"bcfb0a5ada22d472155e73725ec23568f3bc9526\", \"got_v8_revision_cp\": \"refs/heads/8.9.198@{#1}\", \"got_webrtc_revision\": \"1f0df96659aeb6447d5d8959c114f3212fc1ca04\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32884}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b87c5701cec358b5474a287fd9b61649316f4ea8\", \"swarm_hashes_refs/heads/master(at){#839550}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"7c32fa51efe0d07e02a9c9b670dc0460e0b693ee\", \"angle_unittests\": \"629f3c19f39f7b3a6893736958b8370855b899ec\", \"app_shell_unittests\": \"d604e460ac2f0acd4a232e26046a3776db04aa7c\", \"aura_unittests\": \"d376e5aafbc2339a519f2c9fc522ee34b5cc68a6\", \"base_unittests\": \"7b193a80056c606539d30974fda38c0f0e977e5f\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"09ae0aca776d8b097606ea0d0d291317e6d90b4c\", \"blink_fuzzer_unittests\": \"0ea4f25fab91246a5ce2314320bb1d9a3c478b12\", \"blink_heap_unittests\": \"876a83de7d10269939b3b2743103159b409e6423\", \"blink_platform_unittests\": \"708ac160c502062bb34f86cc0ed0a2e5240c4e7f\", \"blink_unittests\": \"4ca017091dd19ff0740fa8546c850298655b7790\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"470b3143538d09ba72ff4f1d805af52fb0e8bdbf\", \"capture_unittests\": \"4813d33a323a0c786d0f5a5f5cc7a9a8fa37b40b\", \"cast_unittests\": \"a986ef688193f438fa1b02777671654ffa560842\", \"cc_unittests\": \"5479eb4466139d6c7c7f56b793d9473ed5f544c6\", \"chrome_app_unittests\": \"ec45de9e6e700a26190b3c2416afbbdd6db0dd39\", \"chrome_cleaner_unittests\": \"f79f942cc29ca8d85143e70499afaadb2fa9b988\", \"chrome_elf_unittests\": \"abb431f39e8af400d316217a42d0f52ed4710f57\", \"chromedriver_unittests\": \"b812976d0dd3d61071ab7d01f1db115697174e6a\", \"components_browsertests\": \"8220eb92bc5f4980143038e79588a6e04e234839\", \"components_unittests\": \"8c0005a4c40ec9425c377c076034136e13f13c00\", \"compositor_unittests\": \"b2bb96eae5243ed24e053db2db7a7e39201d5dba\", \"content_browsertests\": \"cad6d8faeb7ca05a724425d07f71b4313163f779\", \"content_unittests\": \"2586120ca4f551dc3f4e4ff6cc9161fc7b932c2c\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"9751b795cdf550d60883b69cd71f4387b4f14bf6\", \"cronet_unittests\": \"64c20458ac2d42fc2181388532402257b5bd4d00\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"3a8d6cd6108951b3a6d44f20e0bd3468c4e16c4c\", \"device_unittests\": \"e4d52aa238f27fa144f6db761602a4653a4ea4a9\", \"display_unittests\": \"b9a1d272acf67ef81fa670032274c3749b53b103\", \"elevation_service_unittests\": \"ca7652707d00d7d527435353372fc87bd57bc7c1\", \"events_unittests\": \"3ae099d171ff09988d5861f8fb8795ef68826918\", \"extensions_browsertests\": \"23f76c2bdb97b0e092a81cdf09046a9ad2597457\", \"extensions_unittests\": \"be193de18554add12587aa5dc97919d9cdc752c1\", \"filesystem_service_unittests\": \"892ee59ac2cd5e4833212dc011983bb70f2d22b2\", \"gcm_unit_tests\": \"48077ef00ac0e20fd104a60ab3360890100a9e77\", \"gcp_unittests\": \"dd28229c0f0fd93a0b2bebc59d8869f3daa9d87b\", \"gfx_unittests\": \"abbec401f322e63b2079d501478bc04753a5a235\", \"gin_unittests\": \"2db3a08c8c5b59df8751b2e56ab144cad80cbb6c\", \"google_apis_unittests\": \"65e21ec2ca563a03330898119092d0759b05e381\", \"gpu_unittests\": \"8f5a5b356b8f8bc4f3563adcbb1a961e758d675b\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"39a9230d7203850e8d78206ca4e8e7a7fc99101a\", \"headless_unittests\": \"c308267390266f00f2dd521a2399fde71ccb7f45\", \"install_static_unittests\": \"4bf7c43f73bdb4d6b0639d2e4b56712f2e58ddd1\", \"installer_util_unittests\": \"5db795396b432bf81a84d1c8a80b7324973022fc\", \"interactive_ui_tests\": \"83eb146f9f2329a7e319ef320003bbfee137ddad\", \"ipc_tests\": \"d38538cf9d6a1f8bdf9651951478956191263ba5\", \"jingle_unittests\": \"fd04cf56fe9c7d1bafe2064efe4ffd33a3afa799\", \"latency_unittests\": \"9ba7c39fefef100225a0118f44b24a5356828a25\", \"libjingle_xmpp_unittests\": \"cbedee92be0a70cea47ff60719e51a5dfc6410fc\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"4dd0e06990c9f4c66b6a7c518947c0f1cbc343d8\", \"media_unittests\": \"87548559155c76f353f3ba9b536ced7242fdebec\", \"message_center_unittests\": \"284d11780ba2051963284421c280c42aec7645e9\", \"midi_unittests\": \"7c84af120cd2e87acd6818676d2bcf5a0de44ed9\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"58c85f45dd47d22ba7807e884e391ad4e3b91d09\", \"nacl_loader_unittests\": \"a512ae037ad8a695541180d98b4c90117a110547\", \"native_theme_unittests\": \"c5d03dbd4d336d7deb52c97d35abc6c746a8f7ee\", \"net_unittests\": \"06787ca787f4cd5bfe64c6ee5afa098352719956\", \"notification_helper_unittests\": \"3c8daf26cb6fc77aa30947da0aafb38123d82a63\", \"pdf_unittests\": \"5a4aeb6a4afe66652fc80295def7ab79b2655107\", \"perfetto_unittests\": \"579be7f61edfec382e75f1d9d552160b7eec9df3\", \"ppapi_unittests\": \"26252dff4483f69240a3c5341bd801aa2ed9c745\", \"printing_unittests\": \"76fd6695e8b7f0b9ee54d97b2373b5fe8641d9ff\", \"remoting_unittests\": \"513802408d1a48b05cb3b2bf2348aec448341bbd\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"dc4222aa08785945d575c259c7e672fa1432d766\", \"services_unittests\": \"b0f1d35d51399490f29c5d422e4021a3196e6d4f\", \"setup_unittests\": \"a594e217fb1d731d3b0218453a6d8a4161b2872f\", \"shell_dialogs_unittests\": \"4286cb5ddb695244574fb94987c5617c6552073e\", \"skia_unittests\": \"4d279a0dac6113f82053d5d6cb08e3be0ba50a23\", \"snapshot_unittests\": \"4d38b96f02b56938894698d1543a8a0e26b1e8c3\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"4c4fa205d853d660eeb44471e176a0fb0883bd24\", \"sync_integration_tests\": \"354c6d745f3d57f9ae262ee03997e828f973e99b\", \"traffic_annotation_auditor_unittests\": \"8018029e708e908eb744f04b58b49a1ebcbfaf02\", \"ui_base_unittests\": \"c0b15d289f88277f2b317171ab1277bf68d37818\", \"ui_touch_selection_unittests\": \"9bc08d8df1690f81d3f076cc1e45a17aa4b4e4b3\", \"unit_tests\": \"bd92df9172179fe727d5a3458b557152ba73f8fa\", \"updater_tests\": \"72b0c5c6f5f2f513908c70a79d4734fd03b7bdd0\", \"url_unittests\": \"cdd3d0fb54aec79cbc533e9ac33408a46cb830be\", \"views_unittests\": \"4a4771b8bf1ee92bb23f7083c861d1d65b6caaf7\", \"viz_unittests\": \"bda9dd3e457ef20d3fc3f11b03b77a507dde61c4\", \"vr_common_unittests\": \"ee4460e2a423a9cefc3072bf8311526079a78de2\", \"vr_pixeltests\": \"fc5a7e87e27c47af2a2258d9aea4c91d208d147c\", \"weblayer_browsertests\": \"2b9d7b282e44774440079c53e667c9fbc182d3e1\", \"weblayer_unittests\": \"414c15e4f2067a2ae139984f06f0452798195c61\", \"wm_unittests\": \"1e63d5c95632c55fd232d158c660fddf78bf0ed5\", \"wtf_unittests\": \"04e1057dd40218aefcfdb6441f781d15d3c40765\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-2sgf\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**accessibility_unittests** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609227158211426",
"status": "COMPLETED",
"status_changed_ts": "1609227158730910",
"tags": [
"build_address:luci.chromium.ci/win-asan/16524",
"builder:win-asan",
"buildset:commit/git/b87c5701cec358b5474a287fd9b61649316f4ea8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b87c5701cec358b5474a287fd9b61649316f4ea8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025026804158486288",
"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/8859596742228508560/+/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:50c63b4319bb1c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609230599358794",
"url": "https://ci.chromium.org/b/8859596742228508560",
"utcnow_ts": "1609230599637607"
}
}
[08:29:59.654] Invocation finished in 57m25.449823604s with status FAILED