[15:23:48.764] New invocation is queued and will start shortly
[15:23:49.940] Starting the invocation (attempt 1)
[15:23:49.984] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:23:49.984] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:23:49.984] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020014323008734528",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a2ddae9e61b7885ceff1b4751a3f073baf60dd21\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020014323008734528",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a2ddae9e61b7885ceff1b4751a3f073baf60dd21",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2ddae9e61b7885ceff1b4751a3f073baf60dd21",
"gitiles_ref:refs/heads/master"
]
}
[15:23:51.610] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614007430132962",
"id": "8854584260900379744",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a2ddae9e61b7885ceff1b4751a3f073baf60dd21\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614007431390604",
"tags": [
"build_address:luci.chromium.ci/win-asan/17472",
"builder:win-asan",
"buildset:commit/git/a2ddae9e61b7885ceff1b4751a3f073baf60dd21",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2ddae9e61b7885ceff1b4751a3f073baf60dd21",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020014323008734528",
"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/8854584260900379744/+/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": "1614007431390706",
"url": "https://ci.chromium.org/b/8854584260900379744",
"utcnow_ts": "1614007431601406"
}
}
[15:23:51.610] Task URL: https://ci.chromium.org/b/8854584260900379744
[15:23:51.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:2:0) after 1m0s
[15:23:55.551] Received PubSub notification, asking Buildbucket for the build status
[15:23:55.660] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:51.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:2:0)
[15:24:51.626] Timer tick, asking Buildbucket for the build status
[15:24:51.945] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:51.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:4:0) after 1m0s
[15:25:52.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:4:0)
[15:25:52.003] Timer tick, asking Buildbucket for the build status
[15:25:52.123] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:52.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:5:0) after 1m0s
[15:26:52.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:5:0)
[15:26:52.290] Timer tick, asking Buildbucket for the build status
[15:26:52.371] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:52.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:6:0) after 1m0s
[15:27:52.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:6:0)
[15:27:52.478] Timer tick, asking Buildbucket for the build status
[15:27:52.560] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:52.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:7:0) after 1m0s
[15:28:52.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:7:0)
[15:28:52.625] Timer tick, asking Buildbucket for the build status
[15:28:52.716] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:52.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:8:0) after 1m0s
[15:29:52.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:8:0)
[15:29:52.846] Timer tick, asking Buildbucket for the build status
[15:29:52.922] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:52.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:9:0) after 1m0s
[15:30:52.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:9:0)
[15:30:52.956] Timer tick, asking Buildbucket for the build status
[15:30:53.263] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:53.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:10:0) after 1m0s
[15:31:53.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:10:0)
[15:31:53.282] Timer tick, asking Buildbucket for the build status
[15:31:53.462] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:53.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:11:0) after 1m0s
[15:32:53.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:11:0)
[15:32:53.583] Timer tick, asking Buildbucket for the build status
[15:32:53.671] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:53.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:12:0) after 1m0s
[15:33:53.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:12:0)
[15:33:53.653] Timer tick, asking Buildbucket for the build status
[15:33:53.771] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:53.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:13:0) after 1m0s
[15:34:53.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:13:0)
[15:34:53.877] Timer tick, asking Buildbucket for the build status
[15:34:53.966] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:53.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:14:0) after 1m0s
[15:35:53.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:14:0)
[15:35:53.941] Timer tick, asking Buildbucket for the build status
[15:35:54.048] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:54.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:15:0) after 1m0s
[15:36:54.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:15:0)
[15:36:54.068] Timer tick, asking Buildbucket for the build status
[15:36:54.220] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:54.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:16:0) after 1m0s
[15:37:54.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:16:0)
[15:37:54.273] Timer tick, asking Buildbucket for the build status
[15:37:54.358] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:54.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:17:0) after 1m0s
[15:38:54.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:17:0)
[15:38:54.404] Timer tick, asking Buildbucket for the build status
[15:38:54.652] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:54.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:18:0) after 1m0s
[15:39:54.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:18:0)
[15:39:54.630] Timer tick, asking Buildbucket for the build status
[15:39:54.726] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:54.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:19:0) after 1m0s
[15:40:54.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:19:0)
[15:40:54.734] Timer tick, asking Buildbucket for the build status
[15:40:54.838] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:54.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:20:0) after 1m0s
[15:41:54.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:20:0)
[15:41:54.848] Timer tick, asking Buildbucket for the build status
[15:41:55.223] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:55.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:21:0) after 1m0s
[15:42:55.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:21:0)
[15:42:55.220] Timer tick, asking Buildbucket for the build status
[15:42:55.327] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:55.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:22:0) after 1m0s
[15:43:55.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:22:0)
[15:43:55.368] Timer tick, asking Buildbucket for the build status
[15:43:55.830] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:55.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:23:0) after 1m0s
[15:44:55.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:23:0)
[15:44:55.847] Timer tick, asking Buildbucket for the build status
[15:44:55.925] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:55.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:24:0) after 1m0s
[15:45:55.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:24:0)
[15:45:55.949] Timer tick, asking Buildbucket for the build status
[15:45:56.244] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:56.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:25:0) after 1m0s
[15:46:56.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:25:0)
[15:46:56.296] Timer tick, asking Buildbucket for the build status
[15:46:56.592] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:56.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:26:0) after 1m0s
[15:47:56.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:26:0)
[15:47:56.601] Timer tick, asking Buildbucket for the build status
[15:47:56.794] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:56.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:27:0) after 1m0s
[15:48:56.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:27:0)
[15:48:56.814] Timer tick, asking Buildbucket for the build status
[15:48:57.028] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:57.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:28:0) after 1m0s
[15:49:57.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:28:0)
[15:49:57.082] Timer tick, asking Buildbucket for the build status
[15:49:57.306] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:57.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:29:0) after 1m0s
[15:50:57.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:29:0)
[15:50:57.376] Timer tick, asking Buildbucket for the build status
[15:50:57.468] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:57.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:30:0) after 1m0s
[15:51:57.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:30:0)
[15:51:57.471] Timer tick, asking Buildbucket for the build status
[15:51:57.707] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:57.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:31:0) after 1m0s
[15:52:57.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:31:0)
[15:52:57.721] Timer tick, asking Buildbucket for the build status
[15:52:57.823] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:57.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:32:0) after 1m0s
[15:53:57.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:32:0)
[15:53:57.845] Timer tick, asking Buildbucket for the build status
[15:53:58.197] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:58.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:33:0) after 1m0s
[15:54:58.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:33:0)
[15:54:58.265] Timer tick, asking Buildbucket for the build status
[15:54:58.394] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:58.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:34:0) after 1m0s
[15:55:58.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:34:0)
[15:55:58.422] Timer tick, asking Buildbucket for the build status
[15:55:58.929] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:58.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:35:0) after 1m0s
[15:56:58.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:35:0)
[15:56:58.989] Timer tick, asking Buildbucket for the build status
[15:56:59.275] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:59.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:36:0) after 1m0s
[15:57:59.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:36:0)
[15:57:59.292] Timer tick, asking Buildbucket for the build status
[15:57:59.397] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:59.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:37:0) after 1m0s
[15:58:59.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:37:0)
[15:58:59.430] Timer tick, asking Buildbucket for the build status
[15:59:00.214] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:00.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:38:0) after 1m0s
[16:00:00.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:38:0)
[16:00:00.280] Timer tick, asking Buildbucket for the build status
[16:00:00.453] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:00.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:39:0) after 1m0s
[16:01:01.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:39:0)
[16:01:01.186] Timer tick, asking Buildbucket for the build status
[16:01:01.414] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:01.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:40:0) after 1m0s
[16:02:01.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:40:0)
[16:02:01.430] Timer tick, asking Buildbucket for the build status
[16:02:01.540] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:01.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:41:0) after 1m0s
[16:03:01.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:41:0)
[16:03:01.578] Timer tick, asking Buildbucket for the build status
[16:03:01.665] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:01.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:42:0) after 1m0s
[16:04:02.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:42:0)
[16:04:02.135] Timer tick, asking Buildbucket for the build status
[16:04:02.269] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:02.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:43:0) after 1m0s
[16:05:02.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:43:0)
[16:05:02.285] Timer tick, asking Buildbucket for the build status
[16:05:02.363] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:02.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:44:0) after 1m0s
[16:06:02.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:44:0)
[16:06:02.456] Timer tick, asking Buildbucket for the build status
[16:06:02.540] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:02.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:45:0) after 1m0s
[16:07:02.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:45:0)
[16:07:02.580] Timer tick, asking Buildbucket for the build status
[16:07:02.719] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:02.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:46:0) after 1m0s
[16:08:02.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:46:0)
[16:08:02.786] Timer tick, asking Buildbucket for the build status
[16:08:03.131] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:03.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:47:0) after 1m0s
[16:09:03.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:47:0)
[16:09:03.261] Timer tick, asking Buildbucket for the build status
[16:09:03.351] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:03.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:48:0) after 1m0s
[16:10:03.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:48:0)
[16:10:03.335] Timer tick, asking Buildbucket for the build status
[16:10:03.451] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:03.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:49:0) after 1m0s
[16:11:03.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:49:0)
[16:11:03.472] Timer tick, asking Buildbucket for the build status
[16:11:10.328] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:10.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:50:0) after 1m0s
[16:12:10.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:50:0)
[16:12:10.377] Timer tick, asking Buildbucket for the build status
[16:12:10.550] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:10.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:51:0) after 1m0s
[16:13:10.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:51:0)
[16:13:10.595] Timer tick, asking Buildbucket for the build status
[16:13:10.688] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:10.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:52:0) after 1m0s
[16:14:10.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:52:0)
[16:14:10.771] Timer tick, asking Buildbucket for the build status
[16:14:10.968] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:10.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:53:0) after 1m0s
[16:15:10.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:53:0)
[16:15:10.984] Timer tick, asking Buildbucket for the build status
[16:15:11.319] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:11.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:54:0) after 1m0s
[16:16:11.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:54:0)
[16:16:11.379] Timer tick, asking Buildbucket for the build status
[16:16:11.460] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:11.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:55:0) after 1m0s
[16:17:11.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:55:0)
[16:17:11.540] Timer tick, asking Buildbucket for the build status
[16:17:11.995] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:11.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:56:0) after 1m0s
[16:18:12.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:56:0)
[16:18:12.063] Timer tick, asking Buildbucket for the build status
[16:18:12.479] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:12.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:57:0) after 1m0s
[16:19:12.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:57:0)
[16:19:12.576] Timer tick, asking Buildbucket for the build status
[16:19:12.811] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:12.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:58:0) after 1m0s
[16:20:12.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:58:0)
[16:20:12.829] Timer tick, asking Buildbucket for the build status
[16:20:12.999] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:12.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:59:0) after 1m0s
[16:21:13.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:59:0)
[16:21:13.028] Timer tick, asking Buildbucket for the build status
[16:21:13.134] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:13.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:60:0) after 1m0s
[16:22:13.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:60:0)
[16:22:13.153] Timer tick, asking Buildbucket for the build status
[16:22:13.270] Build 8854584260900379744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:13.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020014323008734528:61:0) after 1m0s
[16:22:17.440] Received PubSub notification, asking Buildbucket for the build status
[16:22:17.528] Build 8854584260900379744: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:22:17.528] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614010936944877",
"created_by": "project:chromium",
"created_ts": "1614007430132962",
"failure_reason": "BUILD_FAILURE",
"id": "8854584260900379744",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a2ddae9e61b7885ceff1b4751a3f073baf60dd21\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithValidModelInfoFileButNoModelFileShouldDeleteTempFile\"}, \"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\": \"a2ddae9e61b7885ceff1b4751a3f073baf60dd21\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17472, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"c624d4101ea6c01e494e5322ca0baca5fef085a6\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"a2ddae9e61b7885ceff1b4751a3f073baf60dd21\", \"got_revision_cp\": \"refs/heads/master@{#856279}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"d5e3902e5ab63cc50c95c1552ba8571d003613cd\", \"got_v8_revision_cp\": \"refs/heads/9.0.232@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"a2ddae9e61b7885ceff1b4751a3f073baf60dd21\", \"swarm_hashes_refs/heads/master(at){#856279}_without_patch\": {\"absl_hardening_tests\": \"77719e81557e70364610618acd520b379aadaa96\", \"accessibility_unittests\": \"713897c5259298f955ea265d8f1477a80dbcac22\", \"angle_unittests\": \"a5debc7e93c4066153d4d463211c99e2f13a35d5\", \"app_shell_unittests\": \"837bd193390b35ca096a33bd82874811cffed7e8\", \"aura_unittests\": \"599993ef80ab2940f3f9c1201eadca9fad3d29d8\", \"base_unittests\": \"b14e920ae8cc62f8449dbc5d735c353deb2a1dc1\", \"base_util_unittests\": \"a889c8178aad53fc24a7a3c0fbabb93d6a22c2db\", \"blink_common_unittests\": \"e4585831701748a0dd8edcf3fa97499a60e28ff8\", \"blink_fuzzer_unittests\": \"4e6131e1b9cda507ebfedc0bdb1682867c9c3838\", \"blink_heap_unittests\": \"6fbb37a874c4abad8114240e9024ebd483f068c9\", \"blink_platform_unittests\": \"9fb6894c80ac95b3dee3e378c91bb99a7e402d77\", \"blink_unittests\": \"ffcbe3b88e22f6d397e954421f3aa07bab09505b\", \"boringssl_crypto_tests\": \"cd85dfaadb1e9288ae2d117c7010942d121712f9\", \"boringssl_ssl_tests\": \"36523e11dec874cd24d334a79a54651be462281f\", \"browser_switcher_bho_unittests\": \"d44d7547db1f6c0af175a286ea9ef8b11a46e781\", \"browser_tests\": \"eeb775cdfc5a1ab4c794cffbd7b9541e60950463\", \"capture_unittests\": \"c7000048f5931671d20dcb72fe1252e602a7998b\", \"cast_unittests\": \"c37c87f2858c24973f3457da54a0cf9d51f46032\", \"cc_unittests\": \"9138b20be5368e1c01d98b91d33d8f5d13046734\", \"chrome_app_unittests\": \"48a96fe528f68ee133a5cfb13a2a1452cc3c78b6\", \"chrome_cleaner_unittests\": \"5d1ad3eb62f796f749491a2225a05917b19f8dcc\", \"chrome_elf_unittests\": \"77141f5dd56ba22a1f07dd503c7d16f9f1a662ba\", \"chromedriver_unittests\": \"0266f607cc81726097af9388e41e4add4e30f773\", \"components_browsertests\": \"a58033ea7a540a194de91aaafccf6d7fe42d56bd\", \"components_unittests\": \"f4ac2ce8dd087ed84da4274bb98a29540bc68f3d\", \"compositor_unittests\": \"b0463f08adb7e07761508130f01d2346e274bc84\", \"content_browsertests\": \"7744ee1d13f412da4ec8fffc1d6e84d9080299ec\", \"content_unittests\": \"5f6a6f8a35b69ca836599baadbe64df76420fa5a\", \"courgette_unittests\": \"e83224d4c7b48ca8773755209def5a8f18928741\", \"crashpad_tests\": \"31c1ec62a70052216d201501e589288e44cecb5e\", \"cronet_tests\": \"834fcd29ccf6625284c83ab4ae914bde2a26c89c\", \"cronet_unittests\": \"fe274cfc79565d5cd15043162b128b0f773c64b8\", \"crypto_unittests\": \"95eda521f8279fe2509d3eda600cfc7e34e052da\", \"delayloads_unittests\": \"3dd0e8ddaf045c663381c9335a377a08afb48549\", \"device_unittests\": \"75455408a06371b2843307e6feb82c0c6787ffc8\", \"display_unittests\": \"99eaa871d54178b3a177cad7ed6b6daa716dd56f\", \"elevation_service_unittests\": \"cd2606da215291121ec54546337f9cfbc7bd5f3b\", \"events_unittests\": \"8d3f70d9d633be7bbb5dc9975784dae17235529e\", \"extensions_browsertests\": \"feb8391c85b2264578b9c7793ab0a4c21dca56e7\", \"extensions_unittests\": \"824b17ebd78db2224a164f73a99e84076ecc5463\", \"filesystem_service_unittests\": \"3072f7b10ba6129d8a60035e8476466e7a8918f7\", \"gcm_unit_tests\": \"4e664c850f2b60d0c3ed22ca673740474cfd1b20\", \"gcp_unittests\": \"c81280530555713d39b7bbbb6067fe3100f5d3e7\", \"gfx_unittests\": \"a8f4b120b54e3218c13a8dd0aed41bfcf67e7673\", \"gin_unittests\": \"85a26a708660079de49841a5fcb543adfe1591b6\", \"google_apis_unittests\": \"a79fdc0eab261404a59b9d8daa32f6f9b47e6bbb\", \"gpu_unittests\": \"efb831069f1d22489651e6e28207e254a1f9eb6e\", \"gwp_asan_unittests\": \"d97c1e949a83149acbf120c84cc12d365c0d1a62\", \"headless_browsertests\": \"307cd292fb76fd1f671cf66fda52ce875875acd6\", \"headless_unittests\": \"98dbf946c04fcbc6ea32b74938b75f796ff09b18\", \"install_static_unittests\": \"d547ea9bed207418b6d2ed3d993c156396b9b461\", \"installer_util_unittests\": \"0c52f7797540c099f6d722082633f2004ee649ed\", \"interactive_ui_tests\": \"4cd683a7bfa4c44040ce0ba08ff7449017477126\", \"ipc_tests\": \"5ca3fc2721dabd5009e9437de8be84a6e961dbd9\", \"jingle_unittests\": \"646e980a1ce827b071b6bdfe850002f3fa089ba9\", \"latency_unittests\": \"c2320da5a897584af0d193eed4861b4d4e1deafb\", \"libjingle_xmpp_unittests\": \"74f5f0c77945a987a8afc5f90445dd2de13557a5\", \"liburlpattern_unittests\": \"dea5e569d195bfca3aacd38fb465106c8e61a832\", \"media_blink_unittests\": \"43eb965770d5103fb31d03e6c816b355dc81c3b7\", \"media_unittests\": \"a9fb72277dbc76c569559a83d6f5d7371a27f917\", \"message_center_unittests\": \"1d982bdff03acfedc7c79d36a022190fd862561a\", \"midi_unittests\": \"4ba68bc4e1616243b7f8e57ff5be826a83ebc549\", \"mojo_core_unittests\": \"f37482c37fbd0d20b17c6715adf7fe84cc63319a\", \"mojo_unittests\": \"d639ecc7858760868edda112c42e97b6b0946c4d\", \"nacl_loader_unittests\": \"65d73200414753b51d529fb7e307429aadd3f097\", \"native_theme_unittests\": \"41570ef2edd88b4de175b034a011bbc7e2b10305\", \"net_unittests\": \"418b644e5a6de039ce0fda2b31de13a1e6bad489\", \"notification_helper_unittests\": \"72015342711ed26d5959f71653805ee29f0bf86d\", \"pdf_unittests\": \"7c5dea16726f44bfcc48c9d0574f54bcb7c727d7\", \"perfetto_unittests\": \"475f77640758d3135555e3a0c61bb0503336288f\", \"ppapi_unittests\": \"7b39a44462acd7ab0787aaebba2c4854ca546c91\", \"printing_unittests\": \"f2c02b6012058892998a3dee6bfa1fbd53598bf4\", \"remoting_unittests\": \"4206c21ccba80253da4eb6d8d5337c069d2556c4\", \"sbox_integration_tests\": \"e7d5fb61bf58b901661697eb9727a5efc3c73f69\", \"sbox_unittests\": \"7ee5d06f464e7cf857a4a8332955f72f57b18645\", \"sbox_validation_tests\": \"53945b8dddc321031058b3ed964bdd4ee4f1a105\", \"service_manager_unittests\": \"3a6d537a278eede72ead2e0503ff6c7aaf479304\", \"services_unittests\": \"367fc8761642c6809324792ed3dd04708e0b16fe\", \"setup_unittests\": \"6cba07bba2182e271398fcc321d69999ad64b667\", \"shell_dialogs_unittests\": \"f1a605632b6ce5b08fe6daa9df9c092e4aaca44b\", \"skia_unittests\": \"8660697e2243431edd4033c21048c7f70e7286c5\", \"snapshot_unittests\": \"b2cb523b307472ff6d3cc44b5fc1a9798cb236f1\", \"sql_unittests\": \"7840df688004a0b2ee84fd7883ecc08e3eb29b7c\", \"storage_unittests\": \"aa353855b4dc86cdebd9ac24833e89787ee0af99\", \"sync_integration_tests\": \"b5e452f859021c343b8dda9654c1abd4de7a2232\", \"traffic_annotation_auditor_unittests\": \"25be729539da5bcb3b88501757dc65a921f2d273\", \"ui_base_unittests\": \"b664b03426859837400af1fe0a6eea51cad6dd15\", \"ui_touch_selection_unittests\": \"d281a33a1889624ce97e558a27071eb3f83fbf15\", \"unit_tests\": \"b8e8c765142fc826c7fe4747bf7a0ee74d13f566\", \"updater_tests\": \"72f4026329996e5e70d41bec6e9f57fea78e07d5\", \"url_unittests\": \"50b87ecbc93e3e3ec558164f743f729ce018c462\", \"views_unittests\": \"206d33a432fff9a158aabbe96b2409182b6c564d\", \"viz_unittests\": \"bc1738dfec72d91a3b63172deba61a7570907470\", \"vr_common_unittests\": \"f5fa3678aa6a877262abb46f9f0cf2f6264647fb\", \"vr_pixeltests\": \"c9cc1bab7b8d8a1a103278c97d79a22ff07f7828\", \"weblayer_browsertests\": \"68b47f7baa4603137aca35fb554bce8b684dee0a\", \"weblayer_unittests\": \"ebe5af265eb99ef09182677d415a61afb51084db\", \"wm_unittests\": \"9c1bccbc3c7c7dc22494ee299270eb3f2c2c5ea6\", \"wtf_unittests\": \"538d8d46f17fc7ffe9f524c0d756ba9aa06ef932\", \"zlib_unittests\": \"7a7235d8249466aec4772ff1839cae8dd452f2bc\", \"zucchini_unittests\": \"a5fcc385f8d184f845cbf4d2e6d058b7bdbae044\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xpf4\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5706-397711c\"], \"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.VerifiedCrxWithValidModelInfoFileButNoModelFileShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614007434799668",
"status": "COMPLETED",
"status_changed_ts": "1614007435268905",
"tags": [
"build_address:luci.chromium.ci/win-asan/17472",
"builder:win-asan",
"buildset:commit/git/a2ddae9e61b7885ceff1b4751a3f073baf60dd21",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2ddae9e61b7885ceff1b4751a3f073baf60dd21",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020014323008734528",
"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/8854584260900379744/+/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:51e3287aa8947b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614010937168170",
"url": "https://ci.chromium.org/b/8854584260900379744",
"utcnow_ts": "1614010937520341"
}
}
[16:22:17.528] Invocation finished in 58m28.789737926s with status FAILED