[07:02:06.228] New invocation is queued and will start shortly
[07:02:07.316] Starting the invocation (attempt 1)
[07:02:07.353] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:02:07.353] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:02:07.353] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019683499929245936",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d15e7e48c72b1fde81b1c8d29ab29a19086887e0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019683499929245936",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d15e7e48c72b1fde81b1c8d29ab29a19086887e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d15e7e48c72b1fde81b1c8d29ab29a19086887e0",
"gitiles_ref:refs/heads/master"
]
}
[07:02:11.542] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614322927741012",
"id": "8854253437679117520",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d15e7e48c72b1fde81b1c8d29ab29a19086887e0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614322930543154",
"tags": [
"build_address:luci.chromium.ci/win-asan/17541",
"builder:win-asan",
"buildset:commit/git/d15e7e48c72b1fde81b1c8d29ab29a19086887e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d15e7e48c72b1fde81b1c8d29ab29a19086887e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019683499929245936",
"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/8854253437679117520/+/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": "1614322930543253",
"url": "https://ci.chromium.org/b/8854253437679117520",
"utcnow_ts": "1614322931509492"
}
}
[07:02:11.542] Task URL: https://ci.chromium.org/b/8854253437679117520
[07:02:11.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:2:0) after 1m0s
[07:02:28.885] Received PubSub notification, asking Buildbucket for the build status
[07:02:29.091] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:11.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:2:0)
[07:03:11.680] Timer tick, asking Buildbucket for the build status
[07:03:11.902] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:11.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:4:0) after 1m0s
[07:04:11.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:4:0)
[07:04:11.967] Timer tick, asking Buildbucket for the build status
[07:04:12.040] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:12.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:5:0) after 1m0s
[07:05:12.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:5:0)
[07:05:12.045] Timer tick, asking Buildbucket for the build status
[07:05:12.195] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:12.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:6:0) after 1m0s
[07:06:12.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:6:0)
[07:06:12.262] Timer tick, asking Buildbucket for the build status
[07:06:12.426] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:12.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:7:0) after 1m0s
[07:07:12.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:7:0)
[07:07:12.447] Timer tick, asking Buildbucket for the build status
[07:07:12.537] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:12.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:8:0) after 1m0s
[07:08:12.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:8:0)
[07:08:12.577] Timer tick, asking Buildbucket for the build status
[07:08:12.672] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:12.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:9:0) after 1m0s
[07:09:12.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:9:0)
[07:09:12.655] Timer tick, asking Buildbucket for the build status
[07:09:12.836] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:12.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:10:0) after 1m0s
[07:10:12.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:10:0)
[07:10:12.905] Timer tick, asking Buildbucket for the build status
[07:10:13.016] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:13.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:11:0) after 1m0s
[07:11:13.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:11:0)
[07:11:13.061] Timer tick, asking Buildbucket for the build status
[07:11:13.138] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:13.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:12:0) after 1m0s
[07:12:13.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:12:0)
[07:12:13.157] Timer tick, asking Buildbucket for the build status
[07:12:13.274] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:13.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:13:0) after 1m0s
[07:13:13.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:13:0)
[07:13:13.274] Timer tick, asking Buildbucket for the build status
[07:13:13.363] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:13.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:14:0) after 1m0s
[07:14:13.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:14:0)
[07:14:13.377] Timer tick, asking Buildbucket for the build status
[07:14:13.450] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:13.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:15:0) after 1m0s
[07:15:13.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:15:0)
[07:15:13.489] Timer tick, asking Buildbucket for the build status
[07:15:13.923] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:13.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:16:0) after 1m0s
[07:16:13.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:16:0)
[07:16:13.947] Timer tick, asking Buildbucket for the build status
[07:16:14.168] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:14.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:17:0) after 1m0s
[07:17:14.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:17:0)
[07:17:14.211] Timer tick, asking Buildbucket for the build status
[07:17:14.476] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:14.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:18:0) after 1m0s
[07:18:14.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:18:0)
[07:18:14.505] Timer tick, asking Buildbucket for the build status
[07:18:14.608] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:14.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:19:0) after 1m0s
[07:19:14.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:19:0)
[07:19:14.624] Timer tick, asking Buildbucket for the build status
[07:19:14.726] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:14.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:20:0) after 1m0s
[07:20:14.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:20:0)
[07:20:14.818] Timer tick, asking Buildbucket for the build status
[07:20:14.937] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:14.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:21:0) after 1m0s
[07:21:14.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:21:0)
[07:21:14.995] Timer tick, asking Buildbucket for the build status
[07:21:15.542] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:15.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:22:0) after 1m0s
[07:22:15.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:22:0)
[07:22:15.589] Timer tick, asking Buildbucket for the build status
[07:22:15.663] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:15.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:23:0) after 1m0s
[07:23:15.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:23:0)
[07:23:15.728] Timer tick, asking Buildbucket for the build status
[07:23:15.857] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:15.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:24:0) after 1m0s
[07:24:15.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:24:0)
[07:24:15.854] Timer tick, asking Buildbucket for the build status
[07:24:16.005] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:16.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:25:0) after 1m0s
[07:25:16.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:25:0)
[07:25:16.099] Timer tick, asking Buildbucket for the build status
[07:25:16.255] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:16.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:26:0) after 1m0s
[07:26:16.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:26:0)
[07:26:16.287] Timer tick, asking Buildbucket for the build status
[07:26:17.982] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:17.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:27:0) after 1m0s
[07:27:18.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:27:0)
[07:27:18.043] Timer tick, asking Buildbucket for the build status
[07:27:19.725] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:19.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:28:0) after 1m0s
[07:28:19.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:28:0)
[07:28:19.791] Timer tick, asking Buildbucket for the build status
[07:28:19.922] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:19.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:29:0) after 1m0s
[07:29:20.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:29:0)
[07:29:20.053] Timer tick, asking Buildbucket for the build status
[07:29:20.178] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:20.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:30:0) after 1m0s
[07:30:20.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:30:0)
[07:30:20.224] Timer tick, asking Buildbucket for the build status
[07:30:20.556] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:20.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:31:0) after 1m0s
[07:31:20.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:31:0)
[07:31:20.627] Timer tick, asking Buildbucket for the build status
[07:31:20.700] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:20.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:32:0) after 1m0s
[07:32:20.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:32:0)
[07:32:20.769] Timer tick, asking Buildbucket for the build status
[07:32:20.857] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:20.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:33:0) after 1m0s
[07:33:20.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:33:0)
[07:33:20.857] Timer tick, asking Buildbucket for the build status
[07:33:21.229] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:21.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:34:0) after 1m0s
[07:34:21.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:34:0)
[07:34:21.230] Timer tick, asking Buildbucket for the build status
[07:34:21.646] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:21.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:35:0) after 1m0s
[07:35:21.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:35:0)
[07:35:21.688] Timer tick, asking Buildbucket for the build status
[07:35:21.796] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:21.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:36:0) after 1m0s
[07:36:21.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:36:0)
[07:36:21.816] Timer tick, asking Buildbucket for the build status
[07:36:22.020] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:22.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:37:0) after 1m0s
[07:37:22.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:37:0)
[07:37:22.112] Timer tick, asking Buildbucket for the build status
[07:37:22.195] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:22.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:38:0) after 1m0s
[07:38:22.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:38:0)
[07:38:22.284] Timer tick, asking Buildbucket for the build status
[07:38:22.544] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:22.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:39:0) after 1m0s
[07:39:22.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:39:0)
[07:39:22.560] Timer tick, asking Buildbucket for the build status
[07:39:22.646] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:22.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:40:0) after 1m0s
[07:40:22.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:40:0)
[07:40:22.627] Timer tick, asking Buildbucket for the build status
[07:40:22.705] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:22.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:41:0) after 1m0s
[07:41:22.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:41:0)
[07:41:22.741] Timer tick, asking Buildbucket for the build status
[07:41:22.910] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:22.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:42:0) after 1m0s
[07:42:22.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:42:0)
[07:42:22.905] Timer tick, asking Buildbucket for the build status
[07:42:22.988] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:22.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:43:0) after 1m0s
[07:43:23.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:43:0)
[07:43:23.019] Timer tick, asking Buildbucket for the build status
[07:43:23.307] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:23.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:44:0) after 1m0s
[07:44:23.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:44:0)
[07:44:23.329] Timer tick, asking Buildbucket for the build status
[07:44:24.331] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:24.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:45:0) after 1m0s
[07:45:24.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:45:0)
[07:45:24.350] Timer tick, asking Buildbucket for the build status
[07:45:24.607] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:24.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:46:0) after 1m0s
[07:46:24.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:46:0)
[07:46:24.609] Timer tick, asking Buildbucket for the build status
[07:46:24.703] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:24.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:47:0) after 1m0s
[07:47:24.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:47:0)
[07:47:24.753] Timer tick, asking Buildbucket for the build status
[07:47:24.969] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:24.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:48:0) after 1m0s
[07:48:25.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:48:0)
[07:48:25.073] Timer tick, asking Buildbucket for the build status
[07:48:25.205] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:25.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:49:0) after 1m0s
[07:49:25.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:49:0)
[07:49:25.243] Timer tick, asking Buildbucket for the build status
[07:49:25.411] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:25.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:50:0) after 1m0s
[07:50:25.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:50:0)
[07:50:25.492] Timer tick, asking Buildbucket for the build status
[07:50:25.640] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:25.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:51:0) after 1m0s
[07:51:25.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:51:0)
[07:51:25.680] Timer tick, asking Buildbucket for the build status
[07:51:25.792] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:25.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:52:0) after 1m0s
[07:52:25.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:52:0)
[07:52:25.812] Timer tick, asking Buildbucket for the build status
[07:52:25.898] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:25.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:53:0) after 1m0s
[07:53:25.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:53:0)
[07:53:25.922] Timer tick, asking Buildbucket for the build status
[07:53:26.008] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:26.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:54:0) after 1m0s
[07:54:26.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:54:0)
[07:54:26.106] Timer tick, asking Buildbucket for the build status
[07:54:26.197] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:26.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:55:0) after 1m0s
[07:55:26.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:55:0)
[07:55:26.214] Timer tick, asking Buildbucket for the build status
[07:55:26.598] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:26.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:56:0) after 1m0s
[07:56:26.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:56:0)
[07:56:26.613] Timer tick, asking Buildbucket for the build status
[07:56:26.696] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:26.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:57:0) after 1m0s
[07:57:26.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:57:0)
[07:57:26.668] Timer tick, asking Buildbucket for the build status
[07:57:26.740] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:26.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:58:0) after 1m0s
[07:58:26.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:58:0)
[07:58:26.745] Timer tick, asking Buildbucket for the build status
[07:58:26.897] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:26.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:59:0) after 1m0s
[07:59:26.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:59:0)
[07:59:26.915] Timer tick, asking Buildbucket for the build status
[07:59:26.998] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:26.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:60:0) after 1m0s
[08:00:27.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:60:0)
[08:00:27.016] Timer tick, asking Buildbucket for the build status
[08:00:27.110] Build 8854253437679117520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:27.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019683499929245936:61:0) after 1m0s
[08:00:37.277] Received PubSub notification, asking Buildbucket for the build status
[08:00:37.352] Build 8854253437679117520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:00:37.352] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614326436639971",
"created_by": "project:chromium",
"created_ts": "1614322927741012",
"id": "8854253437679117520",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d15e7e48c72b1fde81b1c8d29ab29a19086887e0\"}}",
"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\": \"d15e7e48c72b1fde81b1c8d29ab29a19086887e0\", \"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\": 17541, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"77637f2dd222530c721018185a90e24a51e7a745\", \"got_dawn_revision\": \"87649ff09dee357f0412834527e4174b4989358f\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"d15e7e48c72b1fde81b1c8d29ab29a19086887e0\", \"got_revision_cp\": \"refs/heads/master@{#858048}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"bb52bdf09516ca548c4aff50526eda561f239bc0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33341}\", \"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\": \"d15e7e48c72b1fde81b1c8d29ab29a19086887e0\", \"swarm_hashes_refs/heads/master(at){#858048}_without_patch\": {\"absl_hardening_tests\": \"81450d4a59cf9e11196375f7c6c8c515700de2b8\", \"accessibility_unittests\": \"ab547df387a235abce91a8b7bc8e761dd648b686\", \"angle_unittests\": \"c95fc065ac8df3967b1c14cf8c25f6b04e407a3f\", \"app_shell_unittests\": \"c38f9639e6916083a31ccc969e65e8528add74b3\", \"aura_unittests\": \"88b8fbbb2c33488f674ea4859fa61939e55a9504\", \"base_unittests\": \"906740c15f10ed80bf925139d3e77ce4f87a7dd4\", \"base_util_unittests\": \"183ea2f869a5bacacb92e1d4efa996d21f62c1b5\", \"blink_common_unittests\": \"4bd61953f257f824ce91b42272522ee32c700cec\", \"blink_fuzzer_unittests\": \"9739bf3db127ce8c72f09f4726679091cbbac452\", \"blink_heap_unittests\": \"5d53840e2288c16f726cd2ec6bb4f13f9bd3eea1\", \"blink_platform_unittests\": \"e430728845ffaff618245c3afa5bf827cc72af17\", \"blink_unittests\": \"38a756c9d34d0d1113c548f56e809d22d13fde28\", \"boringssl_crypto_tests\": \"674ab1de077ecbf293466d4cd9be4a9ce028af5c\", \"boringssl_ssl_tests\": \"9c2e930ff8b74248dbb8f2c686bde225d63fe1b2\", \"browser_switcher_bho_unittests\": \"e6bf1ef4ede47b01747b72e946ac79e27de81f8c\", \"browser_tests\": \"24021d7144977fb67dfac63e2ab126b36595afa1\", \"capture_unittests\": \"940230cb467164e2a41fc6f3c2d12c0a3d4f2c87\", \"cast_unittests\": \"b9999938415f3c79dbb2c0b5a2512e2ed3eba6be\", \"cc_unittests\": \"199beee19cb855d4e2cc0741df2d9496740ea7c1\", \"chrome_app_unittests\": \"ef15cd58137bda6b12d9cc9617de854b666300a4\", \"chrome_cleaner_unittests\": \"192bf6440db48299eca9bbd00194251661d4a6c5\", \"chrome_elf_unittests\": \"fde0d491a5cbf45d0ff71d2bd1ff78c7dd65332e\", \"chromedriver_unittests\": \"01c0e2a85fda208a71cc49122cab8e4c8b727df4\", \"color_unittests\": \"245e4cdae4a28caa80791d94660b7ef44a8b2d90\", \"components_browsertests\": \"08bb122dd2fb10760a6df63fcc5883cbbe52f60d\", \"components_unittests\": \"1e236fb386cbddb3ee6dced00bab2943a404d0b1\", \"compositor_unittests\": \"c393311a7b7b418c2ce5f514da4a07a9079a9650\", \"content_browsertests\": \"e706f4cc73583a66f9ec3769d1e59cccea7fe179\", \"content_unittests\": \"763e030138538608bb9b67f2ef880e9eeaf6039d\", \"courgette_unittests\": \"c1b9625b0b838aeee98d56a7a29d6730a773af97\", \"crashpad_tests\": \"a2c135a197ccc896cab4bffb7654587287aa5ef8\", \"cronet_tests\": \"ff59d422d179c1d2650d012b92bfcd278768dfdb\", \"cronet_unittests\": \"baf01e0117e11e09a50d4ea6a7632c68f190ddb6\", \"crypto_unittests\": \"810f93774b6de6e1c72b90ecf67e53fb0ab2379b\", \"delayloads_unittests\": \"eeeb80cbf552c9d37cb594600e9eb76c31aa8498\", \"device_unittests\": \"1079311fc694807c379d79b30fc30dfc37521369\", \"display_unittests\": \"fd5a51f998be0f2b7e435c60fdc624a13cd3fa81\", \"elevation_service_unittests\": \"87c29b28f7055b19d4463aa1d3fbe561761aebb4\", \"events_unittests\": \"a4b0773ae494f33d0b366614b7f03a190eeb2544\", \"extensions_browsertests\": \"9b2a485e1d42ddd8ea83fce7faca2044fd284031\", \"extensions_unittests\": \"7a1e6880c63a8ed9b795dc65ac1e075ae75befcf\", \"filesystem_service_unittests\": \"ef919b359d66c916899656ab74a633054401a4ed\", \"gcm_unit_tests\": \"260757e09e943ee007b486a82f74c43ca3a60318\", \"gcp_unittests\": \"280a20618409b3276a3d1978c0e59836f88f5dde\", \"gfx_unittests\": \"fc2a933e187d28960dfe3d4eb764b084384cc331\", \"gin_unittests\": \"77a9064652b5990f17c87d035091a2eba8cc212d\", \"google_apis_unittests\": \"e5e248ee0e337d524d75c5ad7c77b890f82024c2\", \"gpu_unittests\": \"8ae3231db105434d61bcfca0d684af5f125111db\", \"gwp_asan_unittests\": \"fba4bda01bdc4c369ac1aaae1a3b1814c13cf30e\", \"headless_browsertests\": \"75043c8496f16f07cc80c00178d433cf5a3f59aa\", \"headless_unittests\": \"dc3e495ce110918b6904bfa7e4f4f7024d2c117b\", \"install_static_unittests\": \"f0102e26b06ea141fea09c403002a08276d314e4\", \"installer_util_unittests\": \"4ce232af2685282d4a49441c22f484f6a5f79938\", \"interactive_ui_tests\": \"714565816ac5eaf2a4663b1ae5f936d888cf6e42\", \"ipc_tests\": \"eb94de0b3b55b0584b8cbf623a1cfc1abf42beee\", \"jingle_unittests\": \"326a980bd1dfe279ffecd8954e6722160aa097d8\", \"latency_unittests\": \"b5fb73f4991241d71d0cb525844eba9183ca4610\", \"libjingle_xmpp_unittests\": \"fab0019103a07408bb35fb9b0bbb9fe7777a5293\", \"liburlpattern_unittests\": \"cd4b5716a0d5f5d2c2b19bf957292b58915f9a62\", \"media_blink_unittests\": \"5236c179701167c42cc183a0e87710ebaa6e5fad\", \"media_unittests\": \"bc0921d5a8ff63b539e0dea949b8dd608a9522b9\", \"message_center_unittests\": \"e6dc00d435a547453d443e864fc1332fc86e5cf7\", \"midi_unittests\": \"d3980e8000f8ffffdd153b72ee7ce77c3d5021dc\", \"mojo_core_unittests\": \"6ed81928f14e680ed1c6c422dafd1b632f0d3423\", \"mojo_unittests\": \"e9e53a6df30f43a670023ca77544f859b11bf22e\", \"nacl_loader_unittests\": \"e39cf22b2822b6b14437f850a242e10f1fa60624\", \"native_theme_unittests\": \"23acd2da7a412aadc92ad9e5d426fcdfbe78e39c\", \"net_unittests\": \"e9809cb9f47a8fcd519c277a6dbb5e1560c73110\", \"notification_helper_unittests\": \"0ec923c9603e70fb3b16c4153a5207083d86d9dc\", \"pdf_unittests\": \"84b2198c12f193cce684c2a9d8937aad3aa79e27\", \"perfetto_unittests\": \"212f88040123494d46da09f0d65a3d4929569640\", \"ppapi_unittests\": \"cfc9648c521eb87956abee879b3b98fd1d4a58d0\", \"printing_unittests\": \"69bdb6900ab498d765fdf011a9cbc4ffb2019591\", \"remoting_unittests\": \"6292c9c3ebe93ce16d180fccfffe9b5a1ec6991b\", \"sbox_integration_tests\": \"761e3f270e3bf207779a054b7e7938690a63bdc2\", \"sbox_unittests\": \"9072afd83d4383c1dd069c4ce494010bf63a438f\", \"sbox_validation_tests\": \"764495ee09df7ee67b33bec8f0180e5bb2aed46c\", \"service_manager_unittests\": \"4ea426bb68bb2b062f98727c66ad1ef3a32963bc\", \"services_unittests\": \"5b86178c105b939bcb7edaa992d23f830c8ff0a2\", \"setup_unittests\": \"24806e4445d388c39b5ef53d00595c3e53fb7a9d\", \"shell_dialogs_unittests\": \"e8d849c32636f8e3edfdc0563b8fd4a724c240ae\", \"skia_unittests\": \"61cb592e305a80feb3bdc79cade87b22b8e6d2e8\", \"snapshot_unittests\": \"0600f5f2209c1e5e2a00b8b79bed66dc6e06b5d6\", \"sql_unittests\": \"157d0c83a4e242868da944f17767efe96ba9c262\", \"storage_unittests\": \"62179fa0c4031e56b0dc4aaf008a4a3d6c9b700f\", \"sync_integration_tests\": \"86cd64fc2b24d1ecd40d7c5984daf6c6e79fe47f\", \"traffic_annotation_auditor_unittests\": \"5e7d772d18987c5a49b9aa385431156471be1585\", \"ui_base_unittests\": \"9d1634f3d9f7f851f48e308ff88e554a0944b8c9\", \"ui_touch_selection_unittests\": \"0d8f726ba9f114247b0a8b950d0b460ecebc6666\", \"unit_tests\": \"7f6b3e3f36b6bf66326abb3db6282119283a7795\", \"updater_tests\": \"1e837d00d53cf5d68b70a5bc35b7a6f25bfdc3d9\", \"url_unittests\": \"e19d33b327f1a7a657f7e5d58f2102d0a30a701f\", \"views_unittests\": \"6d39390b38f83d8087801ed1d75b72c48ae24e62\", \"viz_unittests\": \"cf71ce6a357d5c07f1eb66c5938aef17e193c794\", \"vr_common_unittests\": \"a443731ff4d24ef8500b28d88b4964092fc4c4cf\", \"vr_pixeltests\": \"906936acddb423c1380f0a4b10635183c9c5d81f\", \"weblayer_browsertests\": \"b8ea5f191f132f4636e4b774cbe3b75da3803c89\", \"weblayer_unittests\": \"e3007af86786e70785fd74cd6bfdbf4883bbac02\", \"wm_unittests\": \"2fbdc441fcec242d1af3515a29f14efd4d7b6c10\", \"wtf_unittests\": \"59f90692287066a7904ccadb525d8b315c30e52a\", \"zlib_unittests\": \"1e2790495518a78946c0bf8e63ab5d56caea82bd\", \"zucchini_unittests\": \"9d9838d7ad18fa13984eaf20affc280c4ed32692\"}}, \"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\": [\"5712-339372b\"], \"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": "1614322948231392",
"status": "COMPLETED",
"status_changed_ts": "1614326436879934",
"tags": [
"build_address:luci.chromium.ci/win-asan/17541",
"builder:win-asan",
"buildset:commit/git/d15e7e48c72b1fde81b1c8d29ab29a19086887e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d15e7e48c72b1fde81b1c8d29ab29a19086887e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019683499929245936",
"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/8854253437679117520/+/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:51f5f6dbd812a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1614326436883933",
"url": "https://ci.chromium.org/b/8854253437679117520",
"utcnow_ts": "1614326437344064"
}
}
[08:00:37.352] Invocation finished in 58m31.140134098s with status SUCCEEDED