[10:05:50.678] New invocation is queued and will start shortly
[10:05:51.834] Starting the invocation (attempt 1)
[10:05:51.873] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:05:51.874] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:05:51.874] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030452978958218400",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030452978958218400",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace",
"gitiles_ref:refs/heads/master"
]
}
[10:05:53.011] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1604052351978201",
"id": "8865022916930337264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604052352809466",
"tags": [
"build_address:luci.chromium.ci/win-asan/15365",
"builder:win-asan",
"buildset:commit/git/bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030452978958218400",
"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/8865022916930337264/+/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": "1604052352809593",
"url": "https://ci.chromium.org/b/8865022916930337264",
"utcnow_ts": "1604052353001556"
}
}
[10:05:53.011] Task URL: https://ci.chromium.org/b/8865022916930337264
[10:05:53.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:2:0) after 1m0s
[10:05:56.136] Received PubSub notification, asking Buildbucket for the build status
[10:05:56.238] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:53.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:2:0)
[10:06:53.087] Timer tick, asking Buildbucket for the build status
[10:06:53.224] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:53.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:4:0) after 1m0s
[10:07:53.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:4:0)
[10:07:53.273] Timer tick, asking Buildbucket for the build status
[10:07:53.444] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:53.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:5:0) after 1m0s
[10:08:53.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:5:0)
[10:08:53.499] Timer tick, asking Buildbucket for the build status
[10:08:53.841] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:53.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:6:0) after 1m0s
[10:09:53.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:6:0)
[10:09:53.897] Timer tick, asking Buildbucket for the build status
[10:09:54.009] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:54.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:7:0) after 1m0s
[10:10:54.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:7:0)
[10:10:54.054] Timer tick, asking Buildbucket for the build status
[10:10:54.512] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:54.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:8:0) after 1m0s
[10:11:54.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:8:0)
[10:11:54.608] Timer tick, asking Buildbucket for the build status
[10:11:54.763] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:54.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:9:0) after 1m0s
[10:12:54.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:9:0)
[10:12:54.986] Timer tick, asking Buildbucket for the build status
[10:12:55.089] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:55.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:10:0) after 1m0s
[10:13:56.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:10:0)
[10:13:56.980] Timer tick, asking Buildbucket for the build status
[10:13:57.199] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:57.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:11:0) after 1m0s
[10:14:59.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:11:0)
[10:14:59.761] Timer tick, asking Buildbucket for the build status
[10:14:59.841] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:59.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:12:0) after 1m0s
[10:15:59.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:12:0)
[10:15:59.870] Timer tick, asking Buildbucket for the build status
[10:16:00.209] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:00.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:13:0) after 1m0s
[10:17:00.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:13:0)
[10:17:00.723] Timer tick, asking Buildbucket for the build status
[10:17:01.049] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:01.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:14:0) after 1m0s
[10:18:01.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:14:0)
[10:18:01.601] Timer tick, asking Buildbucket for the build status
[10:18:01.714] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:01.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:15:0) after 1m0s
[10:19:01.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:15:0)
[10:19:01.692] Timer tick, asking Buildbucket for the build status
[10:19:01.827] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:01.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:16:0) after 1m0s
[10:20:01.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:16:0)
[10:20:01.862] Timer tick, asking Buildbucket for the build status
[10:20:01.945] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:01.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:17:0) after 1m0s
[10:21:01.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:17:0)
[10:21:01.965] Timer tick, asking Buildbucket for the build status
[10:21:02.276] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:02.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:18:0) after 1m0s
[10:22:02.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:18:0)
[10:22:02.363] Timer tick, asking Buildbucket for the build status
[10:22:02.489] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:02.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:19:0) after 1m0s
[10:23:02.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:19:0)
[10:23:02.547] Timer tick, asking Buildbucket for the build status
[10:23:02.657] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:02.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:20:0) after 1m0s
[10:24:02.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:20:0)
[10:24:02.694] Timer tick, asking Buildbucket for the build status
[10:24:03.348] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:03.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:21:0) after 1m0s
[10:25:03.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:21:0)
[10:25:03.387] Timer tick, asking Buildbucket for the build status
[10:25:03.963] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:03.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:22:0) after 1m0s
[10:26:04.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:22:0)
[10:26:04.109] Timer tick, asking Buildbucket for the build status
[10:26:04.243] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:04.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:23:0) after 1m0s
[10:27:04.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:23:0)
[10:27:04.312] Timer tick, asking Buildbucket for the build status
[10:27:04.515] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:04.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:24:0) after 1m0s
[10:28:04.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:24:0)
[10:28:04.565] Timer tick, asking Buildbucket for the build status
[10:28:04.700] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:04.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:25:0) after 1m0s
[10:29:04.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:25:0)
[10:29:04.803] Timer tick, asking Buildbucket for the build status
[10:29:04.965] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:04.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:26:0) after 1m0s
[10:30:04.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:26:0)
[10:30:04.989] Timer tick, asking Buildbucket for the build status
[10:30:05.145] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:05.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:27:0) after 1m0s
[10:31:05.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:27:0)
[10:31:05.331] Timer tick, asking Buildbucket for the build status
[10:31:05.562] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:05.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:28:0) after 1m0s
[10:32:05.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:28:0)
[10:32:05.697] Timer tick, asking Buildbucket for the build status
[10:32:05.846] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:05.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:29:0) after 1m0s
[10:33:05.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:29:0)
[10:33:05.894] Timer tick, asking Buildbucket for the build status
[10:33:06.142] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:06.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:30:0) after 1m0s
[10:34:06.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:30:0)
[10:34:06.215] Timer tick, asking Buildbucket for the build status
[10:34:06.303] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:06.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:31:0) after 1m0s
[10:35:06.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:31:0)
[10:35:06.447] Timer tick, asking Buildbucket for the build status
[10:35:06.802] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:06.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:32:0) after 1m0s
[10:36:06.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:32:0)
[10:36:06.784] Timer tick, asking Buildbucket for the build status
[10:36:07.085] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:07.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:33:0) after 1m0s
[10:37:07.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:33:0)
[10:37:07.231] Timer tick, asking Buildbucket for the build status
[10:37:07.781] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:07.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:34:0) after 1m0s
[10:38:07.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:34:0)
[10:38:07.819] Timer tick, asking Buildbucket for the build status
[10:38:07.947] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:07.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:35:0) after 1m0s
[10:39:08.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:35:0)
[10:39:08.087] Timer tick, asking Buildbucket for the build status
[10:39:08.219] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:08.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:36:0) after 1m0s
[10:40:08.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:36:0)
[10:40:08.319] Timer tick, asking Buildbucket for the build status
[10:40:08.531] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:08.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:37:0) after 1m0s
[10:41:08.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:37:0)
[10:41:08.521] Timer tick, asking Buildbucket for the build status
[10:41:08.673] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:08.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:38:0) after 1m0s
[10:42:08.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:38:0)
[10:42:08.786] Timer tick, asking Buildbucket for the build status
[10:42:08.874] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:08.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:39:0) after 1m0s
[10:43:08.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:39:0)
[10:43:08.924] Timer tick, asking Buildbucket for the build status
[10:43:09.045] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:09.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:40:0) after 1m0s
[10:44:09.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:40:0)
[10:44:09.108] Timer tick, asking Buildbucket for the build status
[10:44:09.245] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:09.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:41:0) after 1m0s
[10:45:09.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:41:0)
[10:45:09.274] Timer tick, asking Buildbucket for the build status
[10:45:09.361] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:09.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:42:0) after 1m0s
[10:46:09.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:42:0)
[10:46:09.408] Timer tick, asking Buildbucket for the build status
[10:46:09.583] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:09.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:43:0) after 1m0s
[10:47:09.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:43:0)
[10:47:09.698] Timer tick, asking Buildbucket for the build status
[10:47:09.831] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:09.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:44:0) after 1m0s
[10:48:09.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:44:0)
[10:48:09.860] Timer tick, asking Buildbucket for the build status
[10:48:10.661] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:10.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:45:0) after 1m0s
[10:49:10.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:45:0)
[10:49:10.763] Timer tick, asking Buildbucket for the build status
[10:49:10.851] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:10.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:46:0) after 1m0s
[10:50:10.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:46:0)
[10:50:10.873] Timer tick, asking Buildbucket for the build status
[10:50:11.063] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:11.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:47:0) after 1m0s
[10:51:11.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:47:0)
[10:51:11.158] Timer tick, asking Buildbucket for the build status
[10:51:11.297] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:11.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:48:0) after 1m0s
[10:52:11.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:48:0)
[10:52:11.337] Timer tick, asking Buildbucket for the build status
[10:52:11.419] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:11.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:49:0) after 1m0s
[10:53:11.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:49:0)
[10:53:11.465] Timer tick, asking Buildbucket for the build status
[10:53:11.759] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:11.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:50:0) after 1m0s
[10:54:11.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:50:0)
[10:54:11.789] Timer tick, asking Buildbucket for the build status
[10:54:11.902] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:11.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:51:0) after 1m0s
[10:55:11.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:51:0)
[10:55:11.980] Timer tick, asking Buildbucket for the build status
[10:55:12.118] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:12.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:52:0) after 1m0s
[10:56:12.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:52:0)
[10:56:12.126] Timer tick, asking Buildbucket for the build status
[10:56:12.671] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:12.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:53:0) after 1m0s
[10:57:12.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:53:0)
[10:57:12.786] Timer tick, asking Buildbucket for the build status
[10:57:12.885] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:12.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:54:0) after 1m0s
[10:58:12.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:54:0)
[10:58:12.880] Timer tick, asking Buildbucket for the build status
[10:58:12.961] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:12.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:55:0) after 1m0s
[10:59:13.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:55:0)
[10:59:13.017] Timer tick, asking Buildbucket for the build status
[10:59:13.118] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:13.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:56:0) after 1m0s
[11:00:13.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:56:0)
[11:00:13.154] Timer tick, asking Buildbucket for the build status
[11:00:13.966] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:13.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:57:0) after 1m0s
[11:01:14.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:57:0)
[11:01:14.081] Timer tick, asking Buildbucket for the build status
[11:01:14.342] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:14.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:58:0) after 1m0s
[11:02:14.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:58:0)
[11:02:14.435] Timer tick, asking Buildbucket for the build status
[11:02:14.689] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:14.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:59:0) after 1m0s
[11:03:14.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:59:0)
[11:03:14.797] Timer tick, asking Buildbucket for the build status
[11:03:14.982] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:14.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:60:0) after 1m0s
[11:04:15.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:60:0)
[11:04:15.062] Timer tick, asking Buildbucket for the build status
[11:04:16.403] Build 8865022916930337264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:16.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030452978958218400:61:0) after 1m0s
[11:04:21.605] Received PubSub notification, asking Buildbucket for the build status
[11:04:21.701] Build 8865022916930337264: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:04:21.701] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1604055859959066",
"created_by": "project:chromium",
"created_ts": "1604052351978201",
"failure_reason": "BUILD_FAILURE",
"id": "8865022916930337264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SSLUIWorkerFetchTest.MixedContentSettings_AllowRunningInsecureContent/1\"}, \"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\": \"bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-9-6o02\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15365, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"39b777c6f00fdb03804d7c27bb430307caca329c\", \"got_dawn_revision\": \"25eb373eede4aaa8ed069457e2c05b9c9eab9db2\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace\", \"got_revision_cp\": \"refs/heads/master@{#822585}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e6c9497255c6fde8961729a85104ab0407aeeb5e\", \"got_v8_revision\": \"ffc771df4710e998f73be031890f60ce2f69d513\", \"got_v8_revision_cp\": \"refs/heads/8.8.211@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace\", \"swarm_hashes_refs/heads/master(at){#822585}_without_patch\": {\"absl_hardening_tests\": \"8bba4b168051e1304b04a4c19763870b2116823a\", \"accessibility_unittests\": \"1aba872e1bbee508777244ff546dd5c10c96aaba\", \"angle_unittests\": \"9d1d1db985a732a5b5928631d6ab37f2cb038b60\", \"app_shell_unittests\": \"c59ec4a3802ff97d70347c72ea3ea5187d0a24fa\", \"aura_unittests\": \"a74c169fc5b1fd1477080b122c6e1f5062367fd6\", \"base_unittests\": \"c13cfbf84ef9c3915a886c556e007fcfe93ac465\", \"base_util_unittests\": \"6af16ce94d820951561321cf0ae17927cd409273\", \"blink_common_unittests\": \"a26e2c67e746fe8ea2fd021e78e8df869540323f\", \"blink_fuzzer_unittests\": \"e704d000f14eb6321b29047037085903caa147cb\", \"blink_heap_unittests\": \"e22ae7b7a2af353cb6dc2ae57351507ee8d8d8af\", \"blink_platform_unittests\": \"949b6bb8e4e5ea9d6635e78d6db8962e52d18261\", \"blink_unittests\": \"f5a93aa02bd1cf5b8b40624eac5cb640c81106d7\", \"boringssl_crypto_tests\": \"ccf6363af6f65e4341f4377206c16a12bfe1ffc9\", \"boringssl_ssl_tests\": \"66abe4981a2800453e0cc3cef6fb3a7d4fb92096\", \"browser_switcher_bho_unittests\": \"258aee0b1cc2df62fffe710257f147b49c85182b\", \"browser_tests\": \"e7ec507b3616af23a28c05f9f3aa007b4b42e6aa\", \"capture_unittests\": \"4990440d1363edfd3ae23bd1f6983e304db7a8e5\", \"cast_unittests\": \"f82aae7624f9923bee01616abebaa53dbc07f51a\", \"cc_unittests\": \"b6a7b9918d8cc2f392678d99d26bc35dd39a3b6a\", \"chrome_app_unittests\": \"40c0c03a0764fa01161c9c9240e7d8e880957474\", \"chrome_cleaner_unittests\": \"55abaf8b1d343c5fef1a0779d12d880873fb0702\", \"chrome_elf_unittests\": \"32b47e752d32d86411d8117d602d94b9f54737d1\", \"chromedriver_unittests\": \"9f5ad86d85ce4511058dca7fa669fed9e83c47bf\", \"components_browsertests\": \"f41a0394f42557a338c62739a4a584a62210b245\", \"components_unittests\": \"2a708f76a9f7452619bdd134b3af7ba2911edb21\", \"compositor_unittests\": \"4a452143b7a12403f96f95ee2280b6c952ae22b1\", \"content_browsertests\": \"af71e3c89b38cff92c06cba99a9ae6e1713f1555\", \"content_unittests\": \"1ae9087f105d172c6cd344f6d958c5fc563d3a0f\", \"courgette_unittests\": \"1bbdc5d101481f98e5a09e53dac537c952420731\", \"crashpad_tests\": \"49fa3670d98bcfd9972f8eabac6a473351dc133a\", \"cronet_tests\": \"078601da43d490777c81fbfbdf147606065c3ea1\", \"cronet_unittests\": \"d95ab75dddf94273f2b9b0fb44487e2dbddc8df9\", \"crypto_unittests\": \"873f1563a6a466d8772e63661b4917d72e452014\", \"delayloads_unittests\": \"1df7408202d4fd327654f972172c5ff3f8da34cf\", \"device_unittests\": \"4d6e3a793154c88f2c1bcdcaecee51327d6259bc\", \"display_unittests\": \"5a8ae833e1a9b80c964b629731b4ec6cc0c22335\", \"elevation_service_unittests\": \"44907e6165a0f4e689977679f11c60c021cde69f\", \"events_unittests\": \"4db64da1024221e0c67f7f8d4a0b520509ef093e\", \"extensions_browsertests\": \"4e497dacf44ab4709832577774619e07d683f792\", \"extensions_unittests\": \"cf30b8c959ffab42cce580902d2d7126c7955104\", \"filesystem_service_unittests\": \"3648dcfa329d7e61cbed2d6fb7caba3323e6622f\", \"gcm_unit_tests\": \"3aff8999e388081a52315588244dbaeee44e19d4\", \"gcp_unittests\": \"d45cb76fd965228a1450b25057618ec302471d74\", \"gfx_unittests\": \"6124f8cfc5d9dac9e60ccb0dbbfcd9790c9e43ca\", \"gin_unittests\": \"eaa420258b3f9307e16a1de2d68d7cfd90762e68\", \"google_apis_unittests\": \"1566e6187d0f464e4498d0c2bd6bbe60cec6bc25\", \"gpu_unittests\": \"0f5ffe1919120c6a020c4c922d5f2440397370b2\", \"gwp_asan_unittests\": \"e84f2cf21b80c1279abef6713c05aba675bbdb3e\", \"headless_browsertests\": \"5e298202ee2f51a3998a409a934d4b1dc8adefd5\", \"headless_unittests\": \"bdca5d453ce5dd9da61dff39fbed3f76054d0ebb\", \"install_static_unittests\": \"6a5dff8415a30b1bc3a8becbbcdac326bbd183f3\", \"installer_util_unittests\": \"9f1ac7f8f8eb1b87f0be9d4663d4ebcdab40ec1d\", \"interactive_ui_tests\": \"b46ee8f48fc800d43d5eb6462891d564cfc46b00\", \"ipc_tests\": \"fec2fb1e08373fa3c095eb13e79314bed85ecdaa\", \"jingle_unittests\": \"1ed651f97f42fa059b3c1d36f237ce91f70be9ac\", \"latency_unittests\": \"fee84c3c4bc835dbfd3efefbb81f9f76b3ba2561\", \"libjingle_xmpp_unittests\": \"391cb41be42ca08c13f287859fc7a420c95f4212\", \"liburlpattern_unittests\": \"3c698791bb914dae2dbf6478d7f26a46fca80854\", \"media_blink_unittests\": \"ad4ae89eadd7cabbd152135b64f8b663db0eb93f\", \"media_unittests\": \"b27a4fa361ab65f1f1a4bf5f4cfb4224fd19a33a\", \"message_center_unittests\": \"d78918387e22ac078882c061881477c3dbffe468\", \"midi_unittests\": \"693433baaea50f56c11d2c0720fdf2fa318e8cfa\", \"mojo_core_unittests\": \"59232443119d2863dcd222042bb319d7e71b16ed\", \"mojo_unittests\": \"b68b19fd13f66f84392ab18b3e67358c04b28e44\", \"nacl_loader_unittests\": \"ba32ea7087dde0e8981e07d4f2ded90525091cc2\", \"native_theme_unittests\": \"7d79d9c7b97809cff62a58b7d7b0aff197c16977\", \"net_unittests\": \"58f1287083a790ee8864d3cbd2d56458f3f07b92\", \"notification_helper_unittests\": \"4aada31bd39b94377e3a77a608b28afdc89b7659\", \"pdf_unittests\": \"53b14ac6df932f031d92810c259ce32c08dab9e3\", \"perfetto_unittests\": \"314776f202778fd8a9b094f3a8af163ce0e3fe75\", \"ppapi_unittests\": \"7c620e90c8c6619fc74b2edd5690593b31791cc6\", \"printing_unittests\": \"a73eb2c1ce7681eeb78b571d3137a18d7b6a1fa1\", \"remoting_unittests\": \"eef3b7fe9227de9a34b3bccefd7218498497709e\", \"sbox_integration_tests\": \"56feecad1b4a66cd8be200018a530db338d9bd55\", \"sbox_unittests\": \"5d0069ad57bf5387002263cd541906f472bcc6fa\", \"sbox_validation_tests\": \"214b3c925fc05a0e182838d2f8c572561b5571d1\", \"service_manager_unittests\": \"b1b00ad4e84e041b4a4b89e14e92746c91581207\", \"services_unittests\": \"2b34204bae02ce731ee4e1bcc6c89033cb339e1b\", \"setup_unittests\": \"38d285963a10dc8f6ad0a459c911998991b0fa2b\", \"shell_dialogs_unittests\": \"927e8ff9e386991d03d0022326fca95718e56a88\", \"skia_unittests\": \"e1133069118b0674509b4060cf69eaf702b48202\", \"snapshot_unittests\": \"4ab64623d042a39cf3853bb372b024a55dbf753d\", \"sql_unittests\": \"f25bc8e2f2581765054c61ee040fe0d06e47eb77\", \"storage_unittests\": \"7bbdff0e6603eb2547708a111e45c37675c7a5b4\", \"sync_integration_tests\": \"f09d15ff16b9b85a5fa77d92e7bf7eeaa187ada7\", \"traffic_annotation_auditor_unittests\": \"1aa4041048e27d53890f206414a08ab8632a43d6\", \"ui_base_unittests\": \"a6511e00beb26d2e7a670dcbfa27d39b5bfda81c\", \"ui_touch_selection_unittests\": \"7fe8b02bd0cd70325681783fdd09030ac73ab777\", \"unit_tests\": \"82dcea1136570bd78763e0797ded0de5ed0b5ba7\", \"updater_tests\": \"9ce6dc74b1b10be8835e77dbf8c22677a7c397cd\", \"url_unittests\": \"eb0c62a679296fdf689af8040870ccc6711a388b\", \"views_unittests\": \"9839154372032778a13724bb592765b746ae0a81\", \"viz_unittests\": \"c38e2d75d38e83e3230b1eff118ba6d8e061e91f\", \"vr_common_unittests\": \"d545b0a38e6606fa1a04636713a6befa3b7a1b6d\", \"vr_pixeltests\": \"55a920ecf8cf475439392032fd5beb19ca1a3a81\", \"weblayer_browsertests\": \"3a53a55bd4a872fda4b8e37de4feb689ef71b090\", \"weblayer_unittests\": \"1c0f7132781b8cf86cd07d22da1ec028eec3417e\", \"wm_unittests\": \"aa6b2e698d42be4aadb6a61fe6767cfa4ce6d527\", \"wtf_unittests\": \"e95a9d8fe49b202432dba7f848b01ed504da6584\", \"zlib_unittests\": \"69c0388f8a93ea3f1279c2f248cef91c477ea337\", \"zucchini_unittests\": \"bb9c60d04ac17f3f16d87c0ed95c000d5b4bbac3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-9-6o02\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SSLUIWorkerFetchTest.MixedContentSettings_AllowRunningInsecureContent/1\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604052354767694",
"status": "COMPLETED",
"status_changed_ts": "1604052355281181",
"tags": [
"build_address:luci.chromium.ci/win-asan/15365",
"builder:win-asan",
"buildset:commit/git/bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc3bfb93e5fbc31cc1df7fe57b91aeb3bccd9ace",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030452978958218400",
"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/8865022916930337264/+/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:4f91ca082cac6610",
"user_agent:luci-scheduler"
],
"updated_ts": "1604055860936335",
"url": "https://ci.chromium.org/b/8865022916930337264",
"utcnow_ts": "1604055861691815"
}
}
[11:04:21.701] Invocation finished in 58m31.040160099s with status FAILED