[23:34:47.571] New invocation is queued and will start shortly
[23:34:49.291] Starting the invocation (attempt 1)
[23:34:49.333] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:34:49.333] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:34:49.333] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021523581633890304",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"15d7396f9e0a2cc06a69d1f360f179ffe30dd318\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021523581633890304",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/15d7396f9e0a2cc06a69d1f360f179ffe30dd318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/15d7396f9e0a2cc06a69d1f360f179ffe30dd318",
"gitiles_ref:refs/heads/master"
]
}
[23:34:50.228] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612568089417822",
"id": "8856093519029994320",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"15d7396f9e0a2cc06a69d1f360f179ffe30dd318\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612568089910761",
"tags": [
"build_address:luci.chromium.ci/win-asan/17189",
"builder:win-asan",
"buildset:commit/git/15d7396f9e0a2cc06a69d1f360f179ffe30dd318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/15d7396f9e0a2cc06a69d1f360f179ffe30dd318",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021523581633890304",
"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/8856093519029994320/+/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": "1612568089910956",
"url": "https://ci.chromium.org/b/8856093519029994320",
"utcnow_ts": "1612568090218543"
}
}
[23:34:50.228] Task URL: https://ci.chromium.org/b/8856093519029994320
[23:34:50.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:2:0) after 1m0s
[23:34:55.126] Received PubSub notification, asking Buildbucket for the build status
[23:34:55.266] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:50.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:2:0)
[23:35:50.260] Timer tick, asking Buildbucket for the build status
[23:35:50.359] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:50.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:4:0) after 1m0s
[23:36:50.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:4:0)
[23:36:50.335] Timer tick, asking Buildbucket for the build status
[23:36:51.685] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:51.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:5:0) after 1m0s
[23:37:51.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:5:0)
[23:37:51.720] Timer tick, asking Buildbucket for the build status
[23:37:51.888] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:51.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:6:0) after 1m0s
[23:38:51.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:6:0)
[23:38:51.869] Timer tick, asking Buildbucket for the build status
[23:38:52.242] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:52.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:7:0) after 1m0s
[23:39:52.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:7:0)
[23:39:52.295] Timer tick, asking Buildbucket for the build status
[23:39:52.392] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:52.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:8:0) after 1m0s
[23:40:52.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:8:0)
[23:40:52.411] Timer tick, asking Buildbucket for the build status
[23:40:52.509] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:52.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:9:0) after 1m0s
[23:41:52.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:9:0)
[23:41:52.912] Timer tick, asking Buildbucket for the build status
[23:41:52.977] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:52.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:10:0) after 1m0s
[23:42:52.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:10:0)
[23:42:52.974] Timer tick, asking Buildbucket for the build status
[23:42:53.028] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:53.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:11:0) after 1m0s
[23:43:53.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:11:0)
[23:43:53.052] Timer tick, asking Buildbucket for the build status
[23:43:53.188] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:53.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:12:0) after 1m0s
[23:44:53.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:12:0)
[23:44:53.252] Timer tick, asking Buildbucket for the build status
[23:44:53.326] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:53.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:13:0) after 1m0s
[23:45:53.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:13:0)
[23:45:53.399] Timer tick, asking Buildbucket for the build status
[23:45:53.701] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:53.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:14:0) after 1m0s
[23:46:53.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:14:0)
[23:46:53.733] Timer tick, asking Buildbucket for the build status
[23:46:53.890] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:53.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:15:0) after 1m0s
[23:47:53.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:15:0)
[23:47:53.907] Timer tick, asking Buildbucket for the build status
[23:47:53.962] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:53.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:16:0) after 1m0s
[23:48:54.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:16:0)
[23:48:54.062] Timer tick, asking Buildbucket for the build status
[23:48:54.233] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:54.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:17:0) after 1m0s
[23:49:54.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:17:0)
[23:49:54.251] Timer tick, asking Buildbucket for the build status
[23:49:54.366] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:54.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:18:0) after 1m0s
[23:50:54.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:18:0)
[23:50:54.383] Timer tick, asking Buildbucket for the build status
[23:50:54.453] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:54.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:19:0) after 1m0s
[23:51:54.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:19:0)
[23:51:54.502] Timer tick, asking Buildbucket for the build status
[23:51:55.457] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:55.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:20:0) after 1m0s
[23:52:55.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:20:0)
[23:52:55.569] Timer tick, asking Buildbucket for the build status
[23:52:55.647] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:55.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:21:0) after 1m0s
[23:53:55.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:21:0)
[23:53:55.642] Timer tick, asking Buildbucket for the build status
[23:53:55.711] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:55.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:22:0) after 1m0s
[23:54:55.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:22:0)
[23:54:55.823] Timer tick, asking Buildbucket for the build status
[23:54:56.514] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:56.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:23:0) after 1m0s
[23:55:56.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:23:0)
[23:55:56.597] Timer tick, asking Buildbucket for the build status
[23:55:56.708] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:56.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:24:0) after 1m0s
[23:56:56.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:24:0)
[23:56:56.811] Timer tick, asking Buildbucket for the build status
[23:56:56.933] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:56.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:25:0) after 1m0s
[23:57:56.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:25:0)
[23:57:56.967] Timer tick, asking Buildbucket for the build status
[23:57:57.318] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:57.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:26:0) after 1m0s
[23:58:57.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:26:0)
[23:58:57.371] Timer tick, asking Buildbucket for the build status
[23:58:57.478] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:57.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:27:0) after 1m0s
[23:59:57.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:27:0)
[23:59:57.531] Timer tick, asking Buildbucket for the build status
[23:59:57.993] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:57.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:28:0) after 1m0s
[00:00:58.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:28:0)
[00:00:58.070] Timer tick, asking Buildbucket for the build status
[00:00:58.184] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:58.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:29:0) after 1m0s
[00:01:58.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:29:0)
[00:01:58.273] Timer tick, asking Buildbucket for the build status
[00:01:58.700] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:58.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:30:0) after 1m0s
[00:02:58.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:30:0)
[00:02:58.741] Timer tick, asking Buildbucket for the build status
[00:02:58.880] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:58.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:31:0) after 1m0s
[00:03:58.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:31:0)
[00:03:58.951] Timer tick, asking Buildbucket for the build status
[00:03:59.136] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:59.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:32:0) after 1m0s
[00:04:59.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:32:0)
[00:04:59.148] Timer tick, asking Buildbucket for the build status
[00:04:59.312] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:59.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:33:0) after 1m0s
[00:05:59.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:33:0)
[00:05:59.330] Timer tick, asking Buildbucket for the build status
[00:05:59.453] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:59.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:34:0) after 1m0s
[00:06:59.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:34:0)
[00:06:59.470] Timer tick, asking Buildbucket for the build status
[00:06:59.550] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:59.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:35:0) after 1m0s
[00:07:59.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:35:0)
[00:07:59.672] Timer tick, asking Buildbucket for the build status
[00:08:00.207] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:00.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:36:0) after 1m0s
[00:09:00.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:36:0)
[00:09:00.303] Timer tick, asking Buildbucket for the build status
[00:09:00.433] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:00.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:37:0) after 1m0s
[00:10:00.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:37:0)
[00:10:00.704] Timer tick, asking Buildbucket for the build status
[00:10:00.819] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:00.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:38:0) after 1m0s
[00:11:00.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:38:0)
[00:11:00.845] Timer tick, asking Buildbucket for the build status
[00:11:00.911] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:00.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:39:0) after 1m0s
[00:12:00.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:39:0)
[00:12:00.973] Timer tick, asking Buildbucket for the build status
[00:12:01.055] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:01.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:40:0) after 1m0s
[00:13:01.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:40:0)
[00:13:01.481] Timer tick, asking Buildbucket for the build status
[00:13:02.238] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:02.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:41:0) after 1m0s
[00:14:02.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:41:0)
[00:14:02.756] Timer tick, asking Buildbucket for the build status
[00:14:03.020] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:03.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:42:0) after 1m0s
[00:15:03.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:42:0)
[00:15:03.091] Timer tick, asking Buildbucket for the build status
[00:15:03.831] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:03.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:43:0) after 1m0s
[00:16:03.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:43:0)
[00:16:03.877] Timer tick, asking Buildbucket for the build status
[00:16:03.953] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:03.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:44:0) after 1m0s
[00:17:04.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:44:0)
[00:17:04.010] Timer tick, asking Buildbucket for the build status
[00:17:04.227] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:04.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:45:0) after 1m0s
[00:18:04.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:45:0)
[00:18:04.337] Timer tick, asking Buildbucket for the build status
[00:18:04.528] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:04.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:46:0) after 1m0s
[00:19:04.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:46:0)
[00:19:04.583] Timer tick, asking Buildbucket for the build status
[00:19:04.681] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:04.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:47:0) after 1m0s
[00:20:04.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:47:0)
[00:20:04.726] Timer tick, asking Buildbucket for the build status
[00:20:04.847] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:04.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:48:0) after 1m0s
[00:21:04.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:48:0)
[00:21:04.925] Timer tick, asking Buildbucket for the build status
[00:21:05.025] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:05.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:49:0) after 1m0s
[00:22:05.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:49:0)
[00:22:05.067] Timer tick, asking Buildbucket for the build status
[00:22:05.180] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:05.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:50:0) after 1m0s
[00:23:05.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:50:0)
[00:23:05.290] Timer tick, asking Buildbucket for the build status
[00:23:05.435] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:05.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:51:0) after 1m0s
[00:24:05.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:51:0)
[00:24:05.521] Timer tick, asking Buildbucket for the build status
[00:24:05.897] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:05.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:52:0) after 1m0s
[00:25:05.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:52:0)
[00:25:05.965] Timer tick, asking Buildbucket for the build status
[00:25:06.197] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:06.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:53:0) after 1m0s
[00:26:06.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:53:0)
[00:26:06.221] Timer tick, asking Buildbucket for the build status
[00:26:06.305] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:06.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:54:0) after 1m0s
[00:27:06.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:54:0)
[00:27:06.321] Timer tick, asking Buildbucket for the build status
[00:27:06.398] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:06.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:55:0) after 1m0s
[00:28:06.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:55:0)
[00:28:06.434] Timer tick, asking Buildbucket for the build status
[00:28:06.721] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:06.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:56:0) after 1m0s
[00:29:06.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:56:0)
[00:29:06.780] Timer tick, asking Buildbucket for the build status
[00:29:06.846] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:06.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:57:0) after 1m0s
[00:30:06.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:57:0)
[00:30:06.922] Timer tick, asking Buildbucket for the build status
[00:30:07.088] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:07.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:58:0) after 1m0s
[00:31:07.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:58:0)
[00:31:07.064] Timer tick, asking Buildbucket for the build status
[00:31:07.184] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:07.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:59:0) after 1m0s
[00:32:07.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:59:0)
[00:32:07.215] Timer tick, asking Buildbucket for the build status
[00:32:07.342] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:07.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:60:0) after 1m0s
[00:33:07.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:60:0)
[00:33:07.364] Timer tick, asking Buildbucket for the build status
[00:33:07.506] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:07.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:61:0) after 1m0s
[00:34:07.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:61:0)
[00:34:07.746] Timer tick, asking Buildbucket for the build status
[00:34:07.825] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:07.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:62:0) after 1m0s
[00:35:07.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:62:0)
[00:35:07.925] Timer tick, asking Buildbucket for the build status
[00:35:08.029] Build 8856093519029994320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:08.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021523581633890304:63:0) after 1m0s
[00:36:01.744] Received PubSub notification, asking Buildbucket for the build status
[00:36:01.899] Build 8856093519029994320: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:36:01.899] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612571760074992",
"created_by": "project:chromium",
"created_ts": "1612568089417822",
"failure_reason": "BUILD_FAILURE",
"id": "8856093519029994320",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"15d7396f9e0a2cc06a69d1f360f179ffe30dd318\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ThreatDOMDetailsTest.Everything\\n\\n- TranslateAgentBrowserTest.TranslateLibNeverReady\\n\\n- SubresourceRedirectPublicImageHintsDeciderAgentTest.TestGetSubresourceURL\\n\\n- PasswordGenerationAgentTest.GenerationFallback_NoFocusedElement\\n\\n- ...5 more failure(s) (9 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"15d7396f9e0a2cc06a69d1f360f179ffe30dd318\", \"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-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17189, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"d3c00f959f9d4c071143dbe36ba22f8de68d8c67\", \"got_dawn_revision\": \"14a2398e711f5df5dbb0ec45999d6b16c1e80304\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"15d7396f9e0a2cc06a69d1f360f179ffe30dd318\", \"got_revision_cp\": \"refs/heads/master@{#851339}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"1184b5537fdc37535cb3d099db6a49a2b759f20b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33176}\", \"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\": \"15d7396f9e0a2cc06a69d1f360f179ffe30dd318\", \"swarm_hashes_refs/heads/master(at){#851339}_without_patch\": {\"absl_hardening_tests\": \"b2896a3fdbe8c54d7ff1c9154b2e7c0ca455f509\", \"accessibility_unittests\": \"8e32d231c9e433e16a7da56f1cdd6b5bf7fc932d\", \"angle_unittests\": \"c9bef1ac77a919f8375bdd8e1fe71da4bbbeb880\", \"app_shell_unittests\": \"2a04ffe02e828208e3e71277408f5891c4bf76d7\", \"aura_unittests\": \"12d648bed5b9c8fa2291eeb3f3cc3c530fc458f7\", \"base_unittests\": \"a819dd60d5a2caedb19771f2d4b08e8d18da3bb3\", \"base_util_unittests\": \"2c248a2bc780d6064523f2a340c47ea37e78a0bf\", \"blink_common_unittests\": \"d581240594bf85dd1aaadf6ced1ff3733594f486\", \"blink_fuzzer_unittests\": \"541794e17e93a082a392e2d7d1ded5fb38c1c4ae\", \"blink_heap_unittests\": \"ab05cfe03c5c0a48dd6387e4e7816e27e9d92716\", \"blink_platform_unittests\": \"98deb2b68f888db4101b59da90681c70d9530c2a\", \"blink_unittests\": \"d36dde32dd58647166602f86bde59126a09b7c1e\", \"boringssl_crypto_tests\": \"3674119f78b6fb5d448b1aa047cb291de218f4c5\", \"boringssl_ssl_tests\": \"b656410778ce2143a121e41c0836d51479464557\", \"browser_switcher_bho_unittests\": \"485b19d0933fe8722f2fcb7cccf257b89b290d3d\", \"browser_tests\": \"04283e02ae5a73d0ea210126a3e75badcd5e1f11\", \"capture_unittests\": \"b96b3e45351f4685ca8bdfa6d10b231b16d9f626\", \"cast_unittests\": \"9d2469cc0924fd953f29a6406b4666d6a980bc73\", \"cc_unittests\": \"d33fd3cd67472638772798f46afdeeb31ec87c75\", \"chrome_app_unittests\": \"ebf8bac5a096f0f483db22a1fdca63ee9807d5e6\", \"chrome_cleaner_unittests\": \"4e8fe387ad123b8a288a12feba148ec6c86ce955\", \"chrome_elf_unittests\": \"ff044157bbe4bd223f19c280745649924fe76fd7\", \"chromedriver_unittests\": \"7d690e6e532a2c9c1fb21e2f851e18b373e0b65b\", \"components_browsertests\": \"c275a2c683767070d417ef82e7e0b10d30e0e13d\", \"components_unittests\": \"f603df118ffd546ce705799a9f9ff4a6442fb2ee\", \"compositor_unittests\": \"d8cd394e5ddc68d06332fa69b66232c2f9f9d610\", \"content_browsertests\": \"5cc914393a104741ff50d7d6549650de1b004842\", \"content_unittests\": \"81224a5a552818e42493934e4c056b3c2dd54673\", \"courgette_unittests\": \"5bb28a874982b2b0aa9eab9fd0768157b9d31738\", \"crashpad_tests\": \"1d8dba17352ed252fecb495c2988d8cdbd3da1d1\", \"cronet_tests\": \"ab44eb41527d1e9c5e1a78427b6a7c621ff8abcc\", \"cronet_unittests\": \"74740369e51319cf227a18efb8409dd661455b1e\", \"crypto_unittests\": \"a44bfa3aa10c02b82d36f41ee5ad7e5c06002437\", \"delayloads_unittests\": \"be24f1296c4fed864623a06e0aa267187bb694ab\", \"device_unittests\": \"f5f8be4f4b84f4f58b92317e4d6114fa9a6a12e1\", \"display_unittests\": \"1913db9540f15aca69215257ea2135caca7d6d41\", \"elevation_service_unittests\": \"06e1a21f94f703cfda0044bc6acd52108ce663b2\", \"events_unittests\": \"47ef1952fd63e6af8bb0cc426583d0e04cf14e5f\", \"extensions_browsertests\": \"b650894b818d4eadccc666d527ad6585ad669d79\", \"extensions_unittests\": \"fb11006f06349b000f30fba14b08fdb596f87388\", \"filesystem_service_unittests\": \"8c0095d12263818d84f2f29f37a3a0329688ebf3\", \"gcm_unit_tests\": \"15756d24c0671ab30d26d8aa6dacbd85f9d224fd\", \"gcp_unittests\": \"d25be585d031b9e66a791d1211b022b5f2111bed\", \"gfx_unittests\": \"e3984341fb242565f2bf4f94c160d361ed8dec4a\", \"gin_unittests\": \"3ec35c4a461171ce6312c9a0e6313134784e86d7\", \"google_apis_unittests\": \"e7aa1cce14944f59f53e1d4e2abfb9864cc58604\", \"gpu_unittests\": \"1f6195ab7b35236fd6c488912a2cdccea6fddb4c\", \"gwp_asan_unittests\": \"3f12a26ac05fc188722b1db09323f2fb66f09567\", \"headless_browsertests\": \"84f3eee6d85acf690eb64b623eb7a6dd492587c6\", \"headless_unittests\": \"2c8903bee258ce8765914cd8fea03a0a0d5d932f\", \"install_static_unittests\": \"050752ffaa49ca6c7bb31e63880f6e43afdfbcfc\", \"installer_util_unittests\": \"7c505a554ed19c3fb747a318b6c7b93a968db193\", \"interactive_ui_tests\": \"bc6170c28e139595772c4d6b7acf875bb17cd6d1\", \"ipc_tests\": \"7e9e47ef7f9a6388b3eb54368beec43fab7966ea\", \"jingle_unittests\": \"f9b5fb21b186571ef66e03de08c2bea6d8db8d50\", \"latency_unittests\": \"00f1ec7748de4336a22add07c39739290da4ab40\", \"libjingle_xmpp_unittests\": \"a245eae193a3056e519479269c9387a7ed54bb1b\", \"liburlpattern_unittests\": \"076a596ba380ece726dc4ad95ee284b217d46171\", \"media_blink_unittests\": \"e30e874ba030286a98989ef498a65b0c2e3d9bcf\", \"media_unittests\": \"2d68d814fb4e3343cb886a84983eeff64300b755\", \"message_center_unittests\": \"a38e3ab8d27accf5d129701b93f8cd5ea952d0a1\", \"midi_unittests\": \"40984b70f42f3687f6ba25c7cc3147d2253844f2\", \"mojo_core_unittests\": \"cd9080ce16bce4c08794a90dde3c7385a1967165\", \"mojo_unittests\": \"b39ab2b58299d8e1f3c7a943f890192813cf3dcf\", \"nacl_loader_unittests\": \"e129df5a54e741f82cd8fb299fc8a098a1661ccb\", \"native_theme_unittests\": \"c0b54def582df3ddd303555180db08e289c64aea\", \"net_unittests\": \"0e51ebb36d58b39b944536e8c71c1595d589d803\", \"notification_helper_unittests\": \"9b963ba1990368b893b4c157fbd1d2466c50db05\", \"pdf_unittests\": \"e5cc22b5332b8caec9015e0fe0e97165ae78f26c\", \"perfetto_unittests\": \"bbe75d2bdc74c9576bfa4ab397fb1a8110f6ffcc\", \"ppapi_unittests\": \"97aa764a2e4a9f37e97a60f714f7a010b4a80103\", \"printing_unittests\": \"fdf28b3e172c34d63b621909672d9f48fbbf2c4c\", \"remoting_unittests\": \"820f476887c12651d404bf04027abdd120f1deb0\", \"sbox_integration_tests\": \"ceb145e70e83db115a68616488ea69746d2a4ed3\", \"sbox_unittests\": \"cc7e6802547b966100315e37d85b0498676fe507\", \"sbox_validation_tests\": \"fe65068fd49c626fb8ba5c82baee640092152582\", \"service_manager_unittests\": \"630bf317df25779981b1407aaabe14ba6cf44886\", \"services_unittests\": \"80b2a0a06aab8cfff91791321ed27abd39d322f3\", \"setup_unittests\": \"e0ed8b13a97e427ea8a8a1371862d19a726b465b\", \"shell_dialogs_unittests\": \"e6716de92dce8e6b8c51783e7cd3e41ca9031b3d\", \"skia_unittests\": \"c4bc63015a82e800e2dc85d391fb268ba614ba7b\", \"snapshot_unittests\": \"b1935080c00ebf3a22fe833bc2fa15ee201a069f\", \"sql_unittests\": \"287ecaa16029c7f8d648c4c198a5b84679bde1d6\", \"storage_unittests\": \"1f0eb0ee0ed085f25313ca3a6675763d91994cc5\", \"sync_integration_tests\": \"98e342233eeec6dcc73f5ba5b5c0800f9fc20d10\", \"traffic_annotation_auditor_unittests\": \"77c23312e8387450d86d7f15dcf9d40de9adfebd\", \"ui_base_unittests\": \"02d0c73e5d3fde2aed541aadb542fa3433cea2d5\", \"ui_touch_selection_unittests\": \"9a5d0770398bc72701318fe0bb7146250aed3785\", \"unit_tests\": \"a1fea476377d30ec157e0d08e846a07a0ae6bcf4\", \"updater_tests\": \"cf808f21e55be0f375eb3f60b7022bdfbf869cbc\", \"url_unittests\": \"c8f4123b5480720d5bc8eea9ce9fd4f67211c828\", \"views_unittests\": \"867230e41e6941418a280672cd1a73b8698acb9e\", \"viz_unittests\": \"d2cfc42f8c71015a8064b2678111e04fc48d8417\", \"vr_common_unittests\": \"41e2695ab6cb83048e034537bc2cb9ff9aa3d5b6\", \"vr_pixeltests\": \"5caa34319f616d0af31c9100efe726f2c65b4081\", \"weblayer_browsertests\": \"a672b8c2c82732492b68f58a8998c4a7fbc9c0a4\", \"weblayer_unittests\": \"bfa7c31d60bc45c3dac16cc5303193057e49ae9f\", \"wm_unittests\": \"70a722687139ffd11ac901a70cd96a9f7620c34e\", \"wtf_unittests\": \"0aa2d1e4bbf271899c0bf703635bac78f2cd5b78\", \"zlib_unittests\": \"f9bd2c16fb23e24354aaa87b1c4b00636cfdefec\", \"zucchini_unittests\": \"f5b848abd7dc7977e44a5489ff673f44f2999a96\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"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-0-be5b\"], \"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\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ThreatDOMDetailsTest.Everything\\n\\n- TranslateAgentBrowserTest.TranslateLibNeverReady\\n\\n- SubresourceRedirectPublicImageHintsDeciderAgentTest.TestGetSubresourceURL\\n\\n- PasswordGenerationAgentTest.GenerationFallback_NoFocusedElement\\n\\n- ...5 more failure(s) (9 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612568094319997",
"status": "COMPLETED",
"status_changed_ts": "1612568094681028",
"tags": [
"build_address:luci.chromium.ci/win-asan/17189",
"builder:win-asan",
"buildset:commit/git/15d7396f9e0a2cc06a69d1f360f179ffe30dd318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/15d7396f9e0a2cc06a69d1f360f179ffe30dd318",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021523581633890304",
"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/8856093519029994320/+/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:518d5dea8240a310",
"user_agent:luci-scheduler"
],
"updated_ts": "1612571761174795",
"url": "https://ci.chromium.org/b/8856093519029994320",
"utcnow_ts": "1612571761888602"
}
}
[00:36:01.899] Invocation finished in 1h1m14.348443594s with status FAILED