[22:22:10.869] New invocation is queued and will start shortly
[22:22:12.922] Starting the invocation (attempt 1)
[22:22:12.968] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:22:12.969] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:22:12.969] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023430686257554960",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"524d99903c6da51488cd4706b759a34548e6b2f5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023430686257554960",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/524d99903c6da51488cd4706b759a34548e6b2f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/524d99903c6da51488cd4706b759a34548e6b2f5",
"gitiles_ref:refs/heads/master"
]
}
[22:22:14.755] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610749333074946",
"id": "8858000623281212304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"524d99903c6da51488cd4706b759a34548e6b2f5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610749334301861",
"tags": [
"build_address:luci.chromium.ci/win-asan/16846",
"builder:win-asan",
"buildset:commit/git/524d99903c6da51488cd4706b759a34548e6b2f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/524d99903c6da51488cd4706b759a34548e6b2f5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023430686257554960",
"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/8858000623281212304/+/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": "1610749334301990",
"url": "https://ci.chromium.org/b/8858000623281212304",
"utcnow_ts": "1610749334741140"
}
}
[22:22:14.755] Task URL: https://ci.chromium.org/b/8858000623281212304
[22:22:14.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:2:0) after 1m0s
[22:22:17.336] Received PubSub notification, asking Buildbucket for the build status
[22:22:17.541] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:14.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:2:0)
[22:23:14.812] Timer tick, asking Buildbucket for the build status
[22:23:14.906] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:14.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:4:0) after 1m0s
[22:24:14.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:4:0)
[22:24:14.933] Timer tick, asking Buildbucket for the build status
[22:24:15.456] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:15.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:5:0) after 1m0s
[22:25:15.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:5:0)
[22:25:15.485] Timer tick, asking Buildbucket for the build status
[22:25:15.816] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:15.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:6:0) after 1m0s
[22:26:15.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:6:0)
[22:26:15.863] Timer tick, asking Buildbucket for the build status
[22:26:16.139] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:16.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:7:0) after 1m0s
[22:27:16.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:7:0)
[22:27:16.198] Timer tick, asking Buildbucket for the build status
[22:27:16.370] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:16.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:8:0) after 1m0s
[22:28:16.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:8:0)
[22:28:16.427] Timer tick, asking Buildbucket for the build status
[22:28:16.685] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:16.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:9:0) after 1m0s
[22:29:16.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:9:0)
[22:29:16.686] Timer tick, asking Buildbucket for the build status
[22:29:16.843] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:16.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:10:0) after 1m0s
[22:30:16.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:10:0)
[22:30:16.836] Timer tick, asking Buildbucket for the build status
[22:30:16.938] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:16.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:11:0) after 1m0s
[22:31:17.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:11:0)
[22:31:17.056] Timer tick, asking Buildbucket for the build status
[22:31:17.152] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:17.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:12:0) after 1m0s
[22:32:17.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:12:0)
[22:32:17.182] Timer tick, asking Buildbucket for the build status
[22:32:17.543] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:17.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:13:0) after 1m0s
[22:33:17.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:13:0)
[22:33:17.586] Timer tick, asking Buildbucket for the build status
[22:33:17.649] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:17.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:14:0) after 1m0s
[22:34:17.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:14:0)
[22:34:17.710] Timer tick, asking Buildbucket for the build status
[22:34:17.798] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:17.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:15:0) after 1m0s
[22:35:17.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:15:0)
[22:35:17.817] Timer tick, asking Buildbucket for the build status
[22:35:17.933] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:17.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:16:0) after 1m0s
[22:36:17.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:16:0)
[22:36:17.979] Timer tick, asking Buildbucket for the build status
[22:36:18.089] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:18.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:17:0) after 1m0s
[22:37:18.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:17:0)
[22:37:18.119] Timer tick, asking Buildbucket for the build status
[22:37:18.227] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:18.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:18:0) after 1m0s
[22:38:18.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:18:0)
[22:38:18.255] Timer tick, asking Buildbucket for the build status
[22:38:18.325] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:18.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:19:0) after 1m0s
[22:39:18.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:19:0)
[22:39:18.406] Timer tick, asking Buildbucket for the build status
[22:39:19.194] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:19.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:20:0) after 1m0s
[22:40:19.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:20:0)
[22:40:19.395] Timer tick, asking Buildbucket for the build status
[22:40:19.763] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:19.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:21:0) after 1m0s
[22:41:19.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:21:0)
[22:41:19.741] Timer tick, asking Buildbucket for the build status
[22:41:20.403] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:20.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:22:0) after 1m0s
[22:42:20.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:22:0)
[22:42:20.535] Timer tick, asking Buildbucket for the build status
[22:42:21.130] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:21.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:23:0) after 1m0s
[22:43:21.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:23:0)
[22:43:21.269] Timer tick, asking Buildbucket for the build status
[22:43:21.935] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:21.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:24:0) after 1m0s
[22:44:21.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:24:0)
[22:44:21.942] Timer tick, asking Buildbucket for the build status
[22:44:22.254] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:22.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:25:0) after 1m0s
[22:45:22.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:25:0)
[22:45:22.233] Timer tick, asking Buildbucket for the build status
[22:45:22.415] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:22.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:26:0) after 1m0s
[22:46:22.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:26:0)
[22:46:22.517] Timer tick, asking Buildbucket for the build status
[22:46:22.975] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:22.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:27:0) after 1m0s
[22:47:23.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:27:0)
[22:47:23.016] Timer tick, asking Buildbucket for the build status
[22:47:23.441] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:23.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:28:0) after 1m0s
[22:48:23.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:28:0)
[22:48:23.537] Timer tick, asking Buildbucket for the build status
[22:48:23.904] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:23.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:29:0) after 1m0s
[22:49:23.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:29:0)
[22:49:23.958] Timer tick, asking Buildbucket for the build status
[22:49:24.143] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:24.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:30:0) after 1m0s
[22:50:24.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:30:0)
[22:50:24.193] Timer tick, asking Buildbucket for the build status
[22:50:24.273] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:24.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:31:0) after 1m0s
[22:51:24.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:31:0)
[22:51:24.304] Timer tick, asking Buildbucket for the build status
[22:51:25.350] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:25.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:32:0) after 1m0s
[22:52:25.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:32:0)
[22:52:25.427] Timer tick, asking Buildbucket for the build status
[22:52:25.547] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:25.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:33:0) after 1m0s
[22:53:25.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:33:0)
[22:53:25.601] Timer tick, asking Buildbucket for the build status
[22:53:25.716] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:25.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:34:0) after 1m0s
[22:54:25.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:34:0)
[22:54:25.815] Timer tick, asking Buildbucket for the build status
[22:54:25.884] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:25.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:35:0) after 1m0s
[22:55:25.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:35:0)
[22:55:25.910] Timer tick, asking Buildbucket for the build status
[22:55:26.000] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:26.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:36:0) after 1m0s
[22:56:26.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:36:0)
[22:56:26.078] Timer tick, asking Buildbucket for the build status
[22:56:26.200] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:26.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:37:0) after 1m0s
[22:57:26.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:37:0)
[22:57:26.203] Timer tick, asking Buildbucket for the build status
[22:57:26.346] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:26.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:38:0) after 1m0s
[22:58:26.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:38:0)
[22:58:26.373] Timer tick, asking Buildbucket for the build status
[22:58:26.628] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:26.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:39:0) after 1m0s
[22:59:26.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:39:0)
[22:59:26.653] Timer tick, asking Buildbucket for the build status
[22:59:26.760] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:26.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:40:0) after 1m0s
[23:00:26.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:40:0)
[23:00:26.885] Timer tick, asking Buildbucket for the build status
[23:00:27.032] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:27.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:41:0) after 1m0s
[23:01:27.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:41:0)
[23:01:27.192] Timer tick, asking Buildbucket for the build status
[23:01:27.344] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:27.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:42:0) after 1m0s
[23:02:27.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:42:0)
[23:02:27.408] Timer tick, asking Buildbucket for the build status
[23:02:27.592] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:27.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:43:0) after 1m0s
[23:03:27.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:43:0)
[23:03:27.636] Timer tick, asking Buildbucket for the build status
[23:03:27.734] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:27.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:44:0) after 1m0s
[23:04:27.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:44:0)
[23:04:27.874] Timer tick, asking Buildbucket for the build status
[23:04:31.697] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:31.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:45:0) after 1m0s
[23:05:31.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:45:0)
[23:05:31.731] Timer tick, asking Buildbucket for the build status
[23:05:32.690] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:32.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:46:0) after 1m0s
[23:06:32.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:46:0)
[23:06:32.719] Timer tick, asking Buildbucket for the build status
[23:06:32.994] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:32.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:47:0) after 1m0s
[23:07:33.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:47:0)
[23:07:33.039] Timer tick, asking Buildbucket for the build status
[23:07:33.186] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:33.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:48:0) after 1m0s
[23:08:33.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:48:0)
[23:08:33.241] Timer tick, asking Buildbucket for the build status
[23:08:33.706] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:33.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:49:0) after 1m0s
[23:09:33.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:49:0)
[23:09:33.728] Timer tick, asking Buildbucket for the build status
[23:09:34.014] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:34.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:50:0) after 1m0s
[23:10:34.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:50:0)
[23:10:34.074] Timer tick, asking Buildbucket for the build status
[23:10:34.162] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:34.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:51:0) after 1m0s
[23:11:34.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:51:0)
[23:11:34.216] Timer tick, asking Buildbucket for the build status
[23:11:34.557] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:34.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:52:0) after 1m0s
[23:12:34.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:52:0)
[23:12:34.602] Timer tick, asking Buildbucket for the build status
[23:12:34.714] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:34.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:53:0) after 1m0s
[23:13:34.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:53:0)
[23:13:34.834] Timer tick, asking Buildbucket for the build status
[23:13:34.940] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:34.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:54:0) after 1m0s
[23:14:34.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:54:0)
[23:14:34.968] Timer tick, asking Buildbucket for the build status
[23:14:35.093] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:35.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:55:0) after 1m0s
[23:15:35.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:55:0)
[23:15:35.098] Timer tick, asking Buildbucket for the build status
[23:15:35.161] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:35.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:56:0) after 1m0s
[23:16:35.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:56:0)
[23:16:35.212] Timer tick, asking Buildbucket for the build status
[23:16:35.517] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:35.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:57:0) after 1m0s
[23:17:35.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:57:0)
[23:17:35.540] Timer tick, asking Buildbucket for the build status
[23:17:35.666] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:35.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:58:0) after 1m0s
[23:18:35.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:58:0)
[23:18:35.692] Timer tick, asking Buildbucket for the build status
[23:18:35.813] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:35.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:59:0) after 1m0s
[23:19:35.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:59:0)
[23:19:35.825] Timer tick, asking Buildbucket for the build status
[23:19:35.978] Build 8858000623281212304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:35.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023430686257554960:60:0) after 1m0s
[23:20:00.497] Received PubSub notification, asking Buildbucket for the build status
[23:20:00.896] Build 8858000623281212304: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:20:00.896] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610752798011447",
"created_by": "project:chromium",
"created_ts": "1610749333074946",
"id": "8858000623281212304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"524d99903c6da51488cd4706b759a34548e6b2f5\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"524d99903c6da51488cd4706b759a34548e6b2f5\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16846, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"8f5ca26678c12d54d2981a84e48a5304746ec824\", \"got_dawn_revision\": \"54449966dd2eaa9a04449855f67af4bb29d52239\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"524d99903c6da51488cd4706b759a34548e6b2f5\", \"got_revision_cp\": \"refs/heads/master@{#844259}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"af4a3a998449cb389b607a8b541bf585587667c3\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"524d99903c6da51488cd4706b759a34548e6b2f5\", \"swarm_hashes_refs/heads/master(at){#844259}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"4b33715ae837c036cdf92bee8fb6f18fb0cf38b3\", \"angle_unittests\": \"2ee4162c765277a6a572af317425a77e7d42ab19\", \"app_shell_unittests\": \"0b8afadc751e68aa679e9c75897add575b2660d0\", \"aura_unittests\": \"778ad2b9035cb99f81811f1666e97e0f12b5fca2\", \"base_unittests\": \"0d494cd6f1916d7549b79a9e69c79d91aada06b6\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"0ba3ecaa2db1e274429d13b26bc46ca1ce511a17\", \"blink_fuzzer_unittests\": \"e6036bf596ec1fdf96dd875576d2b6b47e1bd8ca\", \"blink_heap_unittests\": \"7049b6a35c69ef55ebc1a66011021fb552d66a0e\", \"blink_platform_unittests\": \"f10c63f67a59f85725a7602cda3fa72ef79a238c\", \"blink_unittests\": \"d1b77ebd6ce282aa74db4f8568d031ecdda71f0b\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"f3dc60171f7c49abf751c74a7756878bfb187f4b\", \"capture_unittests\": \"0ef02773c9a738efb5564cdc2c1db44ed2cc0852\", \"cast_unittests\": \"268065db7f78d26cd15b262e293f6ead1511bf32\", \"cc_unittests\": \"1d9f18bc672f08214cf5f10ebb14b0cdc149c731\", \"chrome_app_unittests\": \"70ab33823e4302bc4ab39044e140ca2dc84feafb\", \"chrome_cleaner_unittests\": \"1efd56f6f09a4e47331c4e0915aae5a2860ec19f\", \"chrome_elf_unittests\": \"507a66d0e3ed5d516b9c1a1cdb35c15de2cfbcfe\", \"chromedriver_unittests\": \"7b148ff6bec5ad373ad04300db62aa571d67e656\", \"components_browsertests\": \"62c6b03ce7d2269589ed4fb9a0ceaddf47190bdf\", \"components_unittests\": \"ab6aeb673a18058a6eb9901b391422f565e8bdff\", \"compositor_unittests\": \"28211f6574c3c1af7ba5a4e3aef1234e4f8ee6b3\", \"content_browsertests\": \"ea0dfa932872c37b920c68914d81b0328884acd2\", \"content_unittests\": \"701d9c7f0fb1fc503ab927f6df8eaa78dfe105aa\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"de06c729b681fe87c009e1a8d2c347fa7eb16327\", \"cronet_unittests\": \"34027c03c9fc62467d6c73f779f4ee65dd2c033d\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"f607fbc4f26cc9288a825f93c5965368ff1d981b\", \"device_unittests\": \"28890082d32ccf926e82a5515d4fdbda80c5a2b8\", \"display_unittests\": \"0ac7def2f1d7eca82b2dfb96b20f18e2bb9a8255\", \"elevation_service_unittests\": \"f191981cef5c7b3c3768982edc3d8b26d8a54d73\", \"events_unittests\": \"87e687ebd91f1330dab40f7965f855463d6df6cf\", \"extensions_browsertests\": \"fbb9d41c3f1d019c3b72eb6bdd1b67dad609d572\", \"extensions_unittests\": \"f6135fa0f4e0771680c223374fd5f4bb23a22799\", \"filesystem_service_unittests\": \"1a0a90ec0e5e56ae3e1106d44d642ed86eb571b7\", \"gcm_unit_tests\": \"9a2a60b0d0c4cc3ac73f1d00bf1e0c21af593aff\", \"gcp_unittests\": \"8f5df1c927be482ab9905afa63e51e075f652ae9\", \"gfx_unittests\": \"49ba652ed9e8d82c9fe9e40f7fcdc3726dcbada1\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"f4d7f204250e4d586ef67b629f322d624b2bbd4d\", \"gpu_unittests\": \"cb0bba27fb5d63260d53eb0a9af0d22d6a854019\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"db09d85be3cfc0e95f47aebf90d2ce15f312b6ac\", \"headless_unittests\": \"2595c7d3482015f1b06020a2b7573c42897386bc\", \"install_static_unittests\": \"d49b57e36d9f580b2207b3a09bab7dc64c1c508a\", \"installer_util_unittests\": \"7ff393f6b32f1289c91ea19d3344e86bddfc0a80\", \"interactive_ui_tests\": \"e96844d9bf9a3ac56e1d11d6ba64a4d9f81d3f78\", \"ipc_tests\": \"04b29ea2dcac58398dc589b81059bb615997fce1\", \"jingle_unittests\": \"967c1e0a3d9386e5a7f260f776f3655f3496cee0\", \"latency_unittests\": \"adf60cb5af87d6fbb12d3e72913c3cd565f44806\", \"libjingle_xmpp_unittests\": \"894a6d24b059fb7dfad8dd1d6b9bfeecf47f4e57\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"21eb1e07a16dcbbee4fd525fa07a474fd2b5dda9\", \"media_unittests\": \"7644da7f90516b929d6e65f1961ed46526a0fb6a\", \"message_center_unittests\": \"376891174d7d5d0bc43ac445d9edf8ec2efba97c\", \"midi_unittests\": \"63ef9c56554fdcd74f5124fb04fc6dcc32676df1\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"08e939c3cdbd96a33251268e148102bea63a7b30\", \"nacl_loader_unittests\": \"c8b2b90af4d41d0d6071a9c8622d47b09fc47ad4\", \"native_theme_unittests\": \"d4d3322ccb0348ada1fd2f5a9e27fe5986298635\", \"net_unittests\": \"48ff206d05ae9f55c4242d02b610fd8b4ead46ae\", \"notification_helper_unittests\": \"2193545a3dd6aafef2cab95071dccb18ff795333\", \"pdf_unittests\": \"d645aca948434a03658b4f1fd6eae55545e3fad7\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"17dd211554de4136e25ac2d4d72fc72fb90109e1\", \"printing_unittests\": \"cd4275311dbe706ac2773baa80d89fd744c5795f\", \"remoting_unittests\": \"a06bb2fc7c3c31d8a6db51885afaabe21c3cbed1\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"84f5fb9737a010c22aa998edcc104d873adfed64\", \"services_unittests\": \"0db89913a5dbf1e5f8b9bd7e4093a45d46dd0213\", \"setup_unittests\": \"85e387ab1605415185ddc30f17de0cd036538a08\", \"shell_dialogs_unittests\": \"d662d920a506d453cd64e0cb49dc7fa7c16609fa\", \"skia_unittests\": \"d52a2a0eff43c25ad0b3960b3457ff362f91f408\", \"snapshot_unittests\": \"a223be8240c69c47e433ae0ca6945f2ff0bac913\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"744bcdb0b9f192b2fea8b0e8af258ec36235774c\", \"sync_integration_tests\": \"f7d0059aec5265d054a623370b9aec2401e4bf96\", \"traffic_annotation_auditor_unittests\": \"5ca5b0dfb6e577335278e6a58d35ac57539b9360\", \"ui_base_unittests\": \"eca257e99ead1d269f17e5bddad98e6f00755660\", \"ui_touch_selection_unittests\": \"9f4c32cfc2e1220f62fc973f84fada37e303be49\", \"unit_tests\": \"0005838b317529c9afc3fdbbcb5e8b624cd50d89\", \"updater_tests\": \"f79db0f6e406c154b6c7e2385498bff710f5c367\", \"url_unittests\": \"ca5c3c0079d3547037355463520ce302894f4548\", \"views_unittests\": \"67c117d267f798b98133816f45ab4441d66595f3\", \"viz_unittests\": \"17e92afa6382a153c26a75ccaf0d9c9d517bcbe4\", \"vr_common_unittests\": \"c5ba6e60efdfc28e73b0bf968e0cfc9b0b6e3b5a\", \"vr_pixeltests\": \"218c0d51045cd30ee515ca51ba13bfdc652db8be\", \"weblayer_browsertests\": \"618397b94173b4af72d60e66743a64e23039f044\", \"weblayer_unittests\": \"247306e6ab8303df4ec75b9d11644ac2bb7b3ffc\", \"wm_unittests\": \"30c003d8ed119393efd797f515bc7302345643ad\", \"wtf_unittests\": \"a1f5ffcb777592beec5249f95f560581a65dabc6\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"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-nb2x\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610749336831088",
"status": "COMPLETED",
"status_changed_ts": "1610752798580748",
"tags": [
"build_address:luci.chromium.ci/win-asan/16846",
"builder:win-asan",
"buildset:commit/git/524d99903c6da51488cd4706b759a34548e6b2f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/524d99903c6da51488cd4706b759a34548e6b2f5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023430686257554960",
"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/8858000623281212304/+/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:5120f5dea53e4c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610752798593677",
"url": "https://ci.chromium.org/b/8858000623281212304",
"utcnow_ts": "1610752800877083"
}
}
[23:20:00.896] Invocation finished in 57m50.043264911s with status SUCCEEDED