[03:36:46.968] New invocation is queued and will start shortly
[03:36:48.069] Starting the invocation (attempt 1)
[03:36:48.104] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:36:48.104] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:36:48.104] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029118502114808704",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8f60186caac1450314698d57b9cb859ecd748fd6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029118502114808704",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8f60186caac1450314698d57b9cb859ecd748fd6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8f60186caac1450314698d57b9cb859ecd748fd6",
"gitiles_ref:refs/heads/master"
]
}
[03:36:49.036] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605325008198534",
"id": "8863688440162192032",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8f60186caac1450314698d57b9cb859ecd748fd6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605325008776417",
"tags": [
"build_address:luci.chromium.ci/win-asan/15631",
"builder:win-asan",
"buildset:commit/git/8f60186caac1450314698d57b9cb859ecd748fd6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8f60186caac1450314698d57b9cb859ecd748fd6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029118502114808704",
"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/8863688440162192032/+/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": "1605325008776826",
"url": "https://ci.chromium.org/b/8863688440162192032",
"utcnow_ts": "1605325009028444"
}
}
[03:36:49.036] Task URL: https://ci.chromium.org/b/8863688440162192032
[03:36:49.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:2:0) after 1m0s
[03:36:53.473] Received PubSub notification, asking Buildbucket for the build status
[03:36:53.768] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:49.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:2:0)
[03:37:49.134] Timer tick, asking Buildbucket for the build status
[03:37:49.264] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:49.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:4:0) after 1m0s
[03:38:49.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:4:0)
[03:38:49.243] Timer tick, asking Buildbucket for the build status
[03:38:49.351] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:49.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:5:0) after 1m0s
[03:39:49.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:5:0)
[03:39:49.375] Timer tick, asking Buildbucket for the build status
[03:39:49.493] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:49.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:6:0) after 1m0s
[03:40:49.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:6:0)
[03:40:49.503] Timer tick, asking Buildbucket for the build status
[03:40:49.611] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:49.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:7:0) after 1m0s
[03:41:49.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:7:0)
[03:41:49.585] Timer tick, asking Buildbucket for the build status
[03:41:49.740] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:49.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:8:0) after 1m0s
[03:42:49.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:8:0)
[03:42:49.759] Timer tick, asking Buildbucket for the build status
[03:42:49.868] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:49.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:9:0) after 1m0s
[03:43:49.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:9:0)
[03:43:49.997] Timer tick, asking Buildbucket for the build status
[03:43:50.056] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:50.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:10:0) after 1m0s
[03:44:50.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:10:0)
[03:44:50.384] Timer tick, asking Buildbucket for the build status
[03:44:50.524] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:50.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:11:0) after 1m0s
[03:45:50.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:11:0)
[03:45:50.848] Timer tick, asking Buildbucket for the build status
[03:45:51.193] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:51.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:12:0) after 1m0s
[03:46:51.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:12:0)
[03:46:51.351] Timer tick, asking Buildbucket for the build status
[03:46:51.487] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:51.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:13:0) after 1m0s
[03:47:51.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:13:0)
[03:47:51.515] Timer tick, asking Buildbucket for the build status
[03:47:51.612] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:51.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:14:0) after 1m0s
[03:48:51.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:14:0)
[03:48:51.693] Timer tick, asking Buildbucket for the build status
[03:48:51.913] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:51.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:15:0) after 1m0s
[03:49:51.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:15:0)
[03:49:51.955] Timer tick, asking Buildbucket for the build status
[03:49:52.140] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:52.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:16:0) after 1m0s
[03:50:52.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:16:0)
[03:50:52.171] Timer tick, asking Buildbucket for the build status
[03:50:52.858] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:52.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:17:0) after 1m0s
[03:51:52.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:17:0)
[03:51:52.906] Timer tick, asking Buildbucket for the build status
[03:51:53.039] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:53.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:18:0) after 1m0s
[03:52:53.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:18:0)
[03:52:53.074] Timer tick, asking Buildbucket for the build status
[03:52:53.202] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:53.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:19:0) after 1m0s
[03:53:53.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:19:0)
[03:53:53.295] Timer tick, asking Buildbucket for the build status
[03:53:53.395] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:53.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:20:0) after 1m0s
[03:54:53.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:20:0)
[03:54:53.441] Timer tick, asking Buildbucket for the build status
[03:54:53.549] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:53.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:21:0) after 1m0s
[03:55:53.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:21:0)
[03:55:53.727] Timer tick, asking Buildbucket for the build status
[03:55:53.866] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:53.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:22:0) after 1m0s
[03:56:53.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:22:0)
[03:56:53.851] Timer tick, asking Buildbucket for the build status
[03:56:53.931] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:53.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:23:0) after 1m0s
[03:57:54.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:23:0)
[03:57:54.039] Timer tick, asking Buildbucket for the build status
[03:57:54.169] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:54.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:24:0) after 1m0s
[03:58:54.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:24:0)
[03:58:54.312] Timer tick, asking Buildbucket for the build status
[03:58:54.516] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:54.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:25:0) after 1m0s
[03:59:54.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:25:0)
[03:59:54.650] Timer tick, asking Buildbucket for the build status
[03:59:54.758] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:26:0) after 1m0s
[04:00:54.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:26:0)
[04:00:54.794] Timer tick, asking Buildbucket for the build status
[04:00:54.900] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:54.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:27:0) after 1m0s
[04:01:54.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:27:0)
[04:01:54.985] Timer tick, asking Buildbucket for the build status
[04:01:55.778] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:55.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:28:0) after 1m0s
[04:02:55.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:28:0)
[04:02:55.957] Timer tick, asking Buildbucket for the build status
[04:02:56.048] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:56.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:29:0) after 1m0s
[04:03:56.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:29:0)
[04:03:56.147] Timer tick, asking Buildbucket for the build status
[04:03:56.276] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:56.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:30:0) after 1m0s
[04:04:56.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:30:0)
[04:04:56.320] Timer tick, asking Buildbucket for the build status
[04:04:56.664] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:56.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:31:0) after 1m0s
[04:05:56.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:31:0)
[04:05:56.799] Timer tick, asking Buildbucket for the build status
[04:05:56.908] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:56.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:32:0) after 1m0s
[04:06:56.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:32:0)
[04:06:56.910] Timer tick, asking Buildbucket for the build status
[04:06:58.139] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:58.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:33:0) after 1m0s
[04:07:58.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:33:0)
[04:07:58.196] Timer tick, asking Buildbucket for the build status
[04:07:58.363] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:58.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:34:0) after 1m0s
[04:08:58.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:34:0)
[04:08:58.421] Timer tick, asking Buildbucket for the build status
[04:08:58.581] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:58.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:35:0) after 1m0s
[04:09:58.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:35:0)
[04:09:58.595] Timer tick, asking Buildbucket for the build status
[04:09:58.765] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:58.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:36:0) after 1m0s
[04:10:58.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:36:0)
[04:10:58.787] Timer tick, asking Buildbucket for the build status
[04:10:58.895] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:58.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:37:0) after 1m0s
[04:11:58.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:37:0)
[04:11:58.922] Timer tick, asking Buildbucket for the build status
[04:11:59.083] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:59.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:38:0) after 1m0s
[04:12:59.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:38:0)
[04:12:59.104] Timer tick, asking Buildbucket for the build status
[04:12:59.245] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:59.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:39:0) after 1m0s
[04:13:59.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:39:0)
[04:13:59.220] Timer tick, asking Buildbucket for the build status
[04:13:59.341] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:59.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:40:0) after 1m0s
[04:14:59.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:40:0)
[04:14:59.345] Timer tick, asking Buildbucket for the build status
[04:14:59.483] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:59.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:41:0) after 1m0s
[04:15:59.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:41:0)
[04:15:59.532] Timer tick, asking Buildbucket for the build status
[04:15:59.653] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:59.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:42:0) after 1m0s
[04:16:59.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:42:0)
[04:16:59.658] Timer tick, asking Buildbucket for the build status
[04:16:59.780] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:59.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:43:0) after 1m0s
[04:17:59.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:43:0)
[04:17:59.849] Timer tick, asking Buildbucket for the build status
[04:17:59.962] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:59.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:44:0) after 1m0s
[04:19:00.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:44:0)
[04:19:00.101] Timer tick, asking Buildbucket for the build status
[04:19:00.561] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:00.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:45:0) after 1m0s
[04:20:02.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:45:0)
[04:20:02.333] Timer tick, asking Buildbucket for the build status
[04:20:02.425] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:02.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:46:0) after 1m0s
[04:21:02.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:46:0)
[04:21:02.572] Timer tick, asking Buildbucket for the build status
[04:21:02.900] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:02.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:47:0) after 1m0s
[04:22:02.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:47:0)
[04:22:02.998] Timer tick, asking Buildbucket for the build status
[04:22:03.469] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:03.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:48:0) after 1m0s
[04:23:03.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:48:0)
[04:23:03.515] Timer tick, asking Buildbucket for the build status
[04:23:03.588] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:03.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:49:0) after 1m0s
[04:24:03.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:49:0)
[04:24:03.660] Timer tick, asking Buildbucket for the build status
[04:24:04.297] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:04.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:50:0) after 1m0s
[04:25:04.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:50:0)
[04:25:04.323] Timer tick, asking Buildbucket for the build status
[04:25:04.522] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:04.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:51:0) after 1m0s
[04:26:04.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:51:0)
[04:26:04.544] Timer tick, asking Buildbucket for the build status
[04:26:04.618] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:04.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:52:0) after 1m0s
[04:27:04.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:52:0)
[04:27:04.701] Timer tick, asking Buildbucket for the build status
[04:27:05.235] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:05.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:53:0) after 1m0s
[04:28:05.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:53:0)
[04:28:05.326] Timer tick, asking Buildbucket for the build status
[04:28:05.391] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:05.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:54:0) after 1m0s
[04:29:05.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:54:0)
[04:29:05.402] Timer tick, asking Buildbucket for the build status
[04:29:05.487] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:05.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:55:0) after 1m0s
[04:30:05.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:55:0)
[04:30:05.519] Timer tick, asking Buildbucket for the build status
[04:30:05.604] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:05.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:56:0) after 1m0s
[04:31:05.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:56:0)
[04:31:05.765] Timer tick, asking Buildbucket for the build status
[04:31:05.841] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:05.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:57:0) after 1m0s
[04:32:05.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:57:0)
[04:32:05.896] Timer tick, asking Buildbucket for the build status
[04:32:06.063] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:06.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:58:0) after 1m0s
[04:33:06.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:58:0)
[04:33:06.117] Timer tick, asking Buildbucket for the build status
[04:33:06.727] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:06.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:59:0) after 1m0s
[04:34:06.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:59:0)
[04:34:06.916] Timer tick, asking Buildbucket for the build status
[04:34:06.999] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:06.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:60:0) after 1m0s
[04:35:07.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:60:0)
[04:35:07.076] Timer tick, asking Buildbucket for the build status
[04:35:08.085] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:08.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:61:0) after 1m0s
[04:36:08.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:61:0)
[04:36:08.123] Timer tick, asking Buildbucket for the build status
[04:36:08.778] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:08.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:62:0) after 1m0s
[04:37:08.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:62:0)
[04:37:08.893] Timer tick, asking Buildbucket for the build status
[04:37:09.024] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:09.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:63:0) after 1m0s
[04:38:09.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:63:0)
[04:38:09.057] Timer tick, asking Buildbucket for the build status
[04:38:09.133] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:09.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:64:0) after 1m0s
[04:39:09.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:64:0)
[04:39:09.182] Timer tick, asking Buildbucket for the build status
[04:39:09.332] Build 8863688440162192032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:09.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029118502114808704:65:0) after 1m0s
[04:39:55.728] Received PubSub notification, asking Buildbucket for the build status
[04:39:55.795] Build 8863688440162192032: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:39:55.795] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605328794893280",
"created_by": "project:chromium",
"created_ts": "1605325008198534",
"failure_reason": "BUILD_FAILURE",
"id": "8863688440162192032",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8f60186caac1450314698d57b9cb859ecd748fd6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- LoadTimingBrowserTest.HTTPS\"}, \"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\": \"8f60186caac1450314698d57b9cb859ecd748fd6\", \"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-5-d467\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15631, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"f46e6201b40bdee3e0b79d5d97660fc302fd76f5\", \"got_dawn_revision\": \"da1f66c8cee18677bf921181613732a9378e9864\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"8f60186caac1450314698d57b9cb859ecd748fd6\", \"got_revision_cp\": \"refs/heads/master@{#827558}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"ed95a75961bfbb4497d367b24f2d5d4f31affa40\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32608}\", \"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\": \"8f60186caac1450314698d57b9cb859ecd748fd6\", \"swarm_hashes_refs/heads/master(at){#827558}_without_patch\": {\"absl_hardening_tests\": \"b6365e47f86737717baaadf78ceed65b7ae79a89\", \"accessibility_unittests\": \"2b029e92c65256495aa31c492471497ce167592a\", \"angle_unittests\": \"46fce55f4924a637b7b42d6dc19ceb85f118b55d\", \"app_shell_unittests\": \"1a8bcab93d42999d1a394022c83d9f818b054abc\", \"aura_unittests\": \"435ea84385a5fb60ea1caae33c2a0b5020893f81\", \"base_unittests\": \"4f8489dacb633cab098f890a5a5237b5c8656b69\", \"base_util_unittests\": \"18d3cbad5022ab69abe4d84137944955dd773f72\", \"blink_common_unittests\": \"d1d91553e301306f5691eb9f5354896f73c6c4a3\", \"blink_fuzzer_unittests\": \"44622b426cbea94cb541a9fa1b6c9509f4b2f994\", \"blink_heap_unittests\": \"7fb6cc6643a0c5495b178c2c43c4f9fac4abda26\", \"blink_platform_unittests\": \"24f250a4d8ec96ab35751a8de0dd66ad9df7892f\", \"blink_unittests\": \"1298f66f008437bdbb8daa8dd2d057bd9c2a3046\", \"boringssl_crypto_tests\": \"3ada1a4df8bd21930fe9482bfefe560d99006aa5\", \"boringssl_ssl_tests\": \"8c8a04469ba0f6560f5bb3a7346cfa1e9a50732e\", \"browser_switcher_bho_unittests\": \"410502981841e7fc832d4a50b555f33f97f7bc18\", \"browser_tests\": \"9ae9172ddbe134d33d0a296f5a14e86164e85ab2\", \"capture_unittests\": \"e321af3aab80c0067a5f160c11f62d6e89d465f3\", \"cast_unittests\": \"47645eecdf33b5fa21f84170e96cbe140f72a177\", \"cc_unittests\": \"d66e674b4ed4aa8463187c17689d3d6d6ecb7d18\", \"chrome_app_unittests\": \"2498303ea3840255a143548cc6307b0dbdd7167a\", \"chrome_cleaner_unittests\": \"c55be03831f3aa12c7cf92eee841769e7d09d63a\", \"chrome_elf_unittests\": \"2a08847b7d8827336a37e5601ab194646bb56d75\", \"chromedriver_unittests\": \"a7b374d240b8c1a9a0b8c67d7b75f587f1d0fe4f\", \"components_browsertests\": \"dc8114d6629826d3d422cba6f048c839cd64513c\", \"components_unittests\": \"cc760d2274a2c94077c260546bc5dae52050ca3e\", \"compositor_unittests\": \"d171699697164fabe9dffc9bbbfc340ef8490497\", \"content_browsertests\": \"b0862c2fef9667b9b9730078ed305f18a9546ca6\", \"content_unittests\": \"2ecf27c168303e8cf9c921d97d59766d5cacfdd0\", \"courgette_unittests\": \"dfb2fcaad187f2a65b4fb8266624b59f15ee2433\", \"crashpad_tests\": \"fa72afc24e80e9f30c8ca90bc0e114fccab4dcb9\", \"cronet_tests\": \"b998f96dbe626283d9241a7b9de3ae675cc53a63\", \"cronet_unittests\": \"e69712ef10086d791398e9bcd7c1f53b53428839\", \"crypto_unittests\": \"7aff9d3bbcca7bc3237d5a478747347406d6de9c\", \"delayloads_unittests\": \"f83ee69c3c5d571411a4f8c14a69cda26d8d2ebc\", \"device_unittests\": \"6e7820d50c92fc55a4d28d01910bb7c82fa7b6d9\", \"display_unittests\": \"18ada29b42c5b8946ae3b2fcfc2929cfa8984592\", \"elevation_service_unittests\": \"ffad5204adb4e6c4487202f929bc443f371ecf5e\", \"events_unittests\": \"ae78dc4964525783cc619527190b1f31dd1f75dd\", \"extensions_browsertests\": \"66a912ff073e29a4f7dff4341b2d55ed5d6a0e36\", \"extensions_unittests\": \"69afa794b012b6a6dbe1211c5baa8dd6bd523b99\", \"filesystem_service_unittests\": \"aa4d9c13f25cf848d98de671ebe37ea7a19bbd83\", \"gcm_unit_tests\": \"c209074967b020f5cafc56ccbcf786f56de3c68e\", \"gcp_unittests\": \"f25ee9ad91237ab790df2b9ca5098faf73367e2b\", \"gfx_unittests\": \"9ae92d0ea0ec046478ae7dbdffec35cfcf29cb18\", \"gin_unittests\": \"0e9d670cc6860555f4e003c17aa8eb87b68925da\", \"google_apis_unittests\": \"602cc355458068137f9fd3f99aa671defad5e9a4\", \"gpu_unittests\": \"1c2a3bf18df3fa922c7fd70c04efb62f1a5947c5\", \"gwp_asan_unittests\": \"495e4f3b7cd37e072cce18c599930314b20be321\", \"headless_browsertests\": \"c6a77c56f8f744a2cb4ab609bced16720c11dd13\", \"headless_unittests\": \"7d10f33cbb53f9442f831704a406ffec0921a49d\", \"install_static_unittests\": \"a4fefcea2d5294c53ac86e4e4ed1a3a1a14429b1\", \"installer_util_unittests\": \"5556045cd609a042ad9029f4ccc06e141d7dc1cc\", \"interactive_ui_tests\": \"e45b336b1dfbcef7217c9b44cf6592c4caafc084\", \"ipc_tests\": \"ea0d4a935927889472730706c9432aadedd91afe\", \"jingle_unittests\": \"75983996565d0122bf5d32949b55c14fd45bd8e1\", \"latency_unittests\": \"58bbcb9a5f84311a348844333663620af3f82650\", \"libjingle_xmpp_unittests\": \"384a8847d790c98a5750180ae7c4bf0827493c06\", \"liburlpattern_unittests\": \"ce638f93e9708a90ea599873798a4ed2dfc5cc05\", \"media_blink_unittests\": \"df286bd1d83c7bf28a6b48aff207761ff2d30ed8\", \"media_unittests\": \"64d7fe7247a24bbd3dbabf506af569b69028d1dd\", \"message_center_unittests\": \"842f0582c990dde9b252f7de5a0724db0afba2c6\", \"midi_unittests\": \"cb38079e09bc94bdd81ad20cf4e83d60734a004e\", \"mojo_core_unittests\": \"44cea81f20319847376f48e3d5f7f41c01a7d461\", \"mojo_unittests\": \"9a75b5ffa93d153baa9767f2c4f87db333da7d6f\", \"nacl_loader_unittests\": \"2ed51a525833d615ccdd027b1c7e463749c6edd3\", \"native_theme_unittests\": \"9645ba4cf8cf3f982228425af9bf1e8c892a3991\", \"net_unittests\": \"3358412f3561374675f96d408c83eebfd64ba09a\", \"notification_helper_unittests\": \"2f6a535dcc92b5719d7e1840b04aacdadcd47f20\", \"pdf_unittests\": \"5e11c25539ac876ad3675710fe6753d4f782a00c\", \"perfetto_unittests\": \"c81b63a77b2acdab974e6db1cd3b340b6fcefc6b\", \"ppapi_unittests\": \"db355e4e8d68c03030effbb94a9582401fa3caa4\", \"printing_unittests\": \"9e73c4d30627a5584562c4c77a94705f89227a94\", \"remoting_unittests\": \"51e4f1b34c44850629d953b69f3e6010a45ba3bf\", \"sbox_integration_tests\": \"5c55f52dcfcaaf12a20b11cdb4a1e6bf3d135a47\", \"sbox_unittests\": \"d4a96109f90b33c49639e63cf070853944d0dc3a\", \"sbox_validation_tests\": \"5e2ee316830aa29bb96a915ef888f9ba8ecfc9a6\", \"service_manager_unittests\": \"13f17e9c4ea7a8f7fe0c159d8ca4634f6fd06125\", \"services_unittests\": \"ce94b52d4bb6c5650579ddbae25ca39345ece7af\", \"setup_unittests\": \"b40ee49a80e52ebfa326c8240f889c4cf7f4216b\", \"shell_dialogs_unittests\": \"092096903ed411c19ca25220c3ac966a42801230\", \"skia_unittests\": \"22f9d4ce96b479b23261b451bfcf0a0e0b5b4c82\", \"snapshot_unittests\": \"20433586a06e5828e190a28944b3a32c264a0414\", \"sql_unittests\": \"ba26d85ef3eaa401f1fa9076e499b069846a6044\", \"storage_unittests\": \"d30f0f8b01037b0f79a01691efb8cedcd208cc7e\", \"sync_integration_tests\": \"6f702a630bf9ebf78488d8f03b7faaefcd2b054d\", \"traffic_annotation_auditor_unittests\": \"3340abad57123c44a4c4c4cc4b2f530e8f1be9cb\", \"ui_base_unittests\": \"4dd2d1e9e12b8323acc8c314d013ef79238d48d7\", \"ui_touch_selection_unittests\": \"c46002229bb615a17946b511a8c8a37bcb5db967\", \"unit_tests\": \"e559275c24b3862bbe207cd0381820c8cdbb539f\", \"updater_tests\": \"24c7a63566770a52bf4925194528a8e9d20dd6ca\", \"url_unittests\": \"9980ddf0ecb612a04957520c5288b7974a824628\", \"views_unittests\": \"d12d6788d7b838a90c44ce1ba391a54ca9f32e09\", \"viz_unittests\": \"a238d721ee5b3986e274c5e688d1a946692824fa\", \"vr_common_unittests\": \"3fda564e36f8c9dcc89ff254d11bf21880528e68\", \"vr_pixeltests\": \"17a73c93e2128ce39a3922d8d44e07350e807245\", \"weblayer_browsertests\": \"3edd632fbb843ba11f10bbc66e4647d56ef63d3f\", \"weblayer_unittests\": \"450ceabda9fa99e816b433bd9d277bbbb6ca0713\", \"wm_unittests\": \"3c2127810d717dbf74b39e686aba947ebc73687d\", \"wtf_unittests\": \"2a90e49c8d483d9f55de72449153d3594e30e796\", \"zlib_unittests\": \"bb2c922d136da147a84d44b7efb5580be8a7bcbf\", \"zucchini_unittests\": \"4f1e98e90d58b3af45fb66c2902174190ac45bae\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-5-d467\"], \"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\": [\"5626-39642e9\"], \"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- LoadTimingBrowserTest.HTTPS\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605325012157964",
"status": "COMPLETED",
"status_changed_ts": "1605325012481571",
"tags": [
"build_address:luci.chromium.ci/win-asan/15631",
"builder:win-asan",
"buildset:commit/git/8f60186caac1450314698d57b9cb859ecd748fd6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8f60186caac1450314698d57b9cb859ecd748fd6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029118502114808704",
"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/8863688440162192032/+/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:4fdda53996477410",
"user_agent:luci-scheduler"
],
"updated_ts": "1605328795317879",
"url": "https://ci.chromium.org/b/8863688440162192032",
"utcnow_ts": "1605328795787502"
}
}
[04:39:55.795] Invocation finished in 1h3m8.843500576s with status FAILED