[10:45:07.775] New invocation is queued and will start shortly
[10:45:08.851] Starting the invocation (attempt 1)
[10:45:08.885] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:45:08.885] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:45:08.885] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024199316680552848",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"03507dbec6f3f66e26325964b3c3b7befa6bfa6c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024199316680552848",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/03507dbec6f3f66e26325964b3c3b7befa6bfa6c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03507dbec6f3f66e26325964b3c3b7befa6bfa6c",
"gitiles_ref:refs/heads/master"
]
}
[10:45:10.049] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610016309028691",
"id": "8858769254702941408",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"03507dbec6f3f66e26325964b3c3b7befa6bfa6c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610016309921845",
"tags": [
"build_address:luci.chromium.ci/win-asan/16671",
"builder:win-asan",
"buildset:commit/git/03507dbec6f3f66e26325964b3c3b7befa6bfa6c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03507dbec6f3f66e26325964b3c3b7befa6bfa6c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024199316680552848",
"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/8858769254702941408/+/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": "1610016309921953",
"url": "https://ci.chromium.org/b/8858769254702941408",
"utcnow_ts": "1610016310039014"
}
}
[10:45:10.049] Task URL: https://ci.chromium.org/b/8858769254702941408
[10:45:10.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:2:0) after 1m0s
[10:45:12.762] Received PubSub notification, asking Buildbucket for the build status
[10:45:12.835] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:10.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:2:0)
[10:46:10.184] Timer tick, asking Buildbucket for the build status
[10:46:10.291] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:10.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:4:0) after 1m0s
[10:47:10.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:4:0)
[10:47:10.314] Timer tick, asking Buildbucket for the build status
[10:47:10.414] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:10.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:5:0) after 1m0s
[10:48:10.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:5:0)
[10:48:10.451] Timer tick, asking Buildbucket for the build status
[10:48:10.549] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:10.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:6:0) after 1m0s
[10:49:10.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:6:0)
[10:49:10.525] Timer tick, asking Buildbucket for the build status
[10:49:11.032] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:11.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:7:0) after 1m0s
[10:50:11.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:7:0)
[10:50:11.097] Timer tick, asking Buildbucket for the build status
[10:50:11.384] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:11.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:8:0) after 1m0s
[10:51:11.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:8:0)
[10:51:11.497] Timer tick, asking Buildbucket for the build status
[10:51:11.683] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:11.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:9:0) after 1m0s
[10:52:11.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:9:0)
[10:52:11.701] Timer tick, asking Buildbucket for the build status
[10:52:11.827] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:11.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:10:0) after 1m0s
[10:53:11.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:10:0)
[10:53:11.867] Timer tick, asking Buildbucket for the build status
[10:53:12.171] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:12.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:11:0) after 1m0s
[10:54:12.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:11:0)
[10:54:12.205] Timer tick, asking Buildbucket for the build status
[10:54:12.369] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:12.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:12:0) after 1m0s
[10:55:12.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:12:0)
[10:55:12.370] Timer tick, asking Buildbucket for the build status
[10:55:13.533] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:13.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:13:0) after 1m0s
[10:56:13.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:13:0)
[10:56:13.551] Timer tick, asking Buildbucket for the build status
[10:56:13.646] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:13.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:14:0) after 1m0s
[10:57:13.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:14:0)
[10:57:13.665] Timer tick, asking Buildbucket for the build status
[10:57:13.761] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:13.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:15:0) after 1m0s
[10:58:13.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:15:0)
[10:58:13.779] Timer tick, asking Buildbucket for the build status
[10:58:13.994] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:13.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:16:0) after 1m0s
[10:59:14.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:16:0)
[10:59:14.022] Timer tick, asking Buildbucket for the build status
[10:59:14.412] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:14.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:17:0) after 1m0s
[11:00:14.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:17:0)
[11:00:14.471] Timer tick, asking Buildbucket for the build status
[11:00:14.648] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:14.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:18:0) after 1m0s
[11:01:14.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:18:0)
[11:01:14.697] Timer tick, asking Buildbucket for the build status
[11:01:14.798] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:14.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:19:0) after 1m0s
[11:02:14.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:19:0)
[11:02:14.832] Timer tick, asking Buildbucket for the build status
[11:02:15.056] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:15.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:20:0) after 1m0s
[11:03:15.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:20:0)
[11:03:15.085] Timer tick, asking Buildbucket for the build status
[11:03:15.217] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:15.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:21:0) after 1m0s
[11:04:15.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:21:0)
[11:04:15.236] Timer tick, asking Buildbucket for the build status
[11:04:15.362] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:15.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:22:0) after 1m0s
[11:05:15.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:22:0)
[11:05:15.387] Timer tick, asking Buildbucket for the build status
[11:05:15.573] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:15.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:23:0) after 1m0s
[11:06:15.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:23:0)
[11:06:15.606] Timer tick, asking Buildbucket for the build status
[11:06:15.747] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:15.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:24:0) after 1m0s
[11:07:15.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:24:0)
[11:07:15.768] Timer tick, asking Buildbucket for the build status
[11:07:15.944] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:15.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:25:0) after 1m0s
[11:08:15.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:25:0)
[11:08:15.995] Timer tick, asking Buildbucket for the build status
[11:08:16.366] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:16.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:26:0) after 1m0s
[11:09:16.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:26:0)
[11:09:16.448] Timer tick, asking Buildbucket for the build status
[11:09:16.516] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:16.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:27:0) after 1m0s
[11:10:16.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:27:0)
[11:10:16.504] Timer tick, asking Buildbucket for the build status
[11:10:16.719] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:16.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:28:0) after 1m0s
[11:11:16.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:28:0)
[11:11:16.729] Timer tick, asking Buildbucket for the build status
[11:11:16.848] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:16.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:29:0) after 1m0s
[11:12:17.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:29:0)
[11:12:17.994] Timer tick, asking Buildbucket for the build status
[11:12:18.315] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:18.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:30:0) after 1m0s
[11:13:18.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:30:0)
[11:13:18.501] Timer tick, asking Buildbucket for the build status
[11:13:18.835] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:18.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:31:0) after 1m0s
[11:14:18.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:31:0)
[11:14:18.868] Timer tick, asking Buildbucket for the build status
[11:14:18.962] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:18.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:32:0) after 1m0s
[11:15:19.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:32:0)
[11:15:19.298] Timer tick, asking Buildbucket for the build status
[11:15:19.496] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:19.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:33:0) after 1m0s
[11:16:19.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:33:0)
[11:16:19.670] Timer tick, asking Buildbucket for the build status
[11:16:19.751] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:19.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:34:0) after 1m0s
[11:17:19.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:34:0)
[11:17:19.858] Timer tick, asking Buildbucket for the build status
[11:17:19.933] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:19.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:35:0) after 1m0s
[11:18:20.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:35:0)
[11:18:20.107] Timer tick, asking Buildbucket for the build status
[11:18:20.187] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:20.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:36:0) after 1m0s
[11:19:20.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:36:0)
[11:19:20.352] Timer tick, asking Buildbucket for the build status
[11:19:20.492] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:20.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:37:0) after 1m0s
[11:20:20.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:37:0)
[11:20:20.629] Timer tick, asking Buildbucket for the build status
[11:20:20.832] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:20.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:38:0) after 1m0s
[11:21:20.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:38:0)
[11:21:20.813] Timer tick, asking Buildbucket for the build status
[11:21:20.944] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:20.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:39:0) after 1m0s
[11:22:21.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:39:0)
[11:22:21.109] Timer tick, asking Buildbucket for the build status
[11:22:21.233] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:21.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:40:0) after 1m0s
[11:23:21.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:40:0)
[11:23:21.413] Timer tick, asking Buildbucket for the build status
[11:23:21.535] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:21.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:41:0) after 1m0s
[11:24:21.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:41:0)
[11:24:21.661] Timer tick, asking Buildbucket for the build status
[11:24:22.104] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:22.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:42:0) after 1m0s
[11:25:22.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:42:0)
[11:25:22.162] Timer tick, asking Buildbucket for the build status
[11:25:22.307] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:22.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:43:0) after 1m0s
[11:26:22.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:43:0)
[11:26:22.380] Timer tick, asking Buildbucket for the build status
[11:26:22.542] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:22.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:44:0) after 1m0s
[11:27:22.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:44:0)
[11:27:22.673] Timer tick, asking Buildbucket for the build status
[11:27:22.749] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:22.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:45:0) after 1m0s
[11:28:22.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:45:0)
[11:28:22.781] Timer tick, asking Buildbucket for the build status
[11:28:22.869] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:22.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:46:0) after 1m0s
[11:29:23.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:46:0)
[11:29:23.039] Timer tick, asking Buildbucket for the build status
[11:29:23.166] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:23.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:47:0) after 1m0s
[11:30:23.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:47:0)
[11:30:23.206] Timer tick, asking Buildbucket for the build status
[11:30:23.632] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:23.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:48:0) after 1m0s
[11:31:23.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:48:0)
[11:31:23.710] Timer tick, asking Buildbucket for the build status
[11:31:23.809] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:23.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:49:0) after 1m0s
[11:32:23.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:49:0)
[11:32:23.876] Timer tick, asking Buildbucket for the build status
[11:32:24.038] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:24.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:50:0) after 1m0s
[11:33:24.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:50:0)
[11:33:24.125] Timer tick, asking Buildbucket for the build status
[11:33:24.206] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:24.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:51:0) after 1m0s
[11:34:24.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:51:0)
[11:34:24.226] Timer tick, asking Buildbucket for the build status
[11:34:24.308] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:24.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:52:0) after 1m0s
[11:35:24.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:52:0)
[11:35:24.333] Timer tick, asking Buildbucket for the build status
[11:35:24.454] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:24.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:53:0) after 1m0s
[11:36:24.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:53:0)
[11:36:24.567] Timer tick, asking Buildbucket for the build status
[11:36:24.646] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:24.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:54:0) after 1m0s
[11:37:24.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:54:0)
[11:37:24.733] Timer tick, asking Buildbucket for the build status
[11:37:25.282] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:25.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:55:0) after 1m0s
[11:38:25.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:55:0)
[11:38:25.310] Timer tick, asking Buildbucket for the build status
[11:38:25.753] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:25.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:56:0) after 1m0s
[11:39:25.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:56:0)
[11:39:25.816] Timer tick, asking Buildbucket for the build status
[11:39:25.902] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:25.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:57:0) after 1m0s
[11:40:25.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:57:0)
[11:40:25.926] Timer tick, asking Buildbucket for the build status
[11:40:26.116] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:26.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:58:0) after 1m0s
[11:41:26.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:58:0)
[11:41:26.177] Timer tick, asking Buildbucket for the build status
[11:41:26.604] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:26.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:59:0) after 1m0s
[11:42:26.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:59:0)
[11:42:26.698] Timer tick, asking Buildbucket for the build status
[11:42:26.768] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:26.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:60:0) after 1m0s
[11:43:26.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:60:0)
[11:43:26.794] Timer tick, asking Buildbucket for the build status
[11:43:26.888] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:26.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:61:0) after 1m0s
[11:44:26.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:61:0)
[11:44:26.916] Timer tick, asking Buildbucket for the build status
[11:44:27.015] Build 8858769254702941408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:27.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024199316680552848:62:0) after 1m0s
[11:44:28.414] Received PubSub notification, asking Buildbucket for the build status
[11:44:28.872] Build 8858769254702941408: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:44:28.873] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610019867238230",
"created_by": "project:chromium",
"created_ts": "1610016309028691",
"id": "8858769254702941408",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"03507dbec6f3f66e26325964b3c3b7befa6bfa6c\"}}",
"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\": \"03507dbec6f3f66e26325964b3c3b7befa6bfa6c\", \"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-7-w73f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16671, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"ca94d1da85979c910be4c185f97f1f1406534634\", \"got_dawn_revision\": \"fac169113df7dad1b55092ab099eabb25e723719\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"03507dbec6f3f66e26325964b3c3b7befa6bfa6c\", \"got_revision_cp\": \"refs/heads/master@{#841000}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7b2f938954180e8945f776d3e202f6a2bda1aaf9\", \"got_v8_revision\": \"8bf25fa0cda754dd0f8e0f8b7c9f8854eabfe72d\", \"got_v8_revision_cp\": \"refs/heads/8.9.213@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"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\": \"03507dbec6f3f66e26325964b3c3b7befa6bfa6c\", \"swarm_hashes_refs/heads/master(at){#841000}_without_patch\": {\"absl_hardening_tests\": \"38a39e6cba45e660bb5a99c97df8bf6012401c6c\", \"accessibility_unittests\": \"725459a6bf9492dde415c4ea9fdeda1b261c61f2\", \"angle_unittests\": \"cce9d1453820770b7e9422e8ffbc3ccb37748804\", \"app_shell_unittests\": \"9b6e8ceee1357bcd99a193422e8a53c7bdaf73b9\", \"aura_unittests\": \"1ddd7fd036f45ee837b35c4ffdc7f3b91fa00fda\", \"base_unittests\": \"5e676df8449639b6ec2d16b3979a586314cd5831\", \"base_util_unittests\": \"c6ef132b1bbdcd421cffe5e0651cdb1939bafc18\", \"blink_common_unittests\": \"cfc9a5194bc14e3243867e3352301fd5df5838b1\", \"blink_fuzzer_unittests\": \"4d228e1378dbb80815068b784e6f7876f52dc223\", \"blink_heap_unittests\": \"dd5d4cbd47e35c6fa92cb2c693f04639458d07fc\", \"blink_platform_unittests\": \"c6f4638d644b166f22867c3e595eff60964185f8\", \"blink_unittests\": \"6248e58e87fb3763dcb24df9fdc5c0a0167d18d2\", \"boringssl_crypto_tests\": \"a31661495ef160d54491f4037d25332926da9e60\", \"boringssl_ssl_tests\": \"aee64f32ea5d20c7eb47870ca1e0c353da20c654\", \"browser_switcher_bho_unittests\": \"d6bd884e8725cc33245b9696cb2e1a5c5d6e7858\", \"browser_tests\": \"c402cea30ad6d3fa28e3fd76a0ee759d9ae1c7ea\", \"capture_unittests\": \"d5bfb3f035a6b0d408d8189ab77483a97280f20e\", \"cast_unittests\": \"c88cb657178168d3801fb8e445aac66b98265a7e\", \"cc_unittests\": \"20fe20c447c2358a47fa6bc93f8004ab15463559\", \"chrome_app_unittests\": \"58783c66f1f22a101409c1cc986057487dd8b915\", \"chrome_cleaner_unittests\": \"5f878d97bcfda2d468d5f4fc8c5a2cb7802ddf95\", \"chrome_elf_unittests\": \"c6bde13d39219c06455c0d4ef2f56bdad593fdba\", \"chromedriver_unittests\": \"b557ae05e593628bcd049def7e65187c716d41f8\", \"components_browsertests\": \"76f779cf1b30d0428340c92ec861f08f79245a4e\", \"components_unittests\": \"3e13100a06d62f6090b6cd25f16b4425812f86c5\", \"compositor_unittests\": \"cf747a79675b74e7910597498062ede6410ab45b\", \"content_browsertests\": \"437e19446054ffdbf59cc6a3abf099cb61b49512\", \"content_unittests\": \"a09d83df3e9273aeaa51865cc3664fd46e505817\", \"courgette_unittests\": \"c488d504806157ddd3d1fe6b50157cfd1da04526\", \"crashpad_tests\": \"28229f7a48ad733e6330b0fe18842bd54283ff7b\", \"cronet_tests\": \"be300a2675641ee4eb2f79b48579b8b20e773e40\", \"cronet_unittests\": \"dff962ce929e31939c2ef2963387a5ffc694d9a1\", \"crypto_unittests\": \"18e8c4ddd2bfe0093b2e50d08ca55daf59f88acd\", \"delayloads_unittests\": \"4b425caabd184caab3a4603cde51566791ed0116\", \"device_unittests\": \"fc97c0777ab5c1ec3fda0de674300e034c2e9338\", \"display_unittests\": \"bebe528faf43404a7eb71f5a0f3aa5b7d538c262\", \"elevation_service_unittests\": \"e17706a29d1522581c13e5a7e3191005ba665cf5\", \"events_unittests\": \"352da6d2d4fb24411c91b289fd5e3c06c444c160\", \"extensions_browsertests\": \"89c11acc8b032aaae2addc04c526905996aa9214\", \"extensions_unittests\": \"484cd117bad8bb8b8a02496860ef5479d6eb6dcf\", \"filesystem_service_unittests\": \"b85ae7aa67cb88034408f0f1fcc60d0f1c703833\", \"gcm_unit_tests\": \"9d3d31cb1543e7625e83e6a62ab55e2c17b276f2\", \"gcp_unittests\": \"d6a53ace90c56f1a093e13e81c68bbb7004015dc\", \"gfx_unittests\": \"96f4ea8e3f94f01ba77b2ba1b64246425a9748e3\", \"gin_unittests\": \"25ba0fee7cca739933732fd49fffe2a6c4e37e40\", \"google_apis_unittests\": \"c00c63f07417dfc4fcfb81e943b2d6401eefccbe\", \"gpu_unittests\": \"fe42dcf66437baa4ff7111fb36037c8a0133aeb3\", \"gwp_asan_unittests\": \"e6fc52a22fd623787dac3bfc96b6459436766485\", \"headless_browsertests\": \"e4d8e5fae24e2f3905315abec37d5ff7b0903718\", \"headless_unittests\": \"b0ac23eac82e1e4151584277e0f81cdc5ef18423\", \"install_static_unittests\": \"07bb57060fc607e4970c8f63ded71638e36c3af6\", \"installer_util_unittests\": \"5b939f79a107a6224a8b487d52342e2cc106365e\", \"interactive_ui_tests\": \"95ad3e28affdbf45ecd3263fcab69f09aafe960f\", \"ipc_tests\": \"18fc35597e23baa88a44c698a532274c228c7237\", \"jingle_unittests\": \"f0bfc7c35ceeb8cef9dca3bb1e8c476e5ef2c9b6\", \"latency_unittests\": \"1a2f7b1125f0ba52ee9997d46d9415875436da49\", \"libjingle_xmpp_unittests\": \"4c9c7b392dc681cb01945b005459742f8eb39c10\", \"liburlpattern_unittests\": \"90fd42aa1a128bc84c132ef6d946ed6dec1a4fad\", \"media_blink_unittests\": \"0938c18b8a4b26973d500d725f138af78823ded0\", \"media_unittests\": \"28fe247be6237990e1c6864825a36581a4ba1aa3\", \"message_center_unittests\": \"7d23f48dfadbd3c1cbbb1577167512cf3f3257c3\", \"midi_unittests\": \"3aa2cbbac415942468eb96113426adbc1a9b7b28\", \"mojo_core_unittests\": \"55ee9045c275167351ddb0f2dd7028818563e83e\", \"mojo_unittests\": \"6a330adf5ac9706a3e84f680d11ddb5380087559\", \"nacl_loader_unittests\": \"35e51e544f918a235063c0421fd12cac5bb2e907\", \"native_theme_unittests\": \"71c22177634849ab6844dece5769a8042df8de23\", \"net_unittests\": \"77f714d07504f7f0b0d8323354ff12eb8e194ee2\", \"notification_helper_unittests\": \"d362e536d5f3d35abb0b37006818f0862e0918a1\", \"pdf_unittests\": \"23e7d1527fc4397d53538755b33a5647d87a8266\", \"perfetto_unittests\": \"f082152de186334608e114fc3e421c4bac90354f\", \"ppapi_unittests\": \"665f8d9de8afa6b1b19413b1fba5df0d8aaf8820\", \"printing_unittests\": \"593e03fc0b09f1c5fc3af2ac77f4b78a6414ad5a\", \"remoting_unittests\": \"cee6716131b23761655fbba79c58fc59c5b88b30\", \"sbox_integration_tests\": \"7863fa7f37ce957f69194a6a3b8d78972fd99084\", \"sbox_unittests\": \"04a376f2c0598bbec8907fe8b16b0790343b653c\", \"sbox_validation_tests\": \"338a234ab3b6cbcce0313eb51cfdc5b0ed22571f\", \"service_manager_unittests\": \"ae2f22e5ed85070b2fbee68fd68242f93f2af676\", \"services_unittests\": \"f8ac909227a415026fed41c0e7ad28255ca22886\", \"setup_unittests\": \"212d57c86c4b44285782ced7692fe2df2ddcda95\", \"shell_dialogs_unittests\": \"e5f31e3c5c4a2fc7c76fb2836421233b823df9c8\", \"skia_unittests\": \"229d95b2cc57794ff7c5193f2b8f13fa2c0d0215\", \"snapshot_unittests\": \"bff5ae57110fc9a75e387e315b0793645d9d64cf\", \"sql_unittests\": \"6e09365581cc7485478e9720770e4bf535958bf4\", \"storage_unittests\": \"034a77b4a3891452b4066bb2d3e2dc7aa2644f87\", \"sync_integration_tests\": \"40fe329d073acb79345c95646663137169baaf27\", \"traffic_annotation_auditor_unittests\": \"1576f91c16c8651f524eb40a74812076fbb15d2e\", \"ui_base_unittests\": \"1f33acd519505f4ec61ad542ccd7cf5d5794cb3c\", \"ui_touch_selection_unittests\": \"810bc26870f2d7ba5b37deb3cdddb4db1a2eb2bf\", \"unit_tests\": \"1d3785d7ab81cfba953020b5858d6f89387d1d50\", \"updater_tests\": \"e06c47d50aab2a99ddd31e7170f323eb7a8cfc0a\", \"url_unittests\": \"a7eab7fbec91cbe18dab9a3a96d7b2569663a761\", \"views_unittests\": \"478768bee4aa056dd69590457eff9738960288e2\", \"viz_unittests\": \"d4c4a91e5c688c24ff3d6d3c743c22777a723a84\", \"vr_common_unittests\": \"789eeef9ee3b3b489ac8b36825e8667856f732f7\", \"vr_pixeltests\": \"4e60b67dc54b6f6723547de321d49ecb23191b28\", \"weblayer_browsertests\": \"62c04e3b875a9d794439cb2ca37128031ee00a59\", \"weblayer_unittests\": \"c6c6ef849f7892ec37db7cfcfb77aa0f430caa65\", \"wm_unittests\": \"733d96cd6957af3f074bdb52aa95447a074a50d0\", \"wtf_unittests\": \"749713dfb9a26aa69b6ec0eb0c1a85048578de40\", \"zlib_unittests\": \"97b62567050a8a7ef56124d2d86b9ecdfd91573f\", \"zucchini_unittests\": \"7bb7e1683af68d0ecb49e35fb10e55fcb7fdd830\"}}, \"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-7-w73f\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610016312265366",
"status": "COMPLETED",
"status_changed_ts": "1610019867681448",
"tags": [
"build_address:luci.chromium.ci/win-asan/16671",
"builder:win-asan",
"buildset:commit/git/03507dbec6f3f66e26325964b3c3b7befa6bfa6c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03507dbec6f3f66e26325964b3c3b7befa6bfa6c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024199316680552848",
"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/8858769254702941408/+/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:50f544d16013d210",
"user_agent:luci-scheduler"
],
"updated_ts": "1610019867686996",
"url": "https://ci.chromium.org/b/8858769254702941408",
"utcnow_ts": "1610019868848980"
}
}
[11:44:28.874] Invocation finished in 59m21.116281358s with status SUCCEEDED