[16:09:12.573] New invocation is queued and will start shortly
[16:09:13.717] Starting the invocation (attempt 1)
[16:09:13.768] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:09:13.768] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:09:13.768] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025537881829166896",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6a822e69330f36234fa18103bb849925502d9273\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025537881829166896",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6a822e69330f36234fa18103bb849925502d9273",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a822e69330f36234fa18103bb849925502d9273",
"gitiles_ref:refs/heads/master"
]
}
[16:09:14.487] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608739753900504",
"id": "8860107819772908400",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6a822e69330f36234fa18103bb849925502d9273\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608739754318970",
"tags": [
"build_address:luci.chromium.ci/win-asan/16411",
"builder:win-asan",
"buildset:commit/git/6a822e69330f36234fa18103bb849925502d9273",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a822e69330f36234fa18103bb849925502d9273",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025537881829166896",
"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/8860107819772908400/+/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": "1608739754319112",
"url": "https://ci.chromium.org/b/8860107819772908400",
"utcnow_ts": "1608739754479003"
}
}
[16:09:14.487] Task URL: https://ci.chromium.org/b/8860107819772908400
[16:09:14.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:2:0) after 1m0s
[16:09:17.670] Received PubSub notification, asking Buildbucket for the build status
[16:09:17.737] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:14.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:2:0)
[16:10:14.499] Timer tick, asking Buildbucket for the build status
[16:10:14.685] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:14.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:4:0) after 1m0s
[16:11:14.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:4:0)
[16:11:14.738] Timer tick, asking Buildbucket for the build status
[16:11:15.036] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:15.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:5:0) after 1m0s
[16:12:15.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:5:0)
[16:12:15.068] Timer tick, asking Buildbucket for the build status
[16:12:15.182] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:15.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:6:0) after 1m0s
[16:13:15.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:6:0)
[16:13:15.214] Timer tick, asking Buildbucket for the build status
[16:13:15.331] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:15.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:7:0) after 1m0s
[16:14:15.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:7:0)
[16:14:15.352] Timer tick, asking Buildbucket for the build status
[16:14:15.434] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:15.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:8:0) after 1m0s
[16:15:15.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:8:0)
[16:15:15.455] Timer tick, asking Buildbucket for the build status
[16:15:15.567] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:15.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:9:0) after 1m0s
[16:16:15.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:9:0)
[16:16:15.578] Timer tick, asking Buildbucket for the build status
[16:16:15.654] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:15.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:10:0) after 1m0s
[16:17:15.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:10:0)
[16:17:15.676] Timer tick, asking Buildbucket for the build status
[16:17:15.739] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:15.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:11:0) after 1m0s
[16:18:15.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:11:0)
[16:18:15.838] Timer tick, asking Buildbucket for the build status
[16:18:15.994] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:15.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:12:0) after 1m0s
[16:19:16.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:12:0)
[16:19:16.012] Timer tick, asking Buildbucket for the build status
[16:19:16.119] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:16.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:13:0) after 1m0s
[16:20:16.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:13:0)
[16:20:16.133] Timer tick, asking Buildbucket for the build status
[16:20:16.237] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:16.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:14:0) after 1m0s
[16:21:16.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:14:0)
[16:21:16.336] Timer tick, asking Buildbucket for the build status
[16:21:16.497] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:16.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:15:0) after 1m0s
[16:22:16.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:15:0)
[16:22:16.515] Timer tick, asking Buildbucket for the build status
[16:22:16.639] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:16.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:16:0) after 1m0s
[16:23:16.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:16:0)
[16:23:16.736] Timer tick, asking Buildbucket for the build status
[16:23:17.040] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:17.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:17:0) after 1m0s
[16:24:17.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:17:0)
[16:24:17.068] Timer tick, asking Buildbucket for the build status
[16:24:17.511] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:17.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:18:0) after 1m0s
[16:25:17.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:18:0)
[16:25:17.577] Timer tick, asking Buildbucket for the build status
[16:25:17.711] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:17.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:19:0) after 1m0s
[16:26:17.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:19:0)
[16:26:17.762] Timer tick, asking Buildbucket for the build status
[16:26:17.964] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:17.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:20:0) after 1m0s
[16:27:17.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:20:0)
[16:27:17.985] Timer tick, asking Buildbucket for the build status
[16:27:18.116] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:18.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:21:0) after 1m0s
[16:28:18.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:21:0)
[16:28:18.136] Timer tick, asking Buildbucket for the build status
[16:28:18.249] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:18.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:22:0) after 1m0s
[16:29:18.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:22:0)
[16:29:18.313] Timer tick, asking Buildbucket for the build status
[16:29:18.417] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:18.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:23:0) after 1m0s
[16:30:18.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:23:0)
[16:30:18.474] Timer tick, asking Buildbucket for the build status
[16:30:20.614] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:20.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:24:0) after 1m0s
[16:31:20.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:24:0)
[16:31:20.656] Timer tick, asking Buildbucket for the build status
[16:31:20.769] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:20.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:25:0) after 1m0s
[16:32:20.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:25:0)
[16:32:20.786] Timer tick, asking Buildbucket for the build status
[16:32:20.846] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:20.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:26:0) after 1m0s
[16:33:20.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:26:0)
[16:33:20.880] Timer tick, asking Buildbucket for the build status
[16:33:20.968] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:20.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:27:0) after 1m0s
[16:34:21.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:27:0)
[16:34:21.379] Timer tick, asking Buildbucket for the build status
[16:34:21.551] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:21.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:28:0) after 1m0s
[16:35:21.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:28:0)
[16:35:21.783] Timer tick, asking Buildbucket for the build status
[16:35:21.872] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:21.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:29:0) after 1m0s
[16:36:22.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:29:0)
[16:36:22.059] Timer tick, asking Buildbucket for the build status
[16:36:22.310] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:22.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:30:0) after 1m0s
[16:37:22.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:30:0)
[16:37:22.358] Timer tick, asking Buildbucket for the build status
[16:37:22.935] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:22.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:31:0) after 1m0s
[16:38:22.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:31:0)
[16:38:22.998] Timer tick, asking Buildbucket for the build status
[16:38:23.088] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:23.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:32:0) after 1m0s
[16:39:23.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:32:0)
[16:39:23.123] Timer tick, asking Buildbucket for the build status
[16:39:23.240] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:23.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:33:0) after 1m0s
[16:40:23.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:33:0)
[16:40:23.264] Timer tick, asking Buildbucket for the build status
[16:40:23.431] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:23.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:34:0) after 1m0s
[16:41:23.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:34:0)
[16:41:23.608] Timer tick, asking Buildbucket for the build status
[16:41:23.686] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:23.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:35:0) after 1m0s
[16:42:23.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:35:0)
[16:42:23.724] Timer tick, asking Buildbucket for the build status
[16:42:23.885] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:23.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:36:0) after 1m0s
[16:43:23.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:36:0)
[16:43:23.907] Timer tick, asking Buildbucket for the build status
[16:43:24.026] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:24.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:37:0) after 1m0s
[16:44:24.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:37:0)
[16:44:24.035] Timer tick, asking Buildbucket for the build status
[16:44:24.151] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:24.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:38:0) after 1m0s
[16:45:24.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:38:0)
[16:45:24.222] Timer tick, asking Buildbucket for the build status
[16:45:24.509] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:24.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:39:0) after 1m0s
[16:46:24.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:39:0)
[16:46:24.590] Timer tick, asking Buildbucket for the build status
[16:46:24.718] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:24.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:40:0) after 1m0s
[16:47:24.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:40:0)
[16:47:24.814] Timer tick, asking Buildbucket for the build status
[16:47:24.941] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:24.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:41:0) after 1m0s
[16:48:24.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:41:0)
[16:48:24.965] Timer tick, asking Buildbucket for the build status
[16:48:25.125] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:25.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:42:0) after 1m0s
[16:49:25.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:42:0)
[16:49:25.149] Timer tick, asking Buildbucket for the build status
[16:49:25.296] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:25.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:43:0) after 1m0s
[16:50:25.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:43:0)
[16:50:25.326] Timer tick, asking Buildbucket for the build status
[16:50:25.434] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:25.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:44:0) after 1m0s
[16:51:25.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:44:0)
[16:51:25.462] Timer tick, asking Buildbucket for the build status
[16:51:25.544] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:25.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:45:0) after 1m0s
[16:52:25.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:45:0)
[16:52:25.567] Timer tick, asking Buildbucket for the build status
[16:52:25.698] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:25.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:46:0) after 1m0s
[16:53:25.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:46:0)
[16:53:25.827] Timer tick, asking Buildbucket for the build status
[16:53:26.330] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:26.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:47:0) after 1m0s
[16:54:26.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:47:0)
[16:54:26.359] Timer tick, asking Buildbucket for the build status
[16:54:26.538] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:26.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:48:0) after 1m0s
[16:55:26.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:48:0)
[16:55:26.592] Timer tick, asking Buildbucket for the build status
[16:55:26.846] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:26.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:49:0) after 1m0s
[16:56:26.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:49:0)
[16:56:26.968] Timer tick, asking Buildbucket for the build status
[16:56:27.057] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:27.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:50:0) after 1m0s
[16:57:27.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:50:0)
[16:57:27.074] Timer tick, asking Buildbucket for the build status
[16:57:27.248] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:27.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:51:0) after 1m0s
[16:58:27.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:51:0)
[16:58:27.326] Timer tick, asking Buildbucket for the build status
[16:58:27.401] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:27.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:52:0) after 1m0s
[16:59:27.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:52:0)
[16:59:27.526] Timer tick, asking Buildbucket for the build status
[16:59:27.624] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:27.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:53:0) after 1m0s
[17:00:27.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:53:0)
[17:00:27.784] Timer tick, asking Buildbucket for the build status
[17:00:27.866] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:27.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:54:0) after 1m0s
[17:01:27.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:54:0)
[17:01:27.968] Timer tick, asking Buildbucket for the build status
[17:01:28.196] Build 8860107819772908400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:28.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025537881829166896:55:0) after 1m0s
[17:01:32.302] Received PubSub notification, asking Buildbucket for the build status
[17:01:32.378] Build 8860107819772908400: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:01:32.378] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608742891244712",
"created_by": "project:chromium",
"created_ts": "1608739753900504",
"failure_reason": "BUILD_FAILURE",
"id": "8860107819772908400",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6a822e69330f36234fa18103bb849925502d9273\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}, \"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\": \"6a822e69330f36234fa18103bb849925502d9273\", \"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-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16411, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"d447829c19fd6275b94676aab20e303c224fe126\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"6a822e69330f36234fa18103bb849925502d9273\", \"got_revision_cp\": \"refs/heads/master@{#839119}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"711cf5ad4a12abe0f6b50058cb2248d7fb80374e\", \"got_v8_revision_cp\": \"refs/heads/8.9.187@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"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\": \"6a822e69330f36234fa18103bb849925502d9273\", \"swarm_hashes_refs/heads/master(at){#839119}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"9c25cf576cee4b5d6304917b3460cd841b85afa9\", \"angle_unittests\": \"571027a9beb72b2b4a56911580ec4a89ba604851\", \"app_shell_unittests\": \"41165655f9b679c294fd3ed2ec8eeb382e4f581b\", \"aura_unittests\": \"e9948fb8414e74da39d12491f2ef20df6b62140d\", \"base_unittests\": \"ff09bb7d5f2b63139b7ee1626375a378dd7e50f0\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"eb21fdbc2caef49497b3bdcfccae2bd0f82f4997\", \"blink_fuzzer_unittests\": \"fed2e3c1651c315311855859dfc61d5022928b7a\", \"blink_heap_unittests\": \"a917099eb97b7dc581ee0860cc40b99881d07626\", \"blink_platform_unittests\": \"60c483a5b52a2c6b327fe99cd639569dc1c00d67\", \"blink_unittests\": \"53970fee71135e3b3cf4e91f7ad2f84af81bf03d\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"e4493f91d43e88bd7424e7259e49378ace5b42fb\", \"capture_unittests\": \"6baf52caca9e23026e2c5e5251f5d7eda81f48b9\", \"cast_unittests\": \"a2aa57a1cb67e4d9f04bc1edd162f1cbda0f14c1\", \"cc_unittests\": \"f4c67f795880834a51a41999c3cfdab113a351a8\", \"chrome_app_unittests\": \"63eb402ef7aeb21c8602c230a0550a0fb09f5447\", \"chrome_cleaner_unittests\": \"ef985271248808e928b9d5050c1cecafc6295542\", \"chrome_elf_unittests\": \"3fc0041f088efa82432a8a1799c38707d431bb31\", \"chromedriver_unittests\": \"77d9167f7e573d75aca2e23b8ad4ba468f9d893b\", \"components_browsertests\": \"f2edf9462bee1d3063d425c7f5f727e3391715af\", \"components_unittests\": \"f3b43c0b6b1b9b744a429ee51bf9a800e4a3a1bf\", \"compositor_unittests\": \"73cd2f8910107fef080505f46dfc3a0d769b432f\", \"content_browsertests\": \"beccf41cbc55dae6bbb8f58d1f6e1377c463b403\", \"content_unittests\": \"3a44e2fff529f53afb1c30f69b9724dfe9ff135b\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"7a9b09a8aa0c185fa6297f2c11aa679520451d21\", \"cronet_unittests\": \"32ac780691e63b92d2c3b6e10968b1c1237f25e5\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"7c776f6b7cb95511a2848725e958f47f01af6fb0\", \"device_unittests\": \"e24b10062dc061a21c0a6a6b23d9debf64f6bca9\", \"display_unittests\": \"0d5e3123755d93fd59b42b3011a243f5a5b9dbbc\", \"elevation_service_unittests\": \"767eaadce0d30c7de462465fd7c292a41f0bda08\", \"events_unittests\": \"d62bd5e9f38333e493d0a4c6410ef141c59c2b96\", \"extensions_browsertests\": \"0361c91373f1a713e8dac3bc449e89a7567a9661\", \"extensions_unittests\": \"6f367ce046c4b06836e03b9a3577fe20c3af8ce7\", \"filesystem_service_unittests\": \"d092594ef413e064bdeff37e62044f5b2a101bd6\", \"gcm_unit_tests\": \"254496cf3db352ff3bc68859a34c5637d34d3a49\", \"gcp_unittests\": \"dcb5e29d2dc39334de9567c0709fddbe08313223\", \"gfx_unittests\": \"e858888990f8ec196b244320534b41269673db8e\", \"gin_unittests\": \"be87c5dc4264fc574b938f678825984e75e90b79\", \"google_apis_unittests\": \"c2fb0a97fb338ae8fb65adc2fe40b6f3ae43b28a\", \"gpu_unittests\": \"e343ad00e4d9d3067bd5bc7dd4b21b1c0c2aa8c4\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"0b4325ca78a19ccaeef8992f8bca9e0e48539980\", \"headless_unittests\": \"6a63b6da5808999ca6d0918f0e22cea71891fbf4\", \"install_static_unittests\": \"280548038f19c95a74f1ed98de8a493fa3a63f01\", \"installer_util_unittests\": \"aaa7e8d77107da46607521a510c6111679ec8faf\", \"interactive_ui_tests\": \"12c46620103576afec269d053e912871ab240d3c\", \"ipc_tests\": \"4a04e979b7bf4609cd781bac2f50263972d457d8\", \"jingle_unittests\": \"0f727a8e66d9bacf7747bb6206f4851c0966a9da\", \"latency_unittests\": \"8151a18aa9bfa476451f7310237886623c992928\", \"libjingle_xmpp_unittests\": \"ccd9bf5de47731a4925b3f48fc0e0084f636b7e0\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"5bf69b41dc3ff0dc31b1f40c44a28607df86766a\", \"media_unittests\": \"bc62f17666924c0a6f6dd1f837397e085365682a\", \"message_center_unittests\": \"70ebc6ac49aba59e11ddc720f0413f284779315b\", \"midi_unittests\": \"806b4f6b481d690ba397eefc8299e920aabff9e4\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"48b2fdae4040884d57fb569ec4fda1e1415e56c0\", \"nacl_loader_unittests\": \"3377aecbc57f3dfd4398a5326074e47c8641e075\", \"native_theme_unittests\": \"61fea70574a0ede955a363d0bd61b42736c76ea1\", \"net_unittests\": \"86dfa37e0c269a2f40a2635cd2f79ec3abec6c52\", \"notification_helper_unittests\": \"853a987f5686447be6b3567464176b67a4ea4ca2\", \"pdf_unittests\": \"ca33dfb3ba8342961b08645e8772bb9371baba62\", \"perfetto_unittests\": \"67327832b9d178166a745f7c4905751ae5d4144e\", \"ppapi_unittests\": \"528b450ea675e89670cef3ceae84ec4856db5712\", \"printing_unittests\": \"78cbedbef1ada9beec061c30ce0af86ab327a354\", \"remoting_unittests\": \"9cc0ee6c35e4d8b1efd17b135c2c0fc53eed9580\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"c651b5ecd123f81227d5def5b0c5fd6cfcd9adf5\", \"services_unittests\": \"2c5b9ae70608aa220db7e7b8db80b41a1b230806\", \"setup_unittests\": \"99c426eedca8600fc10b14eaf2d308616d6c89ef\", \"shell_dialogs_unittests\": \"c83fd3c614b8b0f97a7f64043ec91e7d31835340\", \"skia_unittests\": \"18ead11d3706944cc2326e691efcf502a97a4dd1\", \"snapshot_unittests\": \"445bf05c8662740cdd6c1c86f8443c28b2c495ed\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"a91a312b9a5dad147f1b49d36efd726ad2d83601\", \"sync_integration_tests\": \"887d66eb40f1e0654f20f66fed4b3b1350d1ac48\", \"traffic_annotation_auditor_unittests\": \"e02b203d0be6c4c522aee20986a6984fe4ac0337\", \"ui_base_unittests\": \"d443564944c0df95efa46cb609a23eca30d61146\", \"ui_touch_selection_unittests\": \"fad905adc771ae651bed83d07cdd7acff764d62e\", \"unit_tests\": \"055bedcbe107c0d79f3b41e75fa983d5f3a1456a\", \"updater_tests\": \"19c8e2f8f7a463fdcc783899deb61d425a014636\", \"url_unittests\": \"84cdd9e2933f621b7eb029d145ce4c3acd541a47\", \"views_unittests\": \"6903508582df4e3429be3b6635783d315f44ec94\", \"viz_unittests\": \"8af19630b87147afcaeae09e3b9cb4bad90463ce\", \"vr_common_unittests\": \"d29b83c8f1edf336d607c66b4ed9b1f0961c66b3\", \"vr_pixeltests\": \"97842cf4c6d1fb4e2881e16e7cb937d5d1c66326\", \"weblayer_browsertests\": \"bb3f94a78f23081b79f229d30438b33203f25602\", \"weblayer_unittests\": \"ec8454e17b2546f431e9d1c04ba6cca99dd25552\", \"wm_unittests\": \"a0fda50370dbec01f2f1a84875b3972e4258df1b\", \"wtf_unittests\": \"9fcfc07bf261dbfd4c00be248dd40f6264a10ebe\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"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-6-gpyc\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608739756276882",
"status": "COMPLETED",
"status_changed_ts": "1608739756958573",
"tags": [
"build_address:luci.chromium.ci/win-asan/16411",
"builder:win-asan",
"buildset:commit/git/6a822e69330f36234fa18103bb849925502d9273",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a822e69330f36234fa18103bb849925502d9273",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025537881829166896",
"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/8860107819772908400/+/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:50a92e1a8e931810",
"user_agent:luci-scheduler"
],
"updated_ts": "1608742891935669",
"url": "https://ci.chromium.org/b/8860107819772908400",
"utcnow_ts": "1608742892369035"
}
}
[17:01:32.378] Invocation finished in 52m19.822615585s with status FAILED