[07:01:52.357] New invocation is queued and will start shortly
[07:01:53.397] Starting the invocation (attempt 1)
[07:01:53.470] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:01:53.470] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:01:53.470] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019864708405134928",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019864708405134928",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d",
"gitiles_ref:refs/heads/master"
]
}
[07:01:54.955] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614150113629629",
"id": "8854434646409086592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614150114544927",
"tags": [
"build_address:luci.chromium.ci/win-asan/17503",
"builder:win-asan",
"buildset:commit/git/6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019864708405134928",
"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/8854434646409086592/+/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": "1614150114545034",
"url": "https://ci.chromium.org/b/8854434646409086592",
"utcnow_ts": "1614150114948419"
}
}
[07:01:54.955] Task URL: https://ci.chromium.org/b/8854434646409086592
[07:01:54.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:2:0) after 1m0s
[07:01:58.792] Received PubSub notification, asking Buildbucket for the build status
[07:01:58.986] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:54.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:2:0)
[07:02:54.916] Timer tick, asking Buildbucket for the build status
[07:02:54.982] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:54.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:4:0) after 1m0s
[07:03:55.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:4:0)
[07:03:55.046] Timer tick, asking Buildbucket for the build status
[07:03:55.296] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:55.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:5:0) after 1m0s
[07:04:55.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:5:0)
[07:04:55.315] Timer tick, asking Buildbucket for the build status
[07:04:55.388] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:55.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:6:0) after 1m0s
[07:05:55.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:6:0)
[07:05:55.539] Timer tick, asking Buildbucket for the build status
[07:05:55.706] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:55.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:7:0) after 1m0s
[07:06:55.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:7:0)
[07:06:55.733] Timer tick, asking Buildbucket for the build status
[07:06:56.216] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:56.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:8:0) after 1m0s
[07:07:56.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:8:0)
[07:07:56.235] Timer tick, asking Buildbucket for the build status
[07:07:56.602] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:56.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:9:0) after 1m0s
[07:08:56.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:9:0)
[07:08:56.621] Timer tick, asking Buildbucket for the build status
[07:08:56.697] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:56.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:10:0) after 1m0s
[07:09:56.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:10:0)
[07:09:56.767] Timer tick, asking Buildbucket for the build status
[07:09:56.963] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:56.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:11:0) after 1m0s
[07:10:57.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:11:0)
[07:10:57.041] Timer tick, asking Buildbucket for the build status
[07:10:57.140] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:57.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:12:0) after 1m0s
[07:11:57.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:12:0)
[07:11:57.255] Timer tick, asking Buildbucket for the build status
[07:11:57.478] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:57.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:13:0) after 1m0s
[07:12:57.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:13:0)
[07:12:57.525] Timer tick, asking Buildbucket for the build status
[07:12:57.829] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:57.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:14:0) after 1m0s
[07:13:57.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:14:0)
[07:13:57.920] Timer tick, asking Buildbucket for the build status
[07:13:58.036] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:58.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:15:0) after 1m0s
[07:14:58.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:15:0)
[07:14:58.097] Timer tick, asking Buildbucket for the build status
[07:14:58.187] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:58.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:16:0) after 1m0s
[07:15:58.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:16:0)
[07:15:58.205] Timer tick, asking Buildbucket for the build status
[07:15:58.305] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:58.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:17:0) after 1m0s
[07:16:58.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:17:0)
[07:16:58.324] Timer tick, asking Buildbucket for the build status
[07:16:58.487] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:58.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:18:0) after 1m0s
[07:17:58.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:18:0)
[07:17:58.496] Timer tick, asking Buildbucket for the build status
[07:17:58.686] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:58.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:19:0) after 1m0s
[07:18:58.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:19:0)
[07:18:58.697] Timer tick, asking Buildbucket for the build status
[07:18:58.783] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:58.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:20:0) after 1m0s
[07:19:58.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:20:0)
[07:19:58.805] Timer tick, asking Buildbucket for the build status
[07:19:58.990] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:58.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:21:0) after 1m0s
[07:20:59.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:21:0)
[07:20:59.007] Timer tick, asking Buildbucket for the build status
[07:20:59.082] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:59.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:22:0) after 1m0s
[07:21:59.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:22:0)
[07:21:59.103] Timer tick, asking Buildbucket for the build status
[07:21:59.304] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:59.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:23:0) after 1m0s
[07:22:59.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:23:0)
[07:22:59.324] Timer tick, asking Buildbucket for the build status
[07:22:59.483] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:59.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:24:0) after 1m0s
[07:23:59.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:24:0)
[07:23:59.508] Timer tick, asking Buildbucket for the build status
[07:23:59.588] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:59.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:25:0) after 1m0s
[07:24:59.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:25:0)
[07:24:59.611] Timer tick, asking Buildbucket for the build status
[07:24:59.706] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:59.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:26:0) after 1m0s
[07:25:59.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:26:0)
[07:25:59.770] Timer tick, asking Buildbucket for the build status
[07:25:59.922] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:59.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:27:0) after 1m0s
[07:26:59.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:27:0)
[07:26:59.939] Timer tick, asking Buildbucket for the build status
[07:27:00.144] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:00.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:28:0) after 1m0s
[07:28:00.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:28:0)
[07:28:00.216] Timer tick, asking Buildbucket for the build status
[07:28:00.336] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:00.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:29:0) after 1m0s
[07:29:00.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:29:0)
[07:29:00.921] Timer tick, asking Buildbucket for the build status
[07:29:01.037] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:01.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:30:0) after 1m0s
[07:30:01.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:30:0)
[07:30:01.064] Timer tick, asking Buildbucket for the build status
[07:30:01.195] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:01.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:31:0) after 1m0s
[07:31:01.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:31:0)
[07:31:01.844] Timer tick, asking Buildbucket for the build status
[07:31:01.929] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:01.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:32:0) after 1m0s
[07:32:02.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:32:0)
[07:32:02.255] Timer tick, asking Buildbucket for the build status
[07:32:02.445] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:02.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:33:0) after 1m0s
[07:33:02.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:33:0)
[07:33:02.561] Timer tick, asking Buildbucket for the build status
[07:33:02.824] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:02.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:34:0) after 1m0s
[07:34:03.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:34:0)
[07:34:03.542] Timer tick, asking Buildbucket for the build status
[07:34:03.621] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:03.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:35:0) after 1m0s
[07:35:03.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:35:0)
[07:35:03.777] Timer tick, asking Buildbucket for the build status
[07:35:04.113] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:04.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:36:0) after 1m0s
[07:36:04.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:36:0)
[07:36:04.305] Timer tick, asking Buildbucket for the build status
[07:36:04.385] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:04.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:37:0) after 1m0s
[07:37:04.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:37:0)
[07:37:04.657] Timer tick, asking Buildbucket for the build status
[07:37:04.789] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:04.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:38:0) after 1m0s
[07:38:04.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:38:0)
[07:38:04.991] Timer tick, asking Buildbucket for the build status
[07:38:05.717] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:05.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:39:0) after 1m0s
[07:39:06.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:39:0)
[07:39:06.080] Timer tick, asking Buildbucket for the build status
[07:39:06.226] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:06.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:40:0) after 1m0s
[07:40:06.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:40:0)
[07:40:06.292] Timer tick, asking Buildbucket for the build status
[07:40:06.372] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:06.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:41:0) after 1m0s
[07:41:06.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:41:0)
[07:41:06.450] Timer tick, asking Buildbucket for the build status
[07:41:06.669] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:06.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:42:0) after 1m0s
[07:42:06.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:42:0)
[07:42:06.695] Timer tick, asking Buildbucket for the build status
[07:42:06.999] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:06.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:43:0) after 1m0s
[07:43:07.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:43:0)
[07:43:07.035] Timer tick, asking Buildbucket for the build status
[07:43:07.175] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:07.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:44:0) after 1m0s
[07:44:07.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:44:0)
[07:44:07.237] Timer tick, asking Buildbucket for the build status
[07:44:07.407] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:07.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:45:0) after 1m0s
[07:45:07.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:45:0)
[07:45:07.455] Timer tick, asking Buildbucket for the build status
[07:45:07.564] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:07.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:46:0) after 1m0s
[07:46:07.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:46:0)
[07:46:07.537] Timer tick, asking Buildbucket for the build status
[07:46:07.613] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:07.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:47:0) after 1m0s
[07:47:07.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:47:0)
[07:47:07.626] Timer tick, asking Buildbucket for the build status
[07:47:07.735] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:07.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:48:0) after 1m0s
[07:48:07.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:48:0)
[07:48:07.775] Timer tick, asking Buildbucket for the build status
[07:48:08.066] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:08.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:49:0) after 1m0s
[07:49:08.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:49:0)
[07:49:08.090] Timer tick, asking Buildbucket for the build status
[07:49:08.195] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:08.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:50:0) after 1m0s
[07:50:08.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:50:0)
[07:50:08.215] Timer tick, asking Buildbucket for the build status
[07:50:08.551] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:08.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:51:0) after 1m0s
[07:51:08.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:51:0)
[07:51:08.583] Timer tick, asking Buildbucket for the build status
[07:51:08.839] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:08.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:52:0) after 1m0s
[07:52:08.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:52:0)
[07:52:08.912] Timer tick, asking Buildbucket for the build status
[07:52:09.014] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:09.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:53:0) after 1m0s
[07:53:09.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:53:0)
[07:53:09.099] Timer tick, asking Buildbucket for the build status
[07:53:09.638] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:09.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:54:0) after 1m0s
[07:54:09.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:54:0)
[07:54:09.650] Timer tick, asking Buildbucket for the build status
[07:54:09.729] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:09.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:55:0) after 1m0s
[07:55:09.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:55:0)
[07:55:09.771] Timer tick, asking Buildbucket for the build status
[07:55:10.014] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:10.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:56:0) after 1m0s
[07:56:10.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:56:0)
[07:56:10.088] Timer tick, asking Buildbucket for the build status
[07:56:10.295] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:10.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:57:0) after 1m0s
[07:57:11.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:57:0)
[07:57:11.754] Timer tick, asking Buildbucket for the build status
[07:57:11.844] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:11.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:58:0) after 1m0s
[07:58:11.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:58:0)
[07:58:11.994] Timer tick, asking Buildbucket for the build status
[07:58:12.139] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:12.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:59:0) after 1m0s
[07:59:12.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:59:0)
[07:59:12.254] Timer tick, asking Buildbucket for the build status
[07:59:12.829] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:12.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:60:0) after 1m0s
[08:00:13.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:60:0)
[08:00:13.153] Timer tick, asking Buildbucket for the build status
[08:00:14.294] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:14.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:61:0) after 1m0s
[08:01:15.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:61:0)
[08:01:15.879] Timer tick, asking Buildbucket for the build status
[08:01:15.970] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:15.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:62:0) after 1m0s
[08:02:16.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:62:0)
[08:02:16.057] Timer tick, asking Buildbucket for the build status
[08:02:16.177] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:16.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:63:0) after 1m0s
[08:03:16.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:63:0)
[08:03:16.311] Timer tick, asking Buildbucket for the build status
[08:03:16.550] Build 8854434646409086592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:16.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019864708405134928:64:0) after 1m0s
[08:03:56.493] Received PubSub notification, asking Buildbucket for the build status
[08:03:56.610] Build 8854434646409086592: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:03:56.610] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614153836178067",
"created_by": "project:chromium",
"created_ts": "1614150113629629",
"failure_reason": "BUILD_FAILURE",
"id": "8854434646409086592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- ContextualNotificationPermissionUiSelectorTest.OnlyCrowdDenyEnabled\"}, \"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\": \"6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17503, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"f19f319a59c83d9ccdce08a3b41024cfc997fd8d\", \"got_dawn_revision\": \"d67544797e54d3866f378c2a7c05181db552f125\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d\", \"got_revision_cp\": \"refs/heads/master@{#857048}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"e6d93982be7f6f3686225c97ffe908935ef372dc\", \"got_v8_revision_cp\": \"refs/heads/9.0.242@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"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\": \"6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d\", \"swarm_hashes_refs/heads/master(at){#857048}_without_patch\": {\"absl_hardening_tests\": \"c09b678be0ed806b6f0edbcdb13730c9d2b429e2\", \"accessibility_unittests\": \"aedafe6e5adc533981d516626b7a7d802b2a27e8\", \"angle_unittests\": \"9a33f2e51748be2af06bd7a07675f97ad4b9644a\", \"app_shell_unittests\": \"334296dc50c912d65d2e8aa00828a7a7304d50d2\", \"aura_unittests\": \"0dfe0cdcb7d119da800ccd7ec026ca5386a6aa20\", \"base_unittests\": \"9cc00c3702d492be8ca51cec2c6752478a9ae81b\", \"base_util_unittests\": \"a941540828cdc9294a8f290e1136da7297fe21f9\", \"blink_common_unittests\": \"b1e086792fa0e17a9e07aeb30d41078372174023\", \"blink_fuzzer_unittests\": \"b36fcb3c352fd7ed77c24f8d991e8863e6095a99\", \"blink_heap_unittests\": \"c64be8d6f165ef345ce7a0bcf8fbfa849eb4a760\", \"blink_platform_unittests\": \"f83631768e35be38d29f33a4f11ecfcb3a098630\", \"blink_unittests\": \"4ec15a975c6d5feea4d436b3341798d0f8f4c0f3\", \"boringssl_crypto_tests\": \"eaffa5d12adf692bdb7e8fa939898fe908aba020\", \"boringssl_ssl_tests\": \"b90a5dcfcf288c06f1c7b510298b340cccdb39bf\", \"browser_switcher_bho_unittests\": \"502055f7ba822262a52281d357953ba709b5880c\", \"browser_tests\": \"284fc9c11a8217ec7cc7273531e9fff4c3db95c3\", \"capture_unittests\": \"bdb8d77165cbdb4728ec89ecb8e02405839da5fb\", \"cast_unittests\": \"307be1551a803aa1978b2ba36759594605b45368\", \"cc_unittests\": \"cd3178d6d7779e4ecb7f29336242070b0157cb35\", \"chrome_app_unittests\": \"07778a739c45a730d873a8a8aef7e27355dae44c\", \"chrome_cleaner_unittests\": \"d66f7c892314a11fa597b50cb4d6e524e8cf7ec2\", \"chrome_elf_unittests\": \"88bfb597bb8fbfbaa08abbb271bcd303dc517794\", \"chromedriver_unittests\": \"ad27b38fc5a1256312ee3cee3ab23bdc2c0c0de5\", \"color_unittests\": \"a780e6fafda641df12728f4ee4e5dc9ae09c1786\", \"components_browsertests\": \"3013e0b8e69155de4273cbde927b45d4243ccd41\", \"components_unittests\": \"0be45c434d13140b145515939d88057669c40f56\", \"compositor_unittests\": \"93ab4e0dcb781c374f5f7372f68976f51485c2bd\", \"content_browsertests\": \"5ad5a04b8b474233d2975eb2e9742d1fd0911120\", \"content_unittests\": \"627171903f1e4181dd29f69892e86057031c713d\", \"courgette_unittests\": \"5e5f411c1842bcda1df7c4881aeac1fe9c2d6db5\", \"crashpad_tests\": \"02299e5d3d0577ee61f53495d06d069e4d20744e\", \"cronet_tests\": \"9f3067284dba30c5ced974142d0712c5e3e44e10\", \"cronet_unittests\": \"8c5e5b3becc861e73cb293f96d95bbfcabd74eb8\", \"crypto_unittests\": \"77790055e53a525dc18193822ac154df6e0e7705\", \"delayloads_unittests\": \"2c65d0cea9d4a73f3bf18d13443eb1954a65fa47\", \"device_unittests\": \"0a4bb5e8d0cf7ff55c2f1b9c522e3b8930bbf057\", \"display_unittests\": \"9fa8a044f88b2bdd0ff731d805334ed41be8afc9\", \"elevation_service_unittests\": \"e0e2318706243d483eb0cca222c23f9fb4872875\", \"events_unittests\": \"b301cc648343122e8de66138e834844505ea06b3\", \"extensions_browsertests\": \"573c5abfa157548f02d2cf96d68992461d05d55a\", \"extensions_unittests\": \"be3bf85e761ef8360e15369c729d44b18d1df293\", \"filesystem_service_unittests\": \"ae9c89254b6e728bee60d3445690aead57840f7e\", \"gcm_unit_tests\": \"0e687452eecad8da6ab23f997eb02d786c040ab8\", \"gcp_unittests\": \"685cf7c3c2da655de2ab96b524abc43990735f08\", \"gfx_unittests\": \"cf1585b7ceee806fe76aa86bb0c91671cff67749\", \"gin_unittests\": \"5a9875cb36bb26653c9cbbab09f959e11c282fd4\", \"google_apis_unittests\": \"b594c4293217c45e9fd77dfbb93da5fe16328aeb\", \"gpu_unittests\": \"8b0bf3f15d779612237cf1e07d39891ea13d7bd0\", \"gwp_asan_unittests\": \"93e7dedd71ad06ca4e9731928c85687ae8303eb8\", \"headless_browsertests\": \"eaf0a34be91b4563e3798e834a34d3e17af33fa2\", \"headless_unittests\": \"2f0f56c98043d4b134d7c9871f8ccc7e8993454e\", \"install_static_unittests\": \"f1d7bf40b1885a60a503850956ea209b58377dab\", \"installer_util_unittests\": \"76e210378c120555f5c61c07e767abcebf275592\", \"interactive_ui_tests\": \"4876336f48ba7461a0fee3cfd8c923feb73df4ee\", \"ipc_tests\": \"512e3afbc3d5bb568c32f2e701ae45b3e48efa36\", \"jingle_unittests\": \"a918a821b8896e4d55639bb991345b32d791cd59\", \"latency_unittests\": \"000ff469a02bf411d88a3ebd7c3041bfda18eef0\", \"libjingle_xmpp_unittests\": \"cf811a9274454b820668f44982206f2bb6c5c704\", \"liburlpattern_unittests\": \"f28715e14064e19e541ca80fce0f481a04959edb\", \"media_blink_unittests\": \"118aa7c3162d732fc013ac27ba35931dd49bd970\", \"media_unittests\": \"9c01448c0377a2383cf816d28fa808e457cbd060\", \"message_center_unittests\": \"153cf70363af19949cc15a35b7742aa825bf2eea\", \"midi_unittests\": \"c90baffa09988920115649f36f5f625caaefa5e4\", \"mojo_core_unittests\": \"d89a4400c4185bf9aad33000eee2b4b2eeb3fe06\", \"mojo_unittests\": \"c264a4461462d7757a006dc2ed314741d98f452f\", \"nacl_loader_unittests\": \"23f1a02f64b73ca31c9c6646a4d559d866e94a9d\", \"native_theme_unittests\": \"9a81cf42276126220b8319da270a8cbd58a9d738\", \"net_unittests\": \"6ad41f1f0bb6366a285c1962fb4a97d6545bc4bd\", \"notification_helper_unittests\": \"27003583639d4bafcb1fc7f3d15b5c62335aac75\", \"pdf_unittests\": \"cba7e8ae2cd219d52aacfcefdc74bf769f32c857\", \"perfetto_unittests\": \"69e39dd837ab596b3cd4b1694963355a1f38279b\", \"ppapi_unittests\": \"3b5cc196e3ee38bcd6917564d6d91837cf125cd5\", \"printing_unittests\": \"f64ec4ef4f6de7bbdc5fd05709a795beefa97db1\", \"remoting_unittests\": \"b1391b8f8d3ad91fe962f98ddb3b8c61948e13e4\", \"sbox_integration_tests\": \"7f7d39e2bac7ce89ef60f1e0a4bd9250756285dc\", \"sbox_unittests\": \"cdb819388ceb08f4e441401c98816ca204167a9b\", \"sbox_validation_tests\": \"1c33e4b9e8aad7866111db259bf54c3a86256e77\", \"service_manager_unittests\": \"6a3114cbc13738920999d02762007f74ff3c61f2\", \"services_unittests\": \"7a748f72e8485d714add645158cb92daa7972bfb\", \"setup_unittests\": \"0251187a1b8436ba346f5cca91ea39915de88b07\", \"shell_dialogs_unittests\": \"52c4d11dc7b94d4c136a721ab6ba6a0ea6d35435\", \"skia_unittests\": \"397c39b6ce2040002da3309680aeb18420f5f036\", \"snapshot_unittests\": \"9bc9b4399ec7c3dd08dec4421ac4356d7d050827\", \"sql_unittests\": \"6dc93b26201fd4dc78f4db2274331a1b2a559899\", \"storage_unittests\": \"735d1ced09cae1a9248cc73894b6abaaa4983b22\", \"sync_integration_tests\": \"9eb23f9e1e2f00067011f131f4ba99a79e2adbf9\", \"traffic_annotation_auditor_unittests\": \"4cd1c88a93eea942d12edc43959a4130ebef5d86\", \"ui_base_unittests\": \"3a3cbe9d74e2b60233ec9f1ee8018816c3d90ae7\", \"ui_touch_selection_unittests\": \"25fb80f4d861180f121283aabd69bb365e81bdde\", \"unit_tests\": \"c60ec06be3f23e7e563dbd70a04eab7d5dcb9935\", \"updater_tests\": \"6ec36c6ca8a3d7adb6ab95ffa4aef59f3d797fd5\", \"url_unittests\": \"719f1474188acdbf90211d77ff285b64188f6a7f\", \"views_unittests\": \"e80eacdddd3857460e7b42713f2e230370f7223a\", \"viz_unittests\": \"fefcc41268433526853af5cb4a6720a57bcc1a35\", \"vr_common_unittests\": \"59240992723a7d8b978c3a2213bfbb2ea162a25d\", \"vr_pixeltests\": \"79ed5e6bf7582e28c2d804e1640a73d755679ebf\", \"weblayer_browsertests\": \"134c80d65c965e1408a5095aabf477e655eedcf0\", \"weblayer_unittests\": \"fa5712bd68a6d482cb386e036461b4621de1a76d\", \"wm_unittests\": \"afcfdf51fc894b0d3224b28fb859532a46438e12\", \"wtf_unittests\": \"0459351d3a6044def752fb1c435eb0d982fc3ee2\", \"zlib_unittests\": \"a890387f5c9a88dc3667795f0feb5e093dc32e52\", \"zucchini_unittests\": \"bb881c14deea9e67d74c03ecccda6383108c6dfa\"}}, \"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-3-xpf4\"], \"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\": [\"5709-d3c4474\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- ContextualNotificationPermissionUiSelectorTest.OnlyCrowdDenyEnabled\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614150117534688",
"status": "COMPLETED",
"status_changed_ts": "1614150118404511",
"tags": [
"build_address:luci.chromium.ci/win-asan/17503",
"builder:win-asan",
"buildset:commit/git/6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6bf7dcbdb41ceb0d6d5bdfcc3db214c4d17bdb5d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019864708405134928",
"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/8854434646409086592/+/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:51eba9a75655cb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614153836179010",
"url": "https://ci.chromium.org/b/8854434646409086592",
"utcnow_ts": "1614153836600986"
}
}
[08:03:56.610] Invocation finished in 1h2m4.26766099s with status FAILED