[11:10:14.501] New invocation is queued and will start shortly
[11:10:15.567] Starting the invocation (attempt 1)
[11:10:15.609] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:10:15.609] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:10:15.610] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030358330482128848",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c460c9b034fa86b99d46c9326748fc5b9983dd99\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030358330482128848",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c460c9b034fa86b99d46c9326748fc5b9983dd99",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c460c9b034fa86b99d46c9326748fc5b9983dd99",
"gitiles_ref:refs/heads/master"
]
}
[11:10:16.661] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1604142615725864",
"id": "8864928268531300448",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c460c9b034fa86b99d46c9326748fc5b9983dd99\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604142616416849",
"tags": [
"build_address:luci.chromium.ci/win-asan/15380",
"builder:win-asan",
"buildset:commit/git/c460c9b034fa86b99d46c9326748fc5b9983dd99",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c460c9b034fa86b99d46c9326748fc5b9983dd99",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030358330482128848",
"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/8864928268531300448/+/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": "1604142616417322",
"url": "https://ci.chromium.org/b/8864928268531300448",
"utcnow_ts": "1604142616651645"
}
}
[11:10:16.661] Task URL: https://ci.chromium.org/b/8864928268531300448
[11:10:16.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:2:0) after 1m0s
[11:11:16.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:2:0)
[11:11:16.657] Timer tick, asking Buildbucket for the build status
[11:11:16.788] Build 8864928268531300448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:11:16.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:3:0) after 1m0s
[11:12:16.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:3:0)
[11:12:16.796] Timer tick, asking Buildbucket for the build status
[11:12:16.954] Build 8864928268531300448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:12:16.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:4:0) after 1m0s
[11:13:17.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:4:0)
[11:13:17.008] Timer tick, asking Buildbucket for the build status
[11:13:17.162] Build 8864928268531300448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:13:17.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:5:0) after 1m0s
[11:14:17.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:5:0)
[11:14:17.189] Timer tick, asking Buildbucket for the build status
[11:14:17.375] Build 8864928268531300448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:14:17.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:6:0) after 1m0s
[11:14:18.572] Received PubSub notification, asking Buildbucket for the build status
[11:14:18.661] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:17.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:6:0)
[11:15:17.408] Timer tick, asking Buildbucket for the build status
[11:15:17.518] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:17.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:8:0) after 1m0s
[11:16:17.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:8:0)
[11:16:17.570] Timer tick, asking Buildbucket for the build status
[11:16:17.703] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:17.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:9:0) after 1m0s
[11:17:17.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:9:0)
[11:17:17.829] Timer tick, asking Buildbucket for the build status
[11:17:18.175] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:18.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:10:0) after 1m0s
[11:18:18.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:10:0)
[11:18:18.201] Timer tick, asking Buildbucket for the build status
[11:18:18.397] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:18.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:11:0) after 1m0s
[11:19:18.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:11:0)
[11:19:18.461] Timer tick, asking Buildbucket for the build status
[11:19:18.621] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:18.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:12:0) after 1m0s
[11:20:18.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:12:0)
[11:20:18.642] Timer tick, asking Buildbucket for the build status
[11:20:19.263] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:19.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:13:0) after 1m0s
[11:21:19.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:13:0)
[11:21:19.328] Timer tick, asking Buildbucket for the build status
[11:21:19.391] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:19.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:14:0) after 1m0s
[11:22:19.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:14:0)
[11:22:19.414] Timer tick, asking Buildbucket for the build status
[11:22:19.586] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:19.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:15:0) after 1m0s
[11:23:19.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:15:0)
[11:23:19.622] Timer tick, asking Buildbucket for the build status
[11:23:19.877] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:19.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:16:0) after 1m0s
[11:24:19.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:16:0)
[11:24:19.880] Timer tick, asking Buildbucket for the build status
[11:24:20.056] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:20.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:17:0) after 1m0s
[11:25:20.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:17:0)
[11:25:20.078] Timer tick, asking Buildbucket for the build status
[11:25:20.186] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:20.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:18:0) after 1m0s
[11:26:20.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:18:0)
[11:26:20.224] Timer tick, asking Buildbucket for the build status
[11:26:20.328] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:20.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:19:0) after 1m0s
[11:27:20.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:19:0)
[11:27:20.418] Timer tick, asking Buildbucket for the build status
[11:27:20.646] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:20.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:20:0) after 1m0s
[11:28:20.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:20:0)
[11:28:20.765] Timer tick, asking Buildbucket for the build status
[11:28:21.188] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:21.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:21:0) after 1m0s
[11:29:21.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:21:0)
[11:29:21.272] Timer tick, asking Buildbucket for the build status
[11:29:21.341] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:21.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:22:0) after 1m0s
[11:30:21.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:22:0)
[11:30:21.456] Timer tick, asking Buildbucket for the build status
[11:30:21.569] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:21.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:23:0) after 1m0s
[11:31:21.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:23:0)
[11:31:21.631] Timer tick, asking Buildbucket for the build status
[11:31:21.726] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:21.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:24:0) after 1m0s
[11:32:21.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:24:0)
[11:32:21.875] Timer tick, asking Buildbucket for the build status
[11:32:21.940] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:21.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:25:0) after 1m0s
[11:33:21.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:25:0)
[11:33:21.947] Timer tick, asking Buildbucket for the build status
[11:33:22.014] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:22.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:26:0) after 1m0s
[11:34:22.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:26:0)
[11:34:22.099] Timer tick, asking Buildbucket for the build status
[11:34:22.191] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:22.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:27:0) after 1m0s
[11:35:22.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:27:0)
[11:35:22.199] Timer tick, asking Buildbucket for the build status
[11:35:22.328] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:22.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:28:0) after 1m0s
[11:36:22.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:28:0)
[11:36:22.403] Timer tick, asking Buildbucket for the build status
[11:36:22.463] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:22.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:29:0) after 1m0s
[11:37:22.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:29:0)
[11:37:22.519] Timer tick, asking Buildbucket for the build status
[11:37:22.586] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:22.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:30:0) after 1m0s
[11:38:22.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:30:0)
[11:38:22.699] Timer tick, asking Buildbucket for the build status
[11:38:22.775] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:22.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:31:0) after 1m0s
[11:39:22.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:31:0)
[11:39:22.803] Timer tick, asking Buildbucket for the build status
[11:39:22.869] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:22.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:32:0) after 1m0s
[11:40:22.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:32:0)
[11:40:22.929] Timer tick, asking Buildbucket for the build status
[11:40:23.058] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:23.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:33:0) after 1m0s
[11:41:23.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:33:0)
[11:41:23.098] Timer tick, asking Buildbucket for the build status
[11:41:23.176] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:23.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:34:0) after 1m0s
[11:42:23.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:34:0)
[11:42:23.199] Timer tick, asking Buildbucket for the build status
[11:42:24.092] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:24.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:35:0) after 1m0s
[11:43:24.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:35:0)
[11:43:24.131] Timer tick, asking Buildbucket for the build status
[11:43:24.246] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:24.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:36:0) after 1m0s
[11:44:24.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:36:0)
[11:44:24.356] Timer tick, asking Buildbucket for the build status
[11:44:24.433] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:24.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:37:0) after 1m0s
[11:45:24.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:37:0)
[11:45:24.444] Timer tick, asking Buildbucket for the build status
[11:45:24.564] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:24.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:38:0) after 1m0s
[11:46:24.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:38:0)
[11:46:24.621] Timer tick, asking Buildbucket for the build status
[11:46:24.697] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:24.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:39:0) after 1m0s
[11:47:24.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:39:0)
[11:47:24.851] Timer tick, asking Buildbucket for the build status
[11:47:25.274] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:25.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:40:0) after 1m0s
[11:48:25.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:40:0)
[11:48:25.252] Timer tick, asking Buildbucket for the build status
[11:48:25.322] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:25.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:41:0) after 1m0s
[11:49:25.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:41:0)
[11:49:25.456] Timer tick, asking Buildbucket for the build status
[11:49:25.522] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:25.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:42:0) after 1m0s
[11:50:25.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:42:0)
[11:50:25.659] Timer tick, asking Buildbucket for the build status
[11:50:25.794] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:25.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:43:0) after 1m0s
[11:51:25.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:43:0)
[11:51:25.866] Timer tick, asking Buildbucket for the build status
[11:51:25.932] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:25.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:44:0) after 1m0s
[11:52:26.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:44:0)
[11:52:26.015] Timer tick, asking Buildbucket for the build status
[11:52:26.410] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:26.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:45:0) after 1m0s
[11:53:26.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:45:0)
[11:53:26.403] Timer tick, asking Buildbucket for the build status
[11:53:26.522] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:26.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:46:0) after 1m0s
[11:54:26.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:46:0)
[11:54:26.647] Timer tick, asking Buildbucket for the build status
[11:54:26.715] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:26.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:47:0) after 1m0s
[11:55:26.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:47:0)
[11:55:26.941] Timer tick, asking Buildbucket for the build status
[11:55:27.203] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:27.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:48:0) after 1m0s
[11:56:27.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:48:0)
[11:56:27.277] Timer tick, asking Buildbucket for the build status
[11:56:27.495] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:27.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:49:0) after 1m0s
[11:57:27.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:49:0)
[11:57:27.607] Timer tick, asking Buildbucket for the build status
[11:57:27.811] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:27.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:50:0) after 1m0s
[11:58:27.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:50:0)
[11:58:27.932] Timer tick, asking Buildbucket for the build status
[11:58:28.077] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:28.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:51:0) after 1m0s
[11:59:28.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:51:0)
[11:59:28.098] Timer tick, asking Buildbucket for the build status
[11:59:28.481] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:28.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:52:0) after 1m0s
[12:00:28.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:52:0)
[12:00:28.558] Timer tick, asking Buildbucket for the build status
[12:00:28.712] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:28.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:53:0) after 1m0s
[12:01:28.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:53:0)
[12:01:28.755] Timer tick, asking Buildbucket for the build status
[12:01:28.907] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:28.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:54:0) after 1m0s
[12:02:29.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:54:0)
[12:02:29.006] Timer tick, asking Buildbucket for the build status
[12:02:29.183] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:29.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:55:0) after 1m0s
[12:03:29.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:55:0)
[12:03:29.211] Timer tick, asking Buildbucket for the build status
[12:03:29.950] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:29.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:56:0) after 1m0s
[12:04:29.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:56:0)
[12:04:29.946] Timer tick, asking Buildbucket for the build status
[12:04:30.103] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:30.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:57:0) after 1m0s
[12:05:30.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:57:0)
[12:05:30.220] Timer tick, asking Buildbucket for the build status
[12:05:30.633] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:30.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:58:0) after 1m0s
[12:06:30.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:58:0)
[12:06:30.746] Timer tick, asking Buildbucket for the build status
[12:06:30.923] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:30.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:59:0) after 1m0s
[12:07:30.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:59:0)
[12:07:30.952] Timer tick, asking Buildbucket for the build status
[12:07:31.079] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:31.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:60:0) after 1m0s
[12:08:31.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:60:0)
[12:08:31.103] Timer tick, asking Buildbucket for the build status
[12:08:31.262] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:31.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:61:0) after 1m0s
[12:09:31.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:61:0)
[12:09:31.293] Timer tick, asking Buildbucket for the build status
[12:09:31.433] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:31.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:62:0) after 1m0s
[12:10:31.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:62:0)
[12:10:31.477] Timer tick, asking Buildbucket for the build status
[12:10:32.162] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:32.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:63:0) after 1m0s
[12:11:32.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:63:0)
[12:11:32.241] Timer tick, asking Buildbucket for the build status
[12:11:32.340] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:32.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:64:0) after 1m0s
[12:12:32.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:64:0)
[12:12:32.357] Timer tick, asking Buildbucket for the build status
[12:12:32.422] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:32.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:65:0) after 1m0s
[12:13:32.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:65:0)
[12:13:32.495] Timer tick, asking Buildbucket for the build status
[12:13:32.796] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:32.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:66:0) after 1m0s
[12:14:32.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:66:0)
[12:14:32.903] Timer tick, asking Buildbucket for the build status
[12:14:33.138] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:33.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:67:0) after 1m0s
[12:15:33.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:67:0)
[12:15:33.215] Timer tick, asking Buildbucket for the build status
[12:15:33.418] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:33.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:68:0) after 1m0s
[12:16:33.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:68:0)
[12:16:33.441] Timer tick, asking Buildbucket for the build status
[12:16:33.734] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:33.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:69:0) after 1m0s
[12:17:33.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:69:0)
[12:17:33.778] Timer tick, asking Buildbucket for the build status
[12:17:33.851] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:33.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:70:0) after 1m0s
[12:18:33.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:70:0)
[12:18:33.968] Timer tick, asking Buildbucket for the build status
[12:18:34.132] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:34.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:71:0) after 1m0s
[12:19:34.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:71:0)
[12:19:34.205] Timer tick, asking Buildbucket for the build status
[12:19:34.352] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:34.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:72:0) after 1m0s
[12:20:34.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:72:0)
[12:20:34.363] Timer tick, asking Buildbucket for the build status
[12:20:34.505] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:34.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:73:0) after 1m0s
[12:21:34.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:73:0)
[12:21:34.505] Timer tick, asking Buildbucket for the build status
[12:21:34.865] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:34.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:74:0) after 1m0s
[12:22:34.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:74:0)
[12:22:34.889] Timer tick, asking Buildbucket for the build status
[12:22:35.228] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:35.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:75:0) after 1m0s
[12:23:35.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:75:0)
[12:23:35.303] Timer tick, asking Buildbucket for the build status
[12:23:35.482] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:35.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:76:0) after 1m0s
[12:24:35.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:76:0)
[12:24:35.531] Timer tick, asking Buildbucket for the build status
[12:24:35.652] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:35.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:77:0) after 1m0s
[12:25:35.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:77:0)
[12:25:35.677] Timer tick, asking Buildbucket for the build status
[12:25:36.260] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:36.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:78:0) after 1m0s
[12:26:36.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:78:0)
[12:26:36.337] Timer tick, asking Buildbucket for the build status
[12:26:36.512] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:36.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:79:0) after 1m0s
[12:27:36.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:79:0)
[12:27:36.567] Timer tick, asking Buildbucket for the build status
[12:27:36.684] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:36.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:80:0) after 1m0s
[12:28:36.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:80:0)
[12:28:36.708] Timer tick, asking Buildbucket for the build status
[12:28:36.840] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:36.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:81:0) after 1m0s
[12:29:36.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:81:0)
[12:29:36.915] Timer tick, asking Buildbucket for the build status
[12:29:37.041] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:37.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:82:0) after 1m0s
[12:30:37.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:82:0)
[12:30:37.070] Timer tick, asking Buildbucket for the build status
[12:30:37.387] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:37.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:83:0) after 1m0s
[12:31:37.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:83:0)
[12:31:37.419] Timer tick, asking Buildbucket for the build status
[12:31:37.488] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:37.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:84:0) after 1m0s
[12:32:37.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:84:0)
[12:32:37.511] Timer tick, asking Buildbucket for the build status
[12:32:37.594] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:37.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:85:0) after 1m0s
[12:33:37.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:85:0)
[12:33:37.624] Timer tick, asking Buildbucket for the build status
[12:33:37.693] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:37.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:86:0) after 1m0s
[12:34:37.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:86:0)
[12:34:37.720] Timer tick, asking Buildbucket for the build status
[12:34:37.807] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:37.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:87:0) after 1m0s
[12:35:37.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:87:0)
[12:35:37.833] Timer tick, asking Buildbucket for the build status
[12:35:37.970] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:37.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:88:0) after 1m0s
[12:36:38.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:88:0)
[12:36:38.045] Timer tick, asking Buildbucket for the build status
[12:36:38.123] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:38.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:89:0) after 1m0s
[12:37:38.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:89:0)
[12:37:38.292] Timer tick, asking Buildbucket for the build status
[12:37:38.401] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:38.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:90:0) after 1m0s
[12:38:38.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:90:0)
[12:38:38.618] Timer tick, asking Buildbucket for the build status
[12:38:38.973] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:38.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:91:0) after 1m0s
[12:39:39.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:91:0)
[12:39:39.107] Timer tick, asking Buildbucket for the build status
[12:39:39.193] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:39.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:92:0) after 1m0s
[12:40:39.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:92:0)
[12:40:39.281] Timer tick, asking Buildbucket for the build status
[12:40:39.493] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:39.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:93:0) after 1m0s
[12:41:39.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:93:0)
[12:41:39.535] Timer tick, asking Buildbucket for the build status
[12:41:39.616] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:39.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:94:0) after 1m0s
[12:42:39.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:94:0)
[12:42:39.740] Timer tick, asking Buildbucket for the build status
[12:42:40.047] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:40.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:95:0) after 1m0s
[12:43:40.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:95:0)
[12:43:40.103] Timer tick, asking Buildbucket for the build status
[12:43:40.492] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:40.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:96:0) after 1m0s
[12:44:40.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:96:0)
[12:44:40.521] Timer tick, asking Buildbucket for the build status
[12:44:40.594] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:40.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:97:0) after 1m0s
[12:45:40.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:97:0)
[12:45:40.626] Timer tick, asking Buildbucket for the build status
[12:45:40.768] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:40.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:98:0) after 1m0s
[12:46:40.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:98:0)
[12:46:40.792] Timer tick, asking Buildbucket for the build status
[12:46:40.899] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:40.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:99:0) after 1m0s
[12:47:40.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:99:0)
[12:47:40.946] Timer tick, asking Buildbucket for the build status
[12:47:41.556] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:41.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:100:0) after 1m0s
[12:48:41.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:100:0)
[12:48:41.693] Timer tick, asking Buildbucket for the build status
[12:48:42.081] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:42.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:101:0) after 1m0s
[12:49:42.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:101:0)
[12:49:42.209] Timer tick, asking Buildbucket for the build status
[12:49:42.289] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:42.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:102:0) after 1m0s
[12:50:45.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:102:0)
[12:50:45.849] Timer tick, asking Buildbucket for the build status
[12:50:45.973] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:45.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:103:0) after 1m0s
[12:51:46.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:103:0)
[12:51:46.900] Timer tick, asking Buildbucket for the build status
[12:51:46.964] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:46.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:104:0) after 1m0s
[12:52:47.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:104:0)
[12:52:47.057] Timer tick, asking Buildbucket for the build status
[12:52:49.816] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:49.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:105:0) after 1m0s
[12:53:49.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:105:0)
[12:53:49.886] Timer tick, asking Buildbucket for the build status
[12:53:49.963] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:49.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:106:0) after 1m0s
[12:54:50.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:106:0)
[12:54:50.023] Timer tick, asking Buildbucket for the build status
[12:54:50.138] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:50.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:107:0) after 1m0s
[12:55:50.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:107:0)
[12:55:50.277] Timer tick, asking Buildbucket for the build status
[12:55:51.002] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:51.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:108:0) after 1m0s
[12:56:51.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:108:0)
[12:56:51.034] Timer tick, asking Buildbucket for the build status
[12:56:51.117] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:51.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:109:0) after 1m0s
[12:57:51.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:109:0)
[12:57:51.206] Timer tick, asking Buildbucket for the build status
[12:57:51.539] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:51.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:110:0) after 1m0s
[12:58:51.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:110:0)
[12:58:51.606] Timer tick, asking Buildbucket for the build status
[12:58:51.726] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:51.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:111:0) after 1m0s
[12:59:51.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:111:0)
[12:59:51.785] Timer tick, asking Buildbucket for the build status
[12:59:51.923] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:51.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:112:0) after 1m0s
[13:00:51.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:112:0)
[13:00:51.969] Timer tick, asking Buildbucket for the build status
[13:00:52.113] Build 8864928268531300448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:52.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030358330482128848:113:0) after 1m0s
[13:01:21.077] Received PubSub notification, asking Buildbucket for the build status
[13:01:21.169] Build 8864928268531300448: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:01:21.169] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1604149278294328",
"created_by": "project:chromium",
"created_ts": "1604142615725864",
"failure_reason": "BUILD_FAILURE",
"id": "8864928268531300448",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c460c9b034fa86b99d46c9326748fc5b9983dd99\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**gcp_unittests** failed because of:\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21519\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21518\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21515\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21514\\n\\n- ...27471 more failure(s) (27475 total)...\"}, \"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\": \"c460c9b034fa86b99d46c9326748fc5b9983dd99\", \"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-9-6o02\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15380, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"42ad027982404039e6c9a921b2f6354f55b65a2e\", \"got_dawn_revision\": \"0a374dce665328391ab3b68fd9e667b018dd9dcd\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"c460c9b034fa86b99d46c9326748fc5b9983dd99\", \"got_revision_cp\": \"refs/heads/master@{#822959}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"69c856dc816ff1dd65fcc536095b44399a7434b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.214@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c460c9b034fa86b99d46c9326748fc5b9983dd99\", \"swarm_hashes_refs/heads/master(at){#822959}_without_patch\": {\"absl_hardening_tests\": \"1ac2261a715bd557d14b95c8b621e2109f0c402e\", \"accessibility_unittests\": \"0a8e6a2277bcdd11fa2cb829588365eb8a99c572\", \"angle_unittests\": \"33395b448e66aadc399261688a673d2da603399f\", \"app_shell_unittests\": \"e7aa00edfcabc8deea8feea38be2fd75ed2d6cd3\", \"aura_unittests\": \"5797b494acdfa38cbb026259de4cc91a34d1e7f7\", \"base_unittests\": \"9c11198c8ce3150685f08899eed06b97d5257aa3\", \"base_util_unittests\": \"81c574f1769fcdec01402c93a4593feabf01b4b1\", \"blink_common_unittests\": \"962d17f558bdbd5992e6e9deaf1a42c2482a41ca\", \"blink_fuzzer_unittests\": \"9fdcd8a694a792cc88f9e01ac58e74a7fec0a785\", \"blink_heap_unittests\": \"5fd04bfe7ba06f9c6a7171a712e3217fc6fb0340\", \"blink_platform_unittests\": \"e99eeef3b3cf059bccdfdaece0a4424ace3ac23e\", \"blink_unittests\": \"49ccef4c434bc6695264e2d9f5dc36bbb2f0b01b\", \"boringssl_crypto_tests\": \"3782536d0d63df158f867dc23ae2b42a1d9c30b3\", \"boringssl_ssl_tests\": \"37392521fea667fe76d980a60328af0ddf7bd725\", \"browser_switcher_bho_unittests\": \"98096cbfbec64e8457ac7f88a806f21fd590370e\", \"browser_tests\": \"14a08baef969c4545c8bcab328c2e42d5191f6c8\", \"capture_unittests\": \"d55343b4439d2f37e493f68f4f7d04da696d975f\", \"cast_unittests\": \"a91c39159632474817a44f3cb7e7161db27428d9\", \"cc_unittests\": \"6a357430f7b0cec7c6f9536c74c393ac06fa97e7\", \"chrome_app_unittests\": \"87735a2f3e12a2038e863ccaed378851b0102530\", \"chrome_cleaner_unittests\": \"39458f433f0df8b176823f2c7b787e74180d681a\", \"chrome_elf_unittests\": \"e00adeab2acd88b4700d3445368143a4cd002819\", \"chromedriver_unittests\": \"af620923bde64bf97a6c8b6a21fab4d4dc47649f\", \"components_browsertests\": \"db5a10a4ff34d0d3838f8275e1c3d5e9f4839bad\", \"components_unittests\": \"62135a86cec93e5d51e8dbc949398b7a1721ff92\", \"compositor_unittests\": \"17e5daba8133a6921efe6926cadc101de69fd211\", \"content_browsertests\": \"4639cb75d922c299e028dce74b78ec3d3de7a113\", \"content_unittests\": \"6c05b6b8c7d35c801974f34c1f1f5427bd2ad488\", \"courgette_unittests\": \"e363875d11449ce2d3d36105987a7feee5937a34\", \"crashpad_tests\": \"75eddf82329186543af07b7b2fb85a23e89f0174\", \"cronet_tests\": \"6be3c15a47e1ad51dd234022fbacc3b6cc0e89d2\", \"cronet_unittests\": \"f90084f164b6f138a05d314ee168bc373130e262\", \"crypto_unittests\": \"e4235e4279a7eb9ff52a201b5f04c7564cc99246\", \"delayloads_unittests\": \"7635f6fb4c4e07891a419e85ae0877daf2f8677f\", \"device_unittests\": \"6436a31fba87f72c9cb1fc2563fe44c31d3ad016\", \"display_unittests\": \"2cc8e5e568e83bb898d6d91485dd233fbd90a276\", \"elevation_service_unittests\": \"95ba5313fb5a727d0530759a36029417c8541cfb\", \"events_unittests\": \"5c1b0b33c336ea7e5d45f648f8600a50ed0fb809\", \"extensions_browsertests\": \"bd21fc5df3f1d414c91c89658bb07156f5caff22\", \"extensions_unittests\": \"a3eaed88c3d3b719a561242f5039ebdd5a4c7ccb\", \"filesystem_service_unittests\": \"64ced338483410c6af77a37839219d1783f62b67\", \"gcm_unit_tests\": \"77f5a2cf3fe9591aeee158c9ab2565771c797c39\", \"gcp_unittests\": \"8699569db0cbc675fa8ced6f2113be41a040a917\", \"gfx_unittests\": \"51ef3636373be0722b3b13355e60269533af75a5\", \"gin_unittests\": \"c99ce24bf53ca538f95415409b2ae441ad0ed97c\", \"google_apis_unittests\": \"fc945fa5c7e0f218f5875dbb35434b4c600d190b\", \"gpu_unittests\": \"71c179e294d4108463af03ce67df86809f95d092\", \"gwp_asan_unittests\": \"80034b5780a4e9d252ce687f178a5d4dcd1fa34f\", \"headless_browsertests\": \"603fb2c2a4225dfa3ba5e2c6e43cb0744a8f2214\", \"headless_unittests\": \"e8d51ef53d058c3827c888d91854f44ac7a4064f\", \"install_static_unittests\": \"6b121b564ea9adb4ee813e956f3343b9bbcfeec8\", \"installer_util_unittests\": \"ebd5f8a7a222ca48549d32de982fa31249e51222\", \"interactive_ui_tests\": \"b61efb069d2ecdb29597cc40159fa26d6a17f002\", \"ipc_tests\": \"572fa455121dbe8a096e271533b3d45521dc0e6a\", \"jingle_unittests\": \"3340fea74b818e03a3c32e678ff4f19eba2b424e\", \"latency_unittests\": \"d4fbb408ff34f0a977f86256a3c39a72a4f59826\", \"libjingle_xmpp_unittests\": \"c1d82978b2934118ef1a044adb0f11b7209e9f6b\", \"liburlpattern_unittests\": \"0a7583bbaaf493bf63f1fbabd5e35dce462cc4fc\", \"media_blink_unittests\": \"1aca8d8e421f153f85c4f16f3ab772cd29b3fc1a\", \"media_unittests\": \"29e76caced6e5fee4f73f426b85779f55f711e6b\", \"message_center_unittests\": \"fa9b5df02f19b49e5110529a0f808b4c6fd2c2e9\", \"midi_unittests\": \"2519a750f60d5cfba5d77b9669d24c60a6b6df49\", \"mojo_core_unittests\": \"57cc277e23e3610566725ed797c6dd9a94a9b0cc\", \"mojo_unittests\": \"a427ed7d8c4db31a8b4ac8c2bda5855d6cc29895\", \"nacl_loader_unittests\": \"57ce77a712eec046b984e2d15dbdf0b897bac19e\", \"native_theme_unittests\": \"b1af1a80c5bfa92ba5d5ab3d61c072fea8807f63\", \"net_unittests\": \"598863405bfc5b27ba024198a02e266fc3e04b5a\", \"notification_helper_unittests\": \"ea6a5dcfc8a42e15c760715a0a15f225be542b14\", \"pdf_unittests\": \"2639344b836b630ef84b2090dff726a5d1a3e4cf\", \"perfetto_unittests\": \"585a50a97f93feb4888e17cb58e84141be2a5fef\", \"ppapi_unittests\": \"2ca74a03bc22f0f0d2590bf45a960aa16a99dfaf\", \"printing_unittests\": \"456ff40d9db69e9f1c18d375c9c079d2e21bc670\", \"remoting_unittests\": \"4469c1e60bb8fa11f4236208fad7847ecc0b092f\", \"sbox_integration_tests\": \"7a76d8be714ded0adc2afbe775954ba1dc04bc7a\", \"sbox_unittests\": \"c2692ce6a5d9a3de4cf889a96651f8f92ed138af\", \"sbox_validation_tests\": \"19ac4e8595c5558a3fa93913f4ed378e113dea7d\", \"service_manager_unittests\": \"5a64d045ca252dddca8cbd295f4bb6e7f0a4ea05\", \"services_unittests\": \"f43b944b4705379a4220da1f83ca1c016abb1c68\", \"setup_unittests\": \"b3f64950e2b68f2a7b3ae5e39258ff6d1b185157\", \"shell_dialogs_unittests\": \"3c6c746234448bc946335c9cfb5d5ac292a22cef\", \"skia_unittests\": \"fb390dc9fda0d9b42fcb1d5d2e11354310b17c95\", \"snapshot_unittests\": \"0101aba536e18febef570d3ce131dcc1a2214fae\", \"sql_unittests\": \"20e79853d8e151ea7ba893291bd85bf7ac45f7af\", \"storage_unittests\": \"4a6fd74b0c7fe30e467160815281f19f5d2785a2\", \"sync_integration_tests\": \"ab7514cd8ed0c2dd8e2f7a7ec326662a13fa00f2\", \"traffic_annotation_auditor_unittests\": \"8ea47cab357d7a82878ccec402acc331568e46d9\", \"ui_base_unittests\": \"9e6da7479983cf190c4ab4bfe880cbee96eff2a1\", \"ui_touch_selection_unittests\": \"070f4f2dfc96ace06702ea601ee714defd35b555\", \"unit_tests\": \"5bbd3194098b485c11e0472f2e91888002588a16\", \"updater_tests\": \"cb59156885715f9877d5c8d1e3d9d384dc127ea1\", \"url_unittests\": \"51cdb8c5048ec64f824a0d57b3b7ee53aa7ddd69\", \"views_unittests\": \"15339f2615845fd637713371bce8246f5aa86cd7\", \"viz_unittests\": \"4a94d1a20e4c467b3098e225ed0f15d947c85f71\", \"vr_common_unittests\": \"6e1fd90eca0fe5e145807595deb05364c9458088\", \"vr_pixeltests\": \"9636f112c19a6ff34f5bee64021c41b18dcea12e\", \"weblayer_browsertests\": \"ce64f19b7cc3f65ea51661266c0e7f59e7c71fb1\", \"weblayer_unittests\": \"38aeb2b57193b939240882b0b1e5c60693c71aa9\", \"wm_unittests\": \"613d5c581dc92e08064fcfce9582d80d8043def8\", \"wtf_unittests\": \"ab9276898bd1019a9cb6c9820bcca0e117dacbf5\", \"zlib_unittests\": \"4e89312ff9b1b327de71e5f6970a38fa03ec07a4\", \"zucchini_unittests\": \"0c5b070b848bd0ca1ce645bfa4a90fbda2a6b37b\"}}, \"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-9-6o02\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**gcp_unittests** failed because of:\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21519\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21518\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21515\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21514\\n\\n- ...27471 more failure(s) (27475 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604142856783608",
"status": "COMPLETED",
"status_changed_ts": "1604142857188584",
"tags": [
"build_address:luci.chromium.ci/win-asan/15380",
"builder:win-asan",
"buildset:commit/git/c460c9b034fa86b99d46c9326748fc5b9983dd99",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c460c9b034fa86b99d46c9326748fc5b9983dd99",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030358330482128848",
"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/8864928268531300448/+/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:4f972b59fdb23410",
"user_agent:luci-scheduler"
],
"updated_ts": "1604149279540467",
"url": "https://ci.chromium.org/b/8864928268531300448",
"utcnow_ts": "1604149281158605"
}
}
[13:01:21.169] Invocation finished in 1h51m6.687404959s with status FAILED