[08:52:56.118] New invocation is queued and will start shortly
[08:52:57.196] Starting the invocation (attempt 1)
[08:52:57.229] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:52:57.229] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:52:57.229] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019132945216780800",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8954fda32772b6251d9bc99322bb1e6ac66465c6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019132945216780800",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8954fda32772b6251d9bc99322bb1e6ac66465c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8954fda32772b6251d9bc99322bb1e6ac66465c6",
"gitiles_ref:refs/heads/master"
]
}
[08:52:58.474] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614847977309687",
"id": "8853702883303142224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8954fda32772b6251d9bc99322bb1e6ac66465c6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614847978162757",
"tags": [
"build_address:luci.chromium.ci/win-asan/17627",
"builder:win-asan",
"buildset:commit/git/8954fda32772b6251d9bc99322bb1e6ac66465c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8954fda32772b6251d9bc99322bb1e6ac66465c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019132945216780800",
"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/8853702883303142224/+/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": "1614847978162904",
"url": "https://ci.chromium.org/b/8853702883303142224",
"utcnow_ts": "1614847978457857"
}
}
[08:52:58.474] Task URL: https://ci.chromium.org/b/8853702883303142224
[08:52:58.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:2:0) after 1m0s
[08:53:00.173] Received PubSub notification, asking Buildbucket for the build status
[08:53:00.276] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:58.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:2:0)
[08:53:58.484] Timer tick, asking Buildbucket for the build status
[08:53:58.537] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:58.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:4:0) after 1m0s
[08:54:58.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:4:0)
[08:54:58.564] Timer tick, asking Buildbucket for the build status
[08:54:58.632] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:58.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:5:0) after 1m0s
[08:55:58.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:5:0)
[08:55:58.696] Timer tick, asking Buildbucket for the build status
[08:55:58.768] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:58.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:6:0) after 1m0s
[08:56:58.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:6:0)
[08:56:58.766] Timer tick, asking Buildbucket for the build status
[08:56:58.854] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:58.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:7:0) after 1m0s
[08:57:58.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:7:0)
[08:57:58.886] Timer tick, asking Buildbucket for the build status
[08:57:58.974] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:58.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:8:0) after 1m0s
[08:58:58.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:8:0)
[08:58:58.971] Timer tick, asking Buildbucket for the build status
[08:58:59.063] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:59.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:9:0) after 1m0s
[08:59:59.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:9:0)
[08:59:59.088] Timer tick, asking Buildbucket for the build status
[08:59:59.231] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:59.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:10:0) after 1m0s
[09:00:59.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:10:0)
[09:00:59.249] Timer tick, asking Buildbucket for the build status
[09:00:59.461] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:59.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:11:0) after 1m0s
[09:01:59.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:11:0)
[09:01:59.446] Timer tick, asking Buildbucket for the build status
[09:01:59.624] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:59.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:12:0) after 1m0s
[09:02:59.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:12:0)
[09:02:59.664] Timer tick, asking Buildbucket for the build status
[09:02:59.739] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:59.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:13:0) after 1m0s
[09:03:59.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:13:0)
[09:03:59.790] Timer tick, asking Buildbucket for the build status
[09:03:59.948] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:59.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:14:0) after 1m0s
[09:05:00.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:14:0)
[09:05:00.026] Timer tick, asking Buildbucket for the build status
[09:05:00.234] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:00.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:15:0) after 1m0s
[09:06:00.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:15:0)
[09:06:00.319] Timer tick, asking Buildbucket for the build status
[09:06:00.418] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:00.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:16:0) after 1m0s
[09:07:00.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:16:0)
[09:07:00.502] Timer tick, asking Buildbucket for the build status
[09:07:00.590] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:00.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:17:0) after 1m0s
[09:08:01.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:17:0)
[09:08:01.298] Timer tick, asking Buildbucket for the build status
[09:08:01.387] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:01.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:18:0) after 1m0s
[09:09:01.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:18:0)
[09:09:01.622] Timer tick, asking Buildbucket for the build status
[09:09:01.741] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:01.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:19:0) after 1m0s
[09:10:01.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:19:0)
[09:10:01.907] Timer tick, asking Buildbucket for the build status
[09:10:02.068] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:02.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:20:0) after 1m0s
[09:11:02.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:20:0)
[09:11:02.194] Timer tick, asking Buildbucket for the build status
[09:11:02.607] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:02.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:21:0) after 1m0s
[09:12:02.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:21:0)
[09:12:02.658] Timer tick, asking Buildbucket for the build status
[09:12:02.736] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:02.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:22:0) after 1m0s
[09:13:02.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:22:0)
[09:13:02.757] Timer tick, asking Buildbucket for the build status
[09:13:02.892] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:02.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:23:0) after 1m0s
[09:14:02.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:23:0)
[09:14:02.944] Timer tick, asking Buildbucket for the build status
[09:14:03.025] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:03.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:24:0) after 1m0s
[09:15:03.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:24:0)
[09:15:03.045] Timer tick, asking Buildbucket for the build status
[09:15:03.305] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:03.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:25:0) after 1m0s
[09:16:03.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:25:0)
[09:16:03.351] Timer tick, asking Buildbucket for the build status
[09:16:03.618] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:03.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:26:0) after 1m0s
[09:17:03.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:26:0)
[09:17:03.637] Timer tick, asking Buildbucket for the build status
[09:17:03.733] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:03.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:27:0) after 1m0s
[09:18:03.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:27:0)
[09:18:03.737] Timer tick, asking Buildbucket for the build status
[09:18:03.828] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:03.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:28:0) after 1m0s
[09:19:03.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:28:0)
[09:19:03.877] Timer tick, asking Buildbucket for the build status
[09:19:03.959] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:03.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:29:0) after 1m0s
[09:20:03.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:29:0)
[09:20:03.995] Timer tick, asking Buildbucket for the build status
[09:20:04.089] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:04.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:30:0) after 1m0s
[09:21:04.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:30:0)
[09:21:04.193] Timer tick, asking Buildbucket for the build status
[09:21:05.099] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:05.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:31:0) after 1m0s
[09:22:05.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:31:0)
[09:22:05.143] Timer tick, asking Buildbucket for the build status
[09:22:05.224] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:05.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:32:0) after 1m0s
[09:23:05.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:32:0)
[09:23:05.482] Timer tick, asking Buildbucket for the build status
[09:23:05.838] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:05.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:33:0) after 1m0s
[09:24:05.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:33:0)
[09:24:05.898] Timer tick, asking Buildbucket for the build status
[09:24:06.319] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:06.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:34:0) after 1m0s
[09:25:06.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:34:0)
[09:25:06.343] Timer tick, asking Buildbucket for the build status
[09:25:06.545] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:06.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:35:0) after 1m0s
[09:26:06.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:35:0)
[09:26:06.518] Timer tick, asking Buildbucket for the build status
[09:26:06.610] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:06.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:36:0) after 1m0s
[09:27:06.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:36:0)
[09:27:06.655] Timer tick, asking Buildbucket for the build status
[09:27:06.931] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:06.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:37:0) after 1m0s
[09:28:07.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:37:0)
[09:28:07.014] Timer tick, asking Buildbucket for the build status
[09:28:07.202] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:07.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:38:0) after 1m0s
[09:29:07.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:38:0)
[09:29:07.215] Timer tick, asking Buildbucket for the build status
[09:29:07.309] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:07.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:39:0) after 1m0s
[09:30:07.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:39:0)
[09:30:07.327] Timer tick, asking Buildbucket for the build status
[09:30:07.426] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:07.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:40:0) after 1m0s
[09:31:07.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:40:0)
[09:31:07.478] Timer tick, asking Buildbucket for the build status
[09:31:07.638] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:07.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:41:0) after 1m0s
[09:32:07.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:41:0)
[09:32:07.697] Timer tick, asking Buildbucket for the build status
[09:32:07.815] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:07.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:42:0) after 1m0s
[09:33:07.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:42:0)
[09:33:07.837] Timer tick, asking Buildbucket for the build status
[09:33:07.957] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:07.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:43:0) after 1m0s
[09:34:08.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:43:0)
[09:34:08.007] Timer tick, asking Buildbucket for the build status
[09:34:08.171] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:08.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:44:0) after 1m0s
[09:35:08.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:44:0)
[09:35:08.232] Timer tick, asking Buildbucket for the build status
[09:35:08.366] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:08.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:45:0) after 1m0s
[09:36:08.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:45:0)
[09:36:08.412] Timer tick, asking Buildbucket for the build status
[09:36:08.633] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:08.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:46:0) after 1m0s
[09:37:08.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:46:0)
[09:37:08.683] Timer tick, asking Buildbucket for the build status
[09:37:08.758] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:08.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:47:0) after 1m0s
[09:38:08.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:47:0)
[09:38:08.793] Timer tick, asking Buildbucket for the build status
[09:38:08.906] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:08.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:48:0) after 1m0s
[09:39:08.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:48:0)
[09:39:08.938] Timer tick, asking Buildbucket for the build status
[09:39:10.605] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:10.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:49:0) after 1m0s
[09:40:10.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:49:0)
[09:40:10.626] Timer tick, asking Buildbucket for the build status
[09:40:10.709] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:10.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:50:0) after 1m0s
[09:41:10.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:50:0)
[09:41:10.727] Timer tick, asking Buildbucket for the build status
[09:41:10.863] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:10.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:51:0) after 1m0s
[09:42:10.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:51:0)
[09:42:10.905] Timer tick, asking Buildbucket for the build status
[09:42:11.183] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:11.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:52:0) after 1m0s
[09:43:11.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:52:0)
[09:43:11.201] Timer tick, asking Buildbucket for the build status
[09:43:11.355] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:11.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:53:0) after 1m0s
[09:44:11.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:53:0)
[09:44:11.380] Timer tick, asking Buildbucket for the build status
[09:44:11.460] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:11.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:54:0) after 1m0s
[09:45:11.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:54:0)
[09:45:11.463] Timer tick, asking Buildbucket for the build status
[09:45:11.554] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:11.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:55:0) after 1m0s
[09:46:11.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:55:0)
[09:46:11.569] Timer tick, asking Buildbucket for the build status
[09:46:11.657] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:11.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:56:0) after 1m0s
[09:47:11.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:56:0)
[09:47:11.689] Timer tick, asking Buildbucket for the build status
[09:47:12.322] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:12.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:57:0) after 1m0s
[09:48:12.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:57:0)
[09:48:12.337] Timer tick, asking Buildbucket for the build status
[09:48:12.436] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:12.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:58:0) after 1m0s
[09:49:12.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:58:0)
[09:49:12.482] Timer tick, asking Buildbucket for the build status
[09:49:12.623] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:12.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:59:0) after 1m0s
[09:50:12.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:59:0)
[09:50:12.650] Timer tick, asking Buildbucket for the build status
[09:50:12.787] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:12.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:60:0) after 1m0s
[09:51:12.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:60:0)
[09:51:12.806] Timer tick, asking Buildbucket for the build status
[09:51:14.542] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:14.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:61:0) after 1m0s
[09:52:14.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:61:0)
[09:52:14.563] Timer tick, asking Buildbucket for the build status
[09:52:14.650] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:14.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:62:0) after 1m0s
[09:53:14.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:62:0)
[09:53:14.709] Timer tick, asking Buildbucket for the build status
[09:53:14.801] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:14.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:63:0) after 1m0s
[09:54:14.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:63:0)
[09:54:14.872] Timer tick, asking Buildbucket for the build status
[09:54:14.952] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:14.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:64:0) after 1m0s
[09:55:15.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:64:0)
[09:55:15.025] Timer tick, asking Buildbucket for the build status
[09:55:15.097] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:15.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:65:0) after 1m0s
[09:56:15.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:65:0)
[09:56:15.145] Timer tick, asking Buildbucket for the build status
[09:56:15.228] Build 8853702883303142224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:15.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019132945216780800:66:0) after 1m0s
[09:57:01.730] Received PubSub notification, asking Buildbucket for the build status
[09:57:02.318] Build 8853702883303142224: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:57:02.318] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614851821324219",
"created_by": "project:chromium",
"created_ts": "1614847977309687",
"failure_reason": "BUILD_FAILURE",
"id": "8853702883303142224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8954fda32772b6251d9bc99322bb1e6ac66465c6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/DumpAccessibilityEventsTest.AccessibilityEventsSamePageLinkNavigation/uia\"}, \"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\": \"8954fda32772b6251d9bc99322bb1e6ac66465c6\", \"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-10-v3tg\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17627, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"90db604919ba6e3a5ffbdd43845ac33f91e7ddfa\", \"got_dawn_revision\": \"637d947937b7669fb606b8bc3c76f1b37769bcd0\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8954fda32772b6251d9bc99322bb1e6ac66465c6\", \"got_revision_cp\": \"refs/heads/master@{#859686}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"084529f3a9b9992a0de73fe333f677bced7b8fdb\", \"got_v8_revision\": \"1bf9a25b8165c205ba4ed305e8e84bc4a8e54fbc\", \"got_v8_revision_cp\": \"refs/heads/9.1.31@{#1}\", \"got_webrtc_revision\": \"07d83c8a9af02a0afc473503f75f150f23e3e0ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33366}\", \"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\": \"8954fda32772b6251d9bc99322bb1e6ac66465c6\", \"swarm_hashes_refs/heads/master(at){#859686}_without_patch\": {\"absl_hardening_tests\": \"961c9af9fd1ecba55a7561bfc8f124b83d8427f7\", \"accessibility_unittests\": \"2dcf235cc12b7e89493a24a9f7e8900b8b8fcb5b\", \"angle_unittests\": \"e46a5f50e57af47c5766df078065fa885c105826\", \"app_shell_unittests\": \"01ab5f706ce2d6f53d944a3b08fdbc53f55fcd89\", \"aura_unittests\": \"01c05f58f9a516ccdcea49b5fea931b945f518f1\", \"base_unittests\": \"ee8b167fb9750395bc98f7ba9649337a0ca34d8a\", \"base_util_unittests\": \"1c581cbfe0339090d93bf39d37c873c92a41e0f8\", \"blink_common_unittests\": \"e8ccea8c867ab5bd427230f5e049a9c6125ea2b0\", \"blink_fuzzer_unittests\": \"d2d43d2884ecf0f80ab2ea2b7c8e1eb6040a9993\", \"blink_heap_unittests\": \"8ebb781bf8e29af38be7ad88676d350ed8318c84\", \"blink_platform_unittests\": \"423b93e903c6aabdb7212ee56d750da06b06251d\", \"blink_unittests\": \"68e63c7c3de704c7b5f41c4599eca650c07e51b7\", \"boringssl_crypto_tests\": \"9535147e31a73a79acb3e43255295796325217d2\", \"boringssl_ssl_tests\": \"b879fc9b72743df1b506968654c59fbd5d0f06f2\", \"browser_switcher_bho_unittests\": \"622d740876f0a88de1f06f7f1b45beee03626c0f\", \"browser_tests\": \"02626f1297d68533f6683011f8cd0035b5feb866\", \"capture_unittests\": \"cbf64087a3c416e114ddb08999aa710cb8935962\", \"cast_unittests\": \"c4f32a6c8ed105a6fe06bed98a7d87b3eb5cc67c\", \"cc_unittests\": \"18e34215841f459a5f3e9fc2f28163bbd03f7d08\", \"chrome_app_unittests\": \"a6219d62c13e9299a11c40fc58fab7d4d6158363\", \"chrome_cleaner_unittests\": \"2010582c565b830580ef17dce14e035fd28166fb\", \"chrome_elf_unittests\": \"14624b42b0c8025c54ce0233e76b5df381ef56e4\", \"chromedriver_unittests\": \"a43d0a569af281c2437a5b27ffb265b78c72d050\", \"color_unittests\": \"6417ef8f6af5e5ace55ccec5c57bb75bb2bc3f9f\", \"components_browsertests\": \"a8cec7c3b2e3dd531a4c62b39d877c49652cb70b\", \"components_unittests\": \"92afecfd5a8cdd06ae6bef58419ce6afaf8b8877\", \"compositor_unittests\": \"2d527da37519b20ef3a5fecb4553fb10aab2c22d\", \"content_browsertests\": \"5e98e8fcd16e1184f5cf1d2e1333d1d4d30b6680\", \"content_unittests\": \"e35e95ed23b513b158b26560a9b28bee12ab4f02\", \"courgette_unittests\": \"20f0482fa3e86724e79dac3ee805011d6dbfd307\", \"crashpad_tests\": \"1e9fffcf71839fc4037ad325d71826dbd71a1c40\", \"cronet_tests\": \"d759e9463ea3a909b4c6992d7dc59309857bdfea\", \"cronet_unittests\": \"04d93311bdfcf57e98a8d81c6e9bfbfd4624a74d\", \"crypto_unittests\": \"d0f0d367217c8b13f8b2e31d83330f0fa10ebbf7\", \"delayloads_unittests\": \"6b2aa8ebe3692808fe4920116ecd6745b21716fb\", \"device_unittests\": \"964b207860a6db60b53b517e8e0d22b8ef421a96\", \"display_unittests\": \"bf73b3063bd422d863de1fe1ad71b96ea6030836\", \"elevation_service_unittests\": \"bac2f65e393339c292c14b4661c72938f68ed5c5\", \"events_unittests\": \"5d4feb7402ac51dbe243d5da4905ba0d545f86ab\", \"extensions_browsertests\": \"f1c4be42caab37507f4e304ef38453b0b41e3d44\", \"extensions_unittests\": \"bc16a8241052cbb5af9a0f0e53fd45ddae0ed3a1\", \"filesystem_service_unittests\": \"d8430245029b8f3e15bad8f24be25c4be39249d9\", \"gcm_unit_tests\": \"7c4201c5c3ea516683208bbe4f862f3bfa087018\", \"gcp_unittests\": \"366151f5965e841a96f2d3542d16a4c8e5069ae3\", \"gfx_unittests\": \"bcfdaae4299b808701dbe155fd41e9e72058fc47\", \"gin_unittests\": \"531966c37c15b83f0a52d4f6aa536e7e2bf9bc09\", \"google_apis_unittests\": \"41ff4f75ec91cdb2ead763e263af77f355fa436a\", \"gpu_unittests\": \"e3611946604aa53341287b2a82b716a84b4dbb68\", \"gwp_asan_unittests\": \"f648cc28d99a030b65023410c193799cf56a00ea\", \"headless_browsertests\": \"ab1e353cf171691bdb4fc1ab69c10b844aeea9fe\", \"headless_unittests\": \"00e5e1bed90729c78b13f5ccafbba838b6577efd\", \"install_static_unittests\": \"e5f8ba7e35aced3eab36f11cfe98ac54ca749862\", \"installer_util_unittests\": \"7fa1a71a9295fc50b583a923d17db7b07170ec98\", \"interactive_ui_tests\": \"837518b2db8d5b087656fa59dc6b4c8383ad9195\", \"ipc_tests\": \"a59de04c8e45733c2ac43a8717d76d516c1073bb\", \"jingle_unittests\": \"47da53d7d9b93ee910c2cd08dfabfdb10ca1cbcb\", \"latency_unittests\": \"80de4b9fed6e001faec39a3d05948f232430342e\", \"libjingle_xmpp_unittests\": \"102f89a2e49ff2d7c24d73281dc06359129cda76\", \"liburlpattern_unittests\": \"ba5ac6a3eb9908364fb2126a0df8ddd916bcc006\", \"media_blink_unittests\": \"b111c4806dbf8b364b9d19521bb338a795071046\", \"media_unittests\": \"1d1edcb2d1929e8fb4c711bbe7e2051e80794eb8\", \"message_center_unittests\": \"d7ab64aafa110b9f4c1ed1e55bec348df449934c\", \"midi_unittests\": \"e53fdae7052b6e9d66d41f091b258126a140c3f0\", \"mojo_core_unittests\": \"de4fe7baba302157469286bd7a9da00dff2fb33e\", \"mojo_unittests\": \"1d9581e2103059a9dcd187fddc455e081393aded\", \"nacl_loader_unittests\": \"896a2926b53e667dcf03833d60bf8d244245ebb0\", \"native_theme_unittests\": \"0e43ce5bb487245ac7c338b16c68f35929cabd8c\", \"net_unittests\": \"2d6f149138b4b88a942f90c207287717cf4ea771\", \"notification_helper_unittests\": \"e50ff6802854c91979011e622de3c744131ed24f\", \"pdf_unittests\": \"b69b1bfe6902e6c723fc0c37d141498cf69705f0\", \"perfetto_unittests\": \"116f99211f087e58540dc5cbf16b3036a08d6fd8\", \"ppapi_unittests\": \"1539918ae4b198abf4b06482a99e5239a08a90e4\", \"printing_unittests\": \"3dab2e3c522b74a04a06f38edd379de331f425a3\", \"remoting_unittests\": \"504f291d20f6ea15067e1d1be1988872a5344208\", \"sbox_integration_tests\": \"bb83e7441fe9ed9162a465e5e9818204fa36e72f\", \"sbox_unittests\": \"766344f697fbeb47c24373379604d303d2ff1eba\", \"sbox_validation_tests\": \"4dac19fc49e37863e6862a480194be5288960088\", \"service_manager_unittests\": \"0fce120551f4d0ff35ab3eb3aec3ba77519c8ed0\", \"services_unittests\": \"087eae249d22a1b20b3f9922bec6fca334d50b72\", \"setup_unittests\": \"97c3fb95bd9180c095a28a3ef7e68104b74eb1e4\", \"shell_dialogs_unittests\": \"f7d2a0d3f7322eddf2dff2c83fc0ab3c882f0103\", \"skia_unittests\": \"5db5fff01faba0f4e8329c2b3a0c719c717a98ac\", \"snapshot_unittests\": \"58974aa9feb3618d10c992cad04581086c6d76c2\", \"sql_unittests\": \"bda96d74a577ebabfda3cde7d9058b97298d3142\", \"storage_unittests\": \"02f40e8fe50b39da79a5320e0b57aad4f842dcc7\", \"sync_integration_tests\": \"1eb290f97b50884e4595077197bb10f8e802d8ba\", \"traffic_annotation_auditor_unittests\": \"da52a034e09278b3e0d591ba4b01bd05b3a46929\", \"ui_base_unittests\": \"992ca8922fce52ca97dfb5f3ce97333ba138178e\", \"ui_touch_selection_unittests\": \"e91815de8186a61b0de14f09fde1161a4771f717\", \"unit_tests\": \"ed425f885adcaa6d529a048c3462579e38368cb5\", \"updater_tests\": \"5c61a374c17a3f3f762bf2ce070ad15ae3aee174\", \"url_unittests\": \"f7c9b2f4101fb6749c4d8b502ae0b6622c2d3dc2\", \"views_unittests\": \"3de8f82686887f635fa917913e03a37b2284e86f\", \"viz_unittests\": \"55278fd42eee4a631d4ed78f793a5f59f358be41\", \"vr_common_unittests\": \"f97ad473dd6afd336442421c92e8bc33cb90565b\", \"vr_pixeltests\": \"2660d8d112f3de6aac2daf9b45b96fd6b6d1e4a3\", \"weblayer_browsertests\": \"f52add7e681040973ad23052009433bf972ba9e7\", \"weblayer_unittests\": \"0e6a1c9bc00ce91c9e466ea9e1ef9decfc4e99d4\", \"wm_unittests\": \"9278e7d0bc50506c184fbbb23fdb655e006419ed\", \"wtf_unittests\": \"3f7580d6b1a5adf7c1be3f3e3e39d07f46f4a317\", \"zlib_unittests\": \"375ea136e26e5679ac9c66df84971a06ef5f2eca\", \"zucchini_unittests\": \"a82f214c932efb0d4c77d3f87964c68eb0508bb5\"}}, \"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-10-v3tg\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/DumpAccessibilityEventsTest.AccessibilityEventsSamePageLinkNavigation/uia\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614847979147589",
"status": "COMPLETED",
"status_changed_ts": "1614847979828438",
"tags": [
"build_address:luci.chromium.ci/win-asan/17627",
"builder:win-asan",
"buildset:commit/git/8954fda32772b6251d9bc99322bb1e6ac66465c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8954fda32772b6251d9bc99322bb1e6ac66465c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019132945216780800",
"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/8853702883303142224/+/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:5215423499da5b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614851821324649",
"url": "https://ci.chromium.org/b/8853702883303142224",
"utcnow_ts": "1614851822290874"
}
}
[09:57:02.318] Invocation finished in 1h4m6.217789751s with status FAILED