[21:10:30.086] New invocation is queued and will start shortly
[21:10:31.161] Starting the invocation (attempt 1)
[21:10:31.202] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:10:31.203] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:10:31.203] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019992511229906352",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019992511229906352",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f",
"gitiles_ref:refs/heads/master"
]
}
[21:10:33.072] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614028231408673",
"id": "8854562449181810224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614028232692299",
"tags": [
"build_address:luci.chromium.ci/win-asan/17477",
"builder:win-asan",
"buildset:commit/git/2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019992511229906352",
"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/8854562449181810224/+/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": "1614028232692432",
"url": "https://ci.chromium.org/b/8854562449181810224",
"utcnow_ts": "1614028233059679"
}
}
[21:10:33.072] Task URL: https://ci.chromium.org/b/8854562449181810224
[21:10:33.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:2:0) after 1m0s
[21:11:33.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:2:0)
[21:11:33.165] Timer tick, asking Buildbucket for the build status
[21:11:33.244] Build 8854562449181810224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:33.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:3:0) after 1m0s
[21:12:33.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:3:0)
[21:12:33.340] Timer tick, asking Buildbucket for the build status
[21:12:33.641] Build 8854562449181810224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:33.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:4:0) after 1m0s
[21:13:12.099] Received PubSub notification, asking Buildbucket for the build status
[21:13:12.198] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:33.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:4:0)
[21:13:33.709] Timer tick, asking Buildbucket for the build status
[21:13:33.968] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:33.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:6:0) after 1m0s
[21:14:33.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:6:0)
[21:14:33.987] Timer tick, asking Buildbucket for the build status
[21:14:34.075] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:34.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:7:0) after 1m0s
[21:15:34.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:7:0)
[21:15:34.177] Timer tick, asking Buildbucket for the build status
[21:15:34.426] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:34.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:8:0) after 1m0s
[21:16:34.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:8:0)
[21:16:34.513] Timer tick, asking Buildbucket for the build status
[21:16:34.599] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:34.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:9:0) after 1m0s
[21:17:34.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:9:0)
[21:17:34.647] Timer tick, asking Buildbucket for the build status
[21:17:34.808] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:34.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:10:0) after 1m0s
[21:18:34.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:10:0)
[21:18:34.824] Timer tick, asking Buildbucket for the build status
[21:18:34.947] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:34.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:11:0) after 1m0s
[21:19:35.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:11:0)
[21:19:35.021] Timer tick, asking Buildbucket for the build status
[21:19:35.342] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:35.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:12:0) after 1m0s
[21:20:35.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:12:0)
[21:20:35.427] Timer tick, asking Buildbucket for the build status
[21:20:35.495] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:35.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:13:0) after 1m0s
[21:21:35.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:13:0)
[21:21:35.539] Timer tick, asking Buildbucket for the build status
[21:21:35.935] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:35.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:14:0) after 1m0s
[21:22:35.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:14:0)
[21:22:35.913] Timer tick, asking Buildbucket for the build status
[21:22:35.985] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:35.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:15:0) after 1m0s
[21:23:36.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:15:0)
[21:23:36.206] Timer tick, asking Buildbucket for the build status
[21:23:36.284] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:36.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:16:0) after 1m0s
[21:24:36.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:16:0)
[21:24:36.529] Timer tick, asking Buildbucket for the build status
[21:24:36.790] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:36.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:17:0) after 1m0s
[21:25:36.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:17:0)
[21:25:36.900] Timer tick, asking Buildbucket for the build status
[21:25:37.050] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:37.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:18:0) after 1m0s
[21:26:37.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:18:0)
[21:26:37.082] Timer tick, asking Buildbucket for the build status
[21:26:37.216] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:37.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:19:0) after 1m0s
[21:27:37.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:19:0)
[21:27:37.289] Timer tick, asking Buildbucket for the build status
[21:27:37.360] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:37.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:20:0) after 1m0s
[21:28:37.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:20:0)
[21:28:37.442] Timer tick, asking Buildbucket for the build status
[21:28:37.837] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:37.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:21:0) after 1m0s
[21:29:37.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:21:0)
[21:29:37.908] Timer tick, asking Buildbucket for the build status
[21:29:38.229] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:38.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:22:0) after 1m0s
[21:30:38.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:22:0)
[21:30:38.335] Timer tick, asking Buildbucket for the build status
[21:30:38.704] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:38.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:23:0) after 1m0s
[21:31:38.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:23:0)
[21:31:38.788] Timer tick, asking Buildbucket for the build status
[21:31:38.921] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:38.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:24:0) after 1m0s
[21:32:39.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:24:0)
[21:32:39.001] Timer tick, asking Buildbucket for the build status
[21:32:39.078] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:39.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:25:0) after 1m0s
[21:33:39.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:25:0)
[21:33:39.066] Timer tick, asking Buildbucket for the build status
[21:33:39.146] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:39.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:26:0) after 1m0s
[21:34:39.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:26:0)
[21:34:39.258] Timer tick, asking Buildbucket for the build status
[21:34:39.598] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:39.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:27:0) after 1m0s
[21:35:39.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:27:0)
[21:35:39.621] Timer tick, asking Buildbucket for the build status
[21:35:39.723] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:39.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:28:0) after 1m0s
[21:36:39.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:28:0)
[21:36:39.718] Timer tick, asking Buildbucket for the build status
[21:36:39.812] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:39.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:29:0) after 1m0s
[21:37:39.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:29:0)
[21:37:39.829] Timer tick, asking Buildbucket for the build status
[21:37:39.999] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:39.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:30:0) after 1m0s
[21:38:40.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:30:0)
[21:38:40.017] Timer tick, asking Buildbucket for the build status
[21:38:40.130] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:40.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:31:0) after 1m0s
[21:39:40.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:31:0)
[21:39:40.198] Timer tick, asking Buildbucket for the build status
[21:39:40.368] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:40.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:32:0) after 1m0s
[21:40:40.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:32:0)
[21:40:40.420] Timer tick, asking Buildbucket for the build status
[21:40:40.626] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:40.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:33:0) after 1m0s
[21:41:40.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:33:0)
[21:41:40.646] Timer tick, asking Buildbucket for the build status
[21:41:41.031] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:41.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:34:0) after 1m0s
[21:42:41.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:34:0)
[21:42:41.035] Timer tick, asking Buildbucket for the build status
[21:42:41.205] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:41.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:35:0) after 1m0s
[21:43:41.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:35:0)
[21:43:41.256] Timer tick, asking Buildbucket for the build status
[21:43:41.332] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:41.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:36:0) after 1m0s
[21:44:41.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:36:0)
[21:44:41.376] Timer tick, asking Buildbucket for the build status
[21:44:41.495] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:41.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:37:0) after 1m0s
[21:45:41.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:37:0)
[21:45:41.520] Timer tick, asking Buildbucket for the build status
[21:45:41.667] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:41.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:38:0) after 1m0s
[21:46:41.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:38:0)
[21:46:41.644] Timer tick, asking Buildbucket for the build status
[21:46:42.004] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:42.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:39:0) after 1m0s
[21:47:42.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:39:0)
[21:47:42.069] Timer tick, asking Buildbucket for the build status
[21:47:42.334] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:42.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:40:0) after 1m0s
[21:48:42.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:40:0)
[21:48:42.385] Timer tick, asking Buildbucket for the build status
[21:48:42.475] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:42.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:41:0) after 1m0s
[21:49:42.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:41:0)
[21:49:42.498] Timer tick, asking Buildbucket for the build status
[21:49:42.667] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:42.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:42:0) after 1m0s
[21:50:42.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:42:0)
[21:50:42.712] Timer tick, asking Buildbucket for the build status
[21:50:43.051] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:43.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:43:0) after 1m0s
[21:51:43.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:43:0)
[21:51:43.100] Timer tick, asking Buildbucket for the build status
[21:51:43.206] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:43.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:44:0) after 1m0s
[21:52:43.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:44:0)
[21:52:43.231] Timer tick, asking Buildbucket for the build status
[21:52:43.322] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:43.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:45:0) after 1m0s
[21:53:43.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:45:0)
[21:53:43.345] Timer tick, asking Buildbucket for the build status
[21:53:43.421] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:43.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:46:0) after 1m0s
[21:54:43.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:46:0)
[21:54:43.492] Timer tick, asking Buildbucket for the build status
[21:54:43.657] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:43.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:47:0) after 1m0s
[21:55:43.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:47:0)
[21:55:43.756] Timer tick, asking Buildbucket for the build status
[21:55:43.866] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:43.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:48:0) after 1m0s
[21:56:43.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:48:0)
[21:56:43.861] Timer tick, asking Buildbucket for the build status
[21:56:43.930] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:43.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:49:0) after 1m0s
[21:57:44.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:49:0)
[21:57:44.123] Timer tick, asking Buildbucket for the build status
[21:57:44.516] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:44.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:50:0) after 1m0s
[21:58:44.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:50:0)
[21:58:44.519] Timer tick, asking Buildbucket for the build status
[21:58:44.796] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:44.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:51:0) after 1m0s
[21:59:44.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:51:0)
[21:59:44.842] Timer tick, asking Buildbucket for the build status
[21:59:45.022] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:45.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:52:0) after 1m0s
[22:00:45.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:52:0)
[22:00:45.033] Timer tick, asking Buildbucket for the build status
[22:00:45.139] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:45.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:53:0) after 1m0s
[22:01:45.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:53:0)
[22:01:45.170] Timer tick, asking Buildbucket for the build status
[22:01:45.251] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:45.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:54:0) after 1m0s
[22:02:45.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:54:0)
[22:02:45.316] Timer tick, asking Buildbucket for the build status
[22:02:45.495] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:45.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:55:0) after 1m0s
[22:03:45.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:55:0)
[22:03:45.606] Timer tick, asking Buildbucket for the build status
[22:03:46.151] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:46.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:56:0) after 1m0s
[22:04:46.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:56:0)
[22:04:46.130] Timer tick, asking Buildbucket for the build status
[22:04:46.254] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:46.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:57:0) after 1m0s
[22:05:46.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:57:0)
[22:05:46.299] Timer tick, asking Buildbucket for the build status
[22:05:46.398] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:46.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:58:0) after 1m0s
[22:06:46.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:58:0)
[22:06:46.503] Timer tick, asking Buildbucket for the build status
[22:06:46.652] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:46.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:59:0) after 1m0s
[22:07:46.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:59:0)
[22:07:46.712] Timer tick, asking Buildbucket for the build status
[22:07:46.791] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:46.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:60:0) after 1m0s
[22:08:46.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:60:0)
[22:08:46.815] Timer tick, asking Buildbucket for the build status
[22:08:46.894] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:46.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:61:0) after 1m0s
[22:09:46.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:61:0)
[22:09:46.953] Timer tick, asking Buildbucket for the build status
[22:09:47.120] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:47.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:62:0) after 1m0s
[22:10:47.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:62:0)
[22:10:47.160] Timer tick, asking Buildbucket for the build status
[22:10:47.247] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:47.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:63:0) after 1m0s
[22:11:47.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:63:0)
[22:11:47.286] Timer tick, asking Buildbucket for the build status
[22:11:47.394] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:47.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:64:0) after 1m0s
[22:12:47.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:64:0)
[22:12:47.427] Timer tick, asking Buildbucket for the build status
[22:12:47.683] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:47.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:65:0) after 1m0s
[22:13:47.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:65:0)
[22:13:47.792] Timer tick, asking Buildbucket for the build status
[22:13:47.875] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:47.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:66:0) after 1m0s
[22:14:47.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:66:0)
[22:14:47.891] Timer tick, asking Buildbucket for the build status
[22:14:47.982] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:47.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:67:0) after 1m0s
[22:15:48.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:67:0)
[22:15:48.038] Timer tick, asking Buildbucket for the build status
[22:15:48.127] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:48.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:68:0) after 1m0s
[22:16:48.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:68:0)
[22:16:48.172] Timer tick, asking Buildbucket for the build status
[22:16:48.264] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:48.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:69:0) after 1m0s
[22:17:48.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:69:0)
[22:17:48.411] Timer tick, asking Buildbucket for the build status
[22:17:48.498] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:48.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:70:0) after 1m0s
[22:18:48.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:70:0)
[22:18:48.493] Timer tick, asking Buildbucket for the build status
[22:18:48.723] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:48.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:71:0) after 1m0s
[22:19:48.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:71:0)
[22:19:48.846] Timer tick, asking Buildbucket for the build status
[22:19:48.958] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:48.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:72:0) after 1m0s
[22:20:49.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:72:0)
[22:20:49.059] Timer tick, asking Buildbucket for the build status
[22:20:49.308] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:49.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:73:0) after 1m0s
[22:21:49.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:73:0)
[22:21:49.432] Timer tick, asking Buildbucket for the build status
[22:21:49.506] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:49.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:74:0) after 1m0s
[22:22:49.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:74:0)
[22:22:49.615] Timer tick, asking Buildbucket for the build status
[22:22:49.706] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:49.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:75:0) after 1m0s
[22:23:49.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:75:0)
[22:23:49.750] Timer tick, asking Buildbucket for the build status
[22:23:49.830] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:49.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:76:0) after 1m0s
[22:24:49.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:76:0)
[22:24:49.953] Timer tick, asking Buildbucket for the build status
[22:24:50.042] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:50.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:77:0) after 1m0s
[22:25:50.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:77:0)
[22:25:50.104] Timer tick, asking Buildbucket for the build status
[22:25:50.212] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:50.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:78:0) after 1m0s
[22:26:50.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:78:0)
[22:26:50.263] Timer tick, asking Buildbucket for the build status
[22:26:50.477] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:50.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:79:0) after 1m0s
[22:27:50.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:79:0)
[22:27:50.565] Timer tick, asking Buildbucket for the build status
[22:27:50.710] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:50.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:80:0) after 1m0s
[22:28:50.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:80:0)
[22:28:50.829] Timer tick, asking Buildbucket for the build status
[22:28:51.078] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:51.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:81:0) after 1m0s
[22:29:51.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:81:0)
[22:29:51.509] Timer tick, asking Buildbucket for the build status
[22:29:51.888] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:51.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:82:0) after 1m0s
[22:30:51.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:82:0)
[22:30:51.994] Timer tick, asking Buildbucket for the build status
[22:30:52.329] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:52.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:83:0) after 1m0s
[22:31:52.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:83:0)
[22:31:52.386] Timer tick, asking Buildbucket for the build status
[22:31:52.549] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:52.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:84:0) after 1m0s
[22:32:52.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:84:0)
[22:32:52.670] Timer tick, asking Buildbucket for the build status
[22:32:52.775] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:52.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:85:0) after 1m0s
[22:33:52.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:85:0)
[22:33:52.901] Timer tick, asking Buildbucket for the build status
[22:33:53.189] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:53.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:86:0) after 1m0s
[22:34:53.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:86:0)
[22:34:53.290] Timer tick, asking Buildbucket for the build status
[22:34:53.591] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:53.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:87:0) after 1m0s
[22:35:53.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:87:0)
[22:35:53.613] Timer tick, asking Buildbucket for the build status
[22:35:53.723] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:53.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:88:0) after 1m0s
[22:36:53.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:88:0)
[22:36:53.709] Timer tick, asking Buildbucket for the build status
[22:36:54.405] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:54.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:89:0) after 1m0s
[22:37:54.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:89:0)
[22:37:54.443] Timer tick, asking Buildbucket for the build status
[22:37:54.543] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:54.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:90:0) after 1m0s
[22:38:54.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:90:0)
[22:38:54.616] Timer tick, asking Buildbucket for the build status
[22:38:54.708] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:54.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:91:0) after 1m0s
[22:39:54.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:91:0)
[22:39:54.747] Timer tick, asking Buildbucket for the build status
[22:39:55.026] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:55.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:92:0) after 1m0s
[22:40:55.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:92:0)
[22:40:55.015] Timer tick, asking Buildbucket for the build status
[22:40:55.366] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:55.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:93:0) after 1m0s
[22:41:55.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:93:0)
[22:41:55.354] Timer tick, asking Buildbucket for the build status
[22:41:55.433] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:55.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:94:0) after 1m0s
[22:42:55.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:94:0)
[22:42:55.505] Timer tick, asking Buildbucket for the build status
[22:42:55.632] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:55.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:95:0) after 1m0s
[22:43:55.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:95:0)
[22:43:55.728] Timer tick, asking Buildbucket for the build status
[22:43:56.220] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:56.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:96:0) after 1m0s
[22:44:56.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:96:0)
[22:44:56.240] Timer tick, asking Buildbucket for the build status
[22:44:56.325] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:56.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:97:0) after 1m0s
[22:45:56.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:97:0)
[22:45:56.348] Timer tick, asking Buildbucket for the build status
[22:45:56.448] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:56.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:98:0) after 1m0s
[22:46:56.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:98:0)
[22:46:56.495] Timer tick, asking Buildbucket for the build status
[22:46:56.593] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:56.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:99:0) after 1m0s
[22:47:56.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:99:0)
[22:47:56.645] Timer tick, asking Buildbucket for the build status
[22:47:56.800] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:56.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:100:0) after 1m0s
[22:48:56.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:100:0)
[22:48:56.880] Timer tick, asking Buildbucket for the build status
[22:48:56.966] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:56.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:101:0) after 1m0s
[22:49:57.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:101:0)
[22:49:57.073] Timer tick, asking Buildbucket for the build status
[22:49:57.419] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:57.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:102:0) after 1m0s
[22:50:57.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:102:0)
[22:50:57.563] Timer tick, asking Buildbucket for the build status
[22:50:58.108] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:58.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:103:0) after 1m0s
[22:51:58.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:103:0)
[22:51:58.124] Timer tick, asking Buildbucket for the build status
[22:51:58.207] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:58.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:104:0) after 1m0s
[22:52:58.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:104:0)
[22:52:58.260] Timer tick, asking Buildbucket for the build status
[22:52:58.394] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:58.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:105:0) after 1m0s
[22:53:58.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:105:0)
[22:53:58.502] Timer tick, asking Buildbucket for the build status
[22:53:58.833] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:58.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:106:0) after 1m0s
[22:54:58.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:106:0)
[22:54:58.904] Timer tick, asking Buildbucket for the build status
[22:54:58.984] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:58.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:107:0) after 1m0s
[22:55:59.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:107:0)
[22:55:59.150] Timer tick, asking Buildbucket for the build status
[22:55:59.251] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:59.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:108:0) after 1m0s
[22:56:59.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:108:0)
[22:56:59.314] Timer tick, asking Buildbucket for the build status
[22:56:59.493] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:59.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:109:0) after 1m0s
[22:57:59.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:109:0)
[22:57:59.497] Timer tick, asking Buildbucket for the build status
[22:57:59.601] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:59.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:110:0) after 1m0s
[22:58:59.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:110:0)
[22:58:59.646] Timer tick, asking Buildbucket for the build status
[22:58:59.826] Build 8854562449181810224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:59.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019992511229906352:111:0) after 1m0s
[22:59:08.806] Received PubSub notification, asking Buildbucket for the build status
[22:59:09.251] Build 8854562449181810224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:59:09.251] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614034747482297",
"created_by": "project:chromium",
"created_ts": "1614028231408673",
"id": "8854562449181810224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f\"}}",
"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\": \"2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f\", \"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\": 17477, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"c624d4101ea6c01e494e5322ca0baca5fef085a6\", \"got_dawn_revision\": \"2d3c2e355337037dfa6873536bfb3e3a558bf9ed\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f\", \"got_revision_cp\": \"refs/heads/master@{#856433}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3ffbd62bb3437e4b12fdf8ee1dd7a3eb8a5d27ee\", \"got_v8_revision\": \"c8019e4062f5bfc3094573fec601a3682ca791bd\", \"got_v8_revision_cp\": \"refs/heads/9.0.235@{#1}\", \"got_webrtc_revision\": \"28547e96cc2b1be9ebdea7455c46f12fe76d90c7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33318}\", \"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\": \"2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f\", \"swarm_hashes_refs/heads/master(at){#856433}_without_patch\": {\"absl_hardening_tests\": \"bdaca3036bccf56b6a4661ca1cb681935c6a6b7a\", \"accessibility_unittests\": \"52dfadeb274076566e6a5ae6b8bdcddacbf6ef3c\", \"angle_unittests\": \"399d7c83055063b8d4ac14a56e4e8fb955d85e67\", \"app_shell_unittests\": \"2cadc6cb23969e84996e6dd9779a0f6f24540913\", \"aura_unittests\": \"ee24a9ee3529fb5ea16d66c40ac5e1eaecd98902\", \"base_unittests\": \"93c4b729e21fecc58998f45a0c7f09e690ba3429\", \"base_util_unittests\": \"97071a6ea442e751e794aaa39931f7149d95a394\", \"blink_common_unittests\": \"5ab0f69b140fdcc0b24c5fcd0ae9bdfa340a06d3\", \"blink_fuzzer_unittests\": \"12af1ea15aae3d3249af76a5c0a036c2ae13cb32\", \"blink_heap_unittests\": \"ab40d615ef59615c31207b31b44b289e0682a8d5\", \"blink_platform_unittests\": \"b8146e3f92facd38ff8e67f396e048697805899b\", \"blink_unittests\": \"9b2ddb3f8519d44ccc75e824311ad58627a134e6\", \"boringssl_crypto_tests\": \"d703e53de01b69b3376be4881dd212cc90a02e24\", \"boringssl_ssl_tests\": \"2e68b9119b87400439cd28a55b837d95083a6f6f\", \"browser_switcher_bho_unittests\": \"55f88fa1a5493dcae263cf6e56c54c6fc223719e\", \"browser_tests\": \"82d1f6a306516a5f035729bd551088f13ea40654\", \"capture_unittests\": \"730afc855041df7dc879e9f903b8bd0092dd5bb5\", \"cast_unittests\": \"0e759b3004993f159309358788802c49102b0343\", \"cc_unittests\": \"a4c02b72aa0460769310beab131ecb8794455a31\", \"chrome_app_unittests\": \"adf77993b5c10dd494ec11993fd14b027d683f2b\", \"chrome_cleaner_unittests\": \"7b5d67d7d3b7329c37bcf6f9af6e074ecbf66f88\", \"chrome_elf_unittests\": \"4dc0770b1e412b319b7a17f6f53c079716dd195f\", \"chromedriver_unittests\": \"f83e91b36e6a6d82061ddb00101084f3743d0b2f\", \"components_browsertests\": \"1d3d6a3d3138683143b65e4508c5dc013fc88543\", \"components_unittests\": \"c82f7e19c4bfe4c1baf0634fc6d129776d14d1bb\", \"compositor_unittests\": \"b6ef015b2351015a32bb7cda9b772c6ce856ef10\", \"content_browsertests\": \"7a1d83a252b905cd46af627e5b36d212a78a17e5\", \"content_unittests\": \"2eadd850bd0278619822b26d7ffb81ae99928021\", \"courgette_unittests\": \"76187c4913fe15cbb530fc32a052d9402563abda\", \"crashpad_tests\": \"5852aa2f2677e53d64bcab545c237425157017c5\", \"cronet_tests\": \"8a5ae15781e178c83723088f402e89d50d3e3910\", \"cronet_unittests\": \"f4cab235aa1d21cb344b3751d3ee87a625d20e27\", \"crypto_unittests\": \"2160cd57abfae1aefdca3476edb2a6c711f68dc1\", \"delayloads_unittests\": \"c3dd2a6cbaf3c15e2eddd12ac1b4e3014d059c60\", \"device_unittests\": \"63ade1670d3ae665ceda14ef09b6caeb3a7afa13\", \"display_unittests\": \"bd4aed2dc8fb17a4e8d6de889f71f9f3ed443f37\", \"elevation_service_unittests\": \"196d2a34262fe6b9aa65acbb6639ddff571956f5\", \"events_unittests\": \"651fdba564c9e93807359f682a8b521cf64bb8ff\", \"extensions_browsertests\": \"be138f765f9d1afa27d99a8d8d13e8859f2933fc\", \"extensions_unittests\": \"4da0539a1fcafda2f9930283ed114a381328130c\", \"filesystem_service_unittests\": \"d192567d8a8f170feafecf78c69d192331521042\", \"gcm_unit_tests\": \"798b370a561958ebfc1c597200c85cd18fa1fd9b\", \"gcp_unittests\": \"9c931325928699d46150c6d286080b9606a715a6\", \"gfx_unittests\": \"abf9e494bbe9e277a0c1ad17a14e929bd8ed0265\", \"gin_unittests\": \"52adf18f94ff70c64507a3975913af72cd4ecb17\", \"google_apis_unittests\": \"bca60f2d29525065773e0b405e81751857e054b4\", \"gpu_unittests\": \"474593042472cc6cddbf200b9985f97bd2878be9\", \"gwp_asan_unittests\": \"696b29e902310bce55b2749a6b07775974d59adf\", \"headless_browsertests\": \"8b8f2d711db4b48bb434832f1a824211d9148346\", \"headless_unittests\": \"a326324617daae753c58cd71fc97bc2d04ca7492\", \"install_static_unittests\": \"6c79575fdcb9352d15e0b01b040b1f42e38bae16\", \"installer_util_unittests\": \"6f268ee8316c2250308f40920d390da762cc28d0\", \"interactive_ui_tests\": \"3efee71be517007f8a93ebc3b5bf151268a4e9cf\", \"ipc_tests\": \"cd4e340e4d52c773f283fe3c9011dc0da94c6cd9\", \"jingle_unittests\": \"8639bfddad92c31d60a55671d3c7b555b4c4edc9\", \"latency_unittests\": \"e8c87dcc77e18ab83d64e6893df6e816e913a1df\", \"libjingle_xmpp_unittests\": \"715ebac8916093c2ece587bdb61b59adb4e9e13c\", \"liburlpattern_unittests\": \"79dbef67de10bd4bd610a1ca268e2cfa8d5c4c5e\", \"media_blink_unittests\": \"c3a3a5921e8de41162a10ad88c1157deb1e61f54\", \"media_unittests\": \"89b3d86ad01a3021ff358c17eca7f62afe5c86a4\", \"message_center_unittests\": \"80ed277100120c8fbf648b51c6a2faff5a671e1d\", \"midi_unittests\": \"19c88f3b512466c639fd99c1b5f85db6b3d4857a\", \"mojo_core_unittests\": \"14be92ec45fe601b611df2e18683b70b1b8eeab9\", \"mojo_unittests\": \"6e44cacf386372c0e30c49501e6b08529538dce6\", \"nacl_loader_unittests\": \"b476bd3286cf6b0d06c5a8bbd6d8e2c84bf563d4\", \"native_theme_unittests\": \"184947921cb612922a4076af3206af71863af734\", \"net_unittests\": \"2905e3d2010b4c4784acbcaa811a5e021981f12b\", \"notification_helper_unittests\": \"2825d19a329bc3777f94c8b98df5d145fbae366a\", \"pdf_unittests\": \"bc49537936917cec24f2a45c6884252b8c8855bb\", \"perfetto_unittests\": \"f349cb8eb9e20b3bdbf92c0b65a6dfa5b18b6e60\", \"ppapi_unittests\": \"f3c9b60dd91e1fb59ac1cd3c3f301c7725e80aa9\", \"printing_unittests\": \"1961f4d93efb76b135fac7225253cb925af39799\", \"remoting_unittests\": \"f6a5b33e8b18bf2ae814612c52416f83f96936af\", \"sbox_integration_tests\": \"14a15953a4212771e64483920e6b4fe1a47e8d2c\", \"sbox_unittests\": \"c5c6b5cb941bee0b55b29b5937e6dabde39f2e98\", \"sbox_validation_tests\": \"55d353e0838f5285cddc0829d3791dc06123a821\", \"service_manager_unittests\": \"e5faf0f77ee789d87355bee2386077c6af9c4335\", \"services_unittests\": \"d204d7ae1395c756566545e7f967579723e40a54\", \"setup_unittests\": \"8967fd10059bcf3fa67b2c188f18275c041a015b\", \"shell_dialogs_unittests\": \"1258a6942318124ff9bdf82f67c2b55ab9f1e081\", \"skia_unittests\": \"1cd0892be5c4953953a5d70338aedfaec4833f98\", \"snapshot_unittests\": \"6f9ace168cb4d5ee26155af6748e7a9fa441ea13\", \"sql_unittests\": \"a7f4425cd2beb3e2be7e6feb6c202fa9b196e0eb\", \"storage_unittests\": \"6096bb93dd619e7dff415a8b3af8bda08c24b3e2\", \"sync_integration_tests\": \"ec017a5acc505772d73c70dbe9354f5138e5e34c\", \"traffic_annotation_auditor_unittests\": \"18fb2161ae70c8068adb910b0cf27e31de07e145\", \"ui_base_unittests\": \"bd99124ca045fa0d7bbca9cc78753c0325bf308c\", \"ui_touch_selection_unittests\": \"8898e292f6cc0997e5f6eb5d060a5e43e8e020d7\", \"unit_tests\": \"0ca6fa56fd346a0d8f3d36237d3e8b86fa89f797\", \"updater_tests\": \"d25a0e705d05c46336ef5fff3a49aef63dbd5209\", \"url_unittests\": \"197955dbeb1ddc7b3dfd759ced88ade98c195b4d\", \"views_unittests\": \"b214c574db18ae1ffae1ef9b7539387ab7a1371a\", \"viz_unittests\": \"c186533c97f4360b833471e90f274cd9d48af9bd\", \"vr_common_unittests\": \"e2c841af20f54543689a7a159cc93713d1953a6c\", \"vr_pixeltests\": \"8b0e42a4fc1288df39e0925b646e77bc21822d2b\", \"weblayer_browsertests\": \"431ddbc54298f6323fdac1ac28ce75327b8319ae\", \"weblayer_unittests\": \"81e8b878291fa592770bb1038781f62f00949177\", \"wm_unittests\": \"4e08a8cafa64bf621c8bf9e5ab2e5f3f0da7b8bf\", \"wtf_unittests\": \"979cf0e90a581f05b4de141d9127a759a66258a4\", \"zlib_unittests\": \"058f04b9a522521e743f6069744cbf4d80637cce\", \"zucchini_unittests\": \"3ae97adc0f22b0956a44b8aed6b6eea520c00e7b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xpf4\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614028391288337",
"status": "COMPLETED",
"status_changed_ts": "1614034747801501",
"tags": [
"build_address:luci.chromium.ci/win-asan/17477",
"builder:win-asan",
"buildset:commit/git/2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2a8784c4f5cb15fafcdf68f1ad86fe7e5715ac7f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019992511229906352",
"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/8854562449181810224/+/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:51e465e8c1ff7610",
"user_agent:luci-scheduler"
],
"updated_ts": "1614034747826577",
"url": "https://ci.chromium.org/b/8854562449181810224",
"utcnow_ts": "1614034749195514"
}
}
[22:59:09.251] Invocation finished in 1h48m39.180584656s with status SUCCEEDED