[14:35:12.367] New invocation is queued and will start shortly
[14:35:13.533] Starting the invocation (attempt 1)
[14:35:13.624] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:35:13.624] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:35:13.624] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022282305229122544",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"84faf63625376560b7ca9697d09564cd537a1323\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022282305229122544",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/84faf63625376560b7ca9697d09564cd537a1323",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84faf63625376560b7ca9697d09564cd537a1323",
"gitiles_ref:refs/heads/master"
]
}
[14:35:14.356] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611844513773490",
"id": "8856852243084375456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84faf63625376560b7ca9697d09564cd537a1323\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611844514211501",
"tags": [
"build_address:luci.chromium.ci/win-asan/17050",
"builder:win-asan",
"buildset:commit/git/84faf63625376560b7ca9697d09564cd537a1323",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84faf63625376560b7ca9697d09564cd537a1323",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022282305229122544",
"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/8856852243084375456/+/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": "1611844514211615",
"url": "https://ci.chromium.org/b/8856852243084375456",
"utcnow_ts": "1611844514333023"
}
}
[14:35:14.356] Task URL: https://ci.chromium.org/b/8856852243084375456
[14:35:14.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:2:0) after 1m0s
[14:36:14.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:2:0)
[14:36:14.429] Timer tick, asking Buildbucket for the build status
[14:36:14.917] Build 8856852243084375456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:36:14.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:3:0) after 1m0s
[14:37:14.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:3:0)
[14:37:14.995] Timer tick, asking Buildbucket for the build status
[14:37:15.152] Build 8856852243084375456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:37:15.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:4:0) after 1m0s
[14:38:15.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:4:0)
[14:38:15.197] Timer tick, asking Buildbucket for the build status
[14:38:15.289] Build 8856852243084375456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:15.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:5:0) after 1m0s
[14:39:15.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:5:0)
[14:39:15.304] Timer tick, asking Buildbucket for the build status
[14:39:15.406] Build 8856852243084375456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:15.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:6:0) after 1m0s
[14:40:15.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:6:0)
[14:40:15.393] Timer tick, asking Buildbucket for the build status
[14:40:15.612] Build 8856852243084375456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:15.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:7:0) after 1m0s
[14:40:22.384] Received PubSub notification, asking Buildbucket for the build status
[14:40:22.526] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:15.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:7:0)
[14:41:15.710] Timer tick, asking Buildbucket for the build status
[14:41:16.221] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:16.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:9:0) after 1m0s
[14:42:16.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:9:0)
[14:42:16.233] Timer tick, asking Buildbucket for the build status
[14:42:16.515] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:16.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:10:0) after 1m0s
[14:43:16.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:10:0)
[14:43:16.488] Timer tick, asking Buildbucket for the build status
[14:43:16.564] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:16.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:11:0) after 1m0s
[14:44:16.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:11:0)
[14:44:16.606] Timer tick, asking Buildbucket for the build status
[14:44:16.794] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:16.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:12:0) after 1m0s
[14:45:16.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:12:0)
[14:45:16.829] Timer tick, asking Buildbucket for the build status
[14:45:16.919] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:16.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:13:0) after 1m0s
[14:46:17.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:13:0)
[14:46:17.007] Timer tick, asking Buildbucket for the build status
[14:46:17.081] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:17.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:14:0) after 1m0s
[14:47:17.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:14:0)
[14:47:17.142] Timer tick, asking Buildbucket for the build status
[14:47:17.247] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:17.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:15:0) after 1m0s
[14:48:17.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:15:0)
[14:48:17.304] Timer tick, asking Buildbucket for the build status
[14:48:17.485] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:17.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:16:0) after 1m0s
[14:49:17.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:16:0)
[14:49:17.528] Timer tick, asking Buildbucket for the build status
[14:49:17.596] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:17.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:17:0) after 1m0s
[14:50:17.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:17:0)
[14:50:17.615] Timer tick, asking Buildbucket for the build status
[14:50:17.786] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:17.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:18:0) after 1m0s
[14:51:17.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:18:0)
[14:51:17.910] Timer tick, asking Buildbucket for the build status
[14:51:17.989] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:17.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:19:0) after 1m0s
[14:52:18.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:19:0)
[14:52:18.018] Timer tick, asking Buildbucket for the build status
[14:52:18.095] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:18.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:20:0) after 1m0s
[14:53:18.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:20:0)
[14:53:18.134] Timer tick, asking Buildbucket for the build status
[14:53:18.199] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:18.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:21:0) after 1m0s
[14:54:18.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:21:0)
[14:54:18.753] Timer tick, asking Buildbucket for the build status
[14:54:18.869] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:18.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:22:0) after 1m0s
[14:55:19.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:22:0)
[14:55:19.058] Timer tick, asking Buildbucket for the build status
[14:55:19.183] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:19.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:23:0) after 1m0s
[14:56:19.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:23:0)
[14:56:19.338] Timer tick, asking Buildbucket for the build status
[14:56:20.014] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:20.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:24:0) after 1m0s
[14:57:20.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:24:0)
[14:57:20.069] Timer tick, asking Buildbucket for the build status
[14:57:20.146] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:20.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:25:0) after 1m0s
[14:58:20.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:25:0)
[14:58:20.254] Timer tick, asking Buildbucket for the build status
[14:58:20.383] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:20.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:26:0) after 1m0s
[14:59:20.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:26:0)
[14:59:20.409] Timer tick, asking Buildbucket for the build status
[14:59:20.750] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:20.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:27:0) after 1m0s
[15:00:20.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:27:0)
[15:00:20.798] Timer tick, asking Buildbucket for the build status
[15:00:20.934] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:20.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:28:0) after 1m0s
[15:01:21.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:28:0)
[15:01:21.140] Timer tick, asking Buildbucket for the build status
[15:01:22.876] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:22.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:29:0) after 1m0s
[15:02:23.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:29:0)
[15:02:23.036] Timer tick, asking Buildbucket for the build status
[15:02:23.143] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:23.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:30:0) after 1m0s
[15:03:23.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:30:0)
[15:03:23.196] Timer tick, asking Buildbucket for the build status
[15:03:23.262] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:23.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:31:0) after 1m0s
[15:04:23.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:31:0)
[15:04:23.266] Timer tick, asking Buildbucket for the build status
[15:04:23.553] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:23.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:32:0) after 1m0s
[15:05:23.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:32:0)
[15:05:23.603] Timer tick, asking Buildbucket for the build status
[15:05:23.794] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:23.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:33:0) after 1m0s
[15:06:23.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:33:0)
[15:06:23.798] Timer tick, asking Buildbucket for the build status
[15:06:23.869] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:23.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:34:0) after 1m0s
[15:07:23.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:34:0)
[15:07:23.864] Timer tick, asking Buildbucket for the build status
[15:07:24.193] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:24.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:35:0) after 1m0s
[15:08:24.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:35:0)
[15:08:24.315] Timer tick, asking Buildbucket for the build status
[15:08:24.431] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:24.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:36:0) after 1m0s
[15:09:24.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:36:0)
[15:09:24.465] Timer tick, asking Buildbucket for the build status
[15:09:24.560] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:24.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:37:0) after 1m0s
[15:10:24.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:37:0)
[15:10:24.704] Timer tick, asking Buildbucket for the build status
[15:10:24.938] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:24.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:38:0) after 1m0s
[15:11:26.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:38:0)
[15:11:26.497] Timer tick, asking Buildbucket for the build status
[15:11:26.575] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:26.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:39:0) after 1m0s
[15:12:26.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:39:0)
[15:12:26.973] Timer tick, asking Buildbucket for the build status
[15:12:27.069] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:27.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:40:0) after 1m0s
[15:13:27.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:40:0)
[15:13:27.607] Timer tick, asking Buildbucket for the build status
[15:13:27.690] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:27.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:41:0) after 1m0s
[15:14:28.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:41:0)
[15:14:28.189] Timer tick, asking Buildbucket for the build status
[15:14:28.272] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:28.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:42:0) after 1m0s
[15:15:28.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:42:0)
[15:15:28.506] Timer tick, asking Buildbucket for the build status
[15:15:28.651] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:28.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:43:0) after 1m0s
[15:16:28.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:43:0)
[15:16:28.975] Timer tick, asking Buildbucket for the build status
[15:16:29.052] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:29.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:44:0) after 1m0s
[15:17:29.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:44:0)
[15:17:29.311] Timer tick, asking Buildbucket for the build status
[15:17:29.387] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:29.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:45:0) after 1m0s
[15:18:29.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:45:0)
[15:18:29.771] Timer tick, asking Buildbucket for the build status
[15:18:29.897] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:29.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:46:0) after 1m0s
[15:19:30.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:46:0)
[15:19:30.029] Timer tick, asking Buildbucket for the build status
[15:19:30.106] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:30.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:47:0) after 1m0s
[15:20:30.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:47:0)
[15:20:30.172] Timer tick, asking Buildbucket for the build status
[15:20:30.290] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:30.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:48:0) after 1m0s
[15:21:30.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:48:0)
[15:21:30.348] Timer tick, asking Buildbucket for the build status
[15:21:30.749] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:30.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:49:0) after 1m0s
[15:22:30.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:49:0)
[15:22:30.793] Timer tick, asking Buildbucket for the build status
[15:22:30.871] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:30.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:50:0) after 1m0s
[15:23:31.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:50:0)
[15:23:31.118] Timer tick, asking Buildbucket for the build status
[15:23:31.180] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:31.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:51:0) after 1m0s
[15:24:31.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:51:0)
[15:24:31.292] Timer tick, asking Buildbucket for the build status
[15:24:31.487] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:31.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:52:0) after 1m0s
[15:25:31.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:52:0)
[15:25:31.638] Timer tick, asking Buildbucket for the build status
[15:25:31.954] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:31.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:53:0) after 1m0s
[15:26:32.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:53:0)
[15:26:32.092] Timer tick, asking Buildbucket for the build status
[15:26:32.664] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:32.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:54:0) after 1m0s
[15:27:32.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:54:0)
[15:27:32.723] Timer tick, asking Buildbucket for the build status
[15:27:32.844] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:32.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:55:0) after 1m0s
[15:28:32.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:55:0)
[15:28:32.939] Timer tick, asking Buildbucket for the build status
[15:28:33.314] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:33.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:56:0) after 1m0s
[15:29:33.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:56:0)
[15:29:33.342] Timer tick, asking Buildbucket for the build status
[15:29:33.517] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:33.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:57:0) after 1m0s
[15:30:33.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:57:0)
[15:30:33.537] Timer tick, asking Buildbucket for the build status
[15:30:33.624] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:33.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:58:0) after 1m0s
[15:31:33.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:58:0)
[15:31:33.670] Timer tick, asking Buildbucket for the build status
[15:31:33.793] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:33.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:59:0) after 1m0s
[15:32:33.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:59:0)
[15:32:33.822] Timer tick, asking Buildbucket for the build status
[15:32:34.069] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:34.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:60:0) after 1m0s
[15:33:35.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:60:0)
[15:33:35.116] Timer tick, asking Buildbucket for the build status
[15:33:35.218] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:35.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:61:0) after 1m0s
[15:34:35.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:61:0)
[15:34:35.238] Timer tick, asking Buildbucket for the build status
[15:34:35.357] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:35.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:62:0) after 1m0s
[15:35:35.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:62:0)
[15:35:35.355] Timer tick, asking Buildbucket for the build status
[15:35:35.473] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:35.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:63:0) after 1m0s
[15:36:35.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:63:0)
[15:36:35.554] Timer tick, asking Buildbucket for the build status
[15:36:35.777] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:35.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:64:0) after 1m0s
[15:37:35.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:64:0)
[15:37:35.831] Timer tick, asking Buildbucket for the build status
[15:37:36.055] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:36.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:65:0) after 1m0s
[15:38:36.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:65:0)
[15:38:36.055] Timer tick, asking Buildbucket for the build status
[15:38:36.195] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:36.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:66:0) after 1m0s
[15:39:36.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:66:0)
[15:39:36.254] Timer tick, asking Buildbucket for the build status
[15:39:36.347] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:36.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:67:0) after 1m0s
[15:40:36.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:67:0)
[15:40:36.375] Timer tick, asking Buildbucket for the build status
[15:40:36.528] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:36.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:68:0) after 1m0s
[15:41:36.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:68:0)
[15:41:36.712] Timer tick, asking Buildbucket for the build status
[15:41:36.949] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:36.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:69:0) after 1m0s
[15:42:37.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:69:0)
[15:42:37.428] Timer tick, asking Buildbucket for the build status
[15:42:37.571] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:37.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:70:0) after 1m0s
[15:43:37.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:70:0)
[15:43:37.675] Timer tick, asking Buildbucket for the build status
[15:43:37.873] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:37.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:71:0) after 1m0s
[15:44:37.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:71:0)
[15:44:37.890] Timer tick, asking Buildbucket for the build status
[15:44:38.666] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:38.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:72:0) after 1m0s
[15:45:38.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:72:0)
[15:45:38.724] Timer tick, asking Buildbucket for the build status
[15:45:38.843] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:38.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:73:0) after 1m0s
[15:46:38.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:73:0)
[15:46:38.896] Timer tick, asking Buildbucket for the build status
[15:46:39.351] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:39.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:74:0) after 1m0s
[15:47:39.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:74:0)
[15:47:39.369] Timer tick, asking Buildbucket for the build status
[15:47:41.096] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:41.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:75:0) after 1m0s
[15:48:41.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:75:0)
[15:48:41.144] Timer tick, asking Buildbucket for the build status
[15:48:41.559] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:41.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:76:0) after 1m0s
[15:49:41.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:76:0)
[15:49:41.774] Timer tick, asking Buildbucket for the build status
[15:49:42.056] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:42.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:77:0) after 1m0s
[15:50:42.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:77:0)
[15:50:42.094] Timer tick, asking Buildbucket for the build status
[15:50:42.254] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:42.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:78:0) after 1m0s
[15:51:42.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:78:0)
[15:51:42.283] Timer tick, asking Buildbucket for the build status
[15:51:42.422] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:42.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:79:0) after 1m0s
[15:52:42.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:79:0)
[15:52:42.453] Timer tick, asking Buildbucket for the build status
[15:52:42.593] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:42.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:80:0) after 1m0s
[15:53:42.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:80:0)
[15:53:42.804] Timer tick, asking Buildbucket for the build status
[15:53:42.898] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:42.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:81:0) after 1m0s
[15:54:42.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:81:0)
[15:54:42.984] Timer tick, asking Buildbucket for the build status
[15:54:43.391] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:43.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:82:0) after 1m0s
[15:55:43.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:82:0)
[15:55:43.427] Timer tick, asking Buildbucket for the build status
[15:55:43.572] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:43.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:83:0) after 1m0s
[15:56:43.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:83:0)
[15:56:43.725] Timer tick, asking Buildbucket for the build status
[15:56:43.856] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:43.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:84:0) after 1m0s
[15:57:43.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:84:0)
[15:57:43.912] Timer tick, asking Buildbucket for the build status
[15:57:46.781] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:46.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:85:0) after 1m0s
[15:58:46.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:85:0)
[15:58:46.827] Timer tick, asking Buildbucket for the build status
[15:58:47.158] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:47.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:86:0) after 1m0s
[15:59:47.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:86:0)
[15:59:47.209] Timer tick, asking Buildbucket for the build status
[15:59:47.314] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:47.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:87:0) after 1m0s
[16:00:47.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:87:0)
[16:00:47.324] Timer tick, asking Buildbucket for the build status
[16:00:48.428] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:48.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:88:0) after 1m0s
[16:01:48.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:88:0)
[16:01:48.459] Timer tick, asking Buildbucket for the build status
[16:01:48.635] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:48.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:89:0) after 1m0s
[16:02:48.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:89:0)
[16:02:48.656] Timer tick, asking Buildbucket for the build status
[16:02:48.781] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:48.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:90:0) after 1m0s
[16:03:48.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:90:0)
[16:03:48.816] Timer tick, asking Buildbucket for the build status
[16:03:49.024] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:49.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:91:0) after 1m0s
[16:04:49.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:91:0)
[16:04:49.099] Timer tick, asking Buildbucket for the build status
[16:04:49.243] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:49.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:92:0) after 1m0s
[16:05:49.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:92:0)
[16:05:49.332] Timer tick, asking Buildbucket for the build status
[16:05:49.622] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:49.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:93:0) after 1m0s
[16:06:49.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:93:0)
[16:06:49.629] Timer tick, asking Buildbucket for the build status
[16:06:49.803] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:49.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:94:0) after 1m0s
[16:07:49.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:94:0)
[16:07:49.874] Timer tick, asking Buildbucket for the build status
[16:07:49.981] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:49.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:95:0) after 1m0s
[16:08:50.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:95:0)
[16:08:50.029] Timer tick, asking Buildbucket for the build status
[16:08:50.174] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:50.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:96:0) after 1m0s
[16:09:50.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:96:0)
[16:09:50.152] Timer tick, asking Buildbucket for the build status
[16:09:50.402] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:50.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:97:0) after 1m0s
[16:10:50.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:97:0)
[16:10:50.440] Timer tick, asking Buildbucket for the build status
[16:10:50.959] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:50.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:98:0) after 1m0s
[16:11:51.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:98:0)
[16:11:51.038] Timer tick, asking Buildbucket for the build status
[16:11:51.184] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:51.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:99:0) after 1m0s
[16:12:51.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:99:0)
[16:12:51.244] Timer tick, asking Buildbucket for the build status
[16:12:51.503] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:51.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:100:0) after 1m0s
[16:13:51.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:100:0)
[16:13:51.535] Timer tick, asking Buildbucket for the build status
[16:13:52.157] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:52.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:101:0) after 1m0s
[16:14:52.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:101:0)
[16:14:52.139] Timer tick, asking Buildbucket for the build status
[16:14:52.206] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:52.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:102:0) after 1m0s
[16:15:52.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:102:0)
[16:15:52.232] Timer tick, asking Buildbucket for the build status
[16:15:52.380] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:52.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:103:0) after 1m0s
[16:16:52.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:103:0)
[16:16:52.435] Timer tick, asking Buildbucket for the build status
[16:16:52.519] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:52.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:104:0) after 1m0s
[16:17:52.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:104:0)
[16:17:52.598] Timer tick, asking Buildbucket for the build status
[16:17:52.923] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:52.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:105:0) after 1m0s
[16:18:53.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:105:0)
[16:18:53.084] Timer tick, asking Buildbucket for the build status
[16:18:53.358] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:53.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:106:0) after 1m0s
[16:19:53.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:106:0)
[16:19:53.494] Timer tick, asking Buildbucket for the build status
[16:19:54.065] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:54.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:107:0) after 1m0s
[16:20:54.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:107:0)
[16:20:54.171] Timer tick, asking Buildbucket for the build status
[16:20:54.315] Build 8856852243084375456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:54.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022282305229122544:108:0) after 1m0s
[16:21:02.273] Received PubSub notification, asking Buildbucket for the build status
[16:21:02.364] Build 8856852243084375456: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:21:02.364] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611850860723543",
"created_by": "project:chromium",
"created_ts": "1611844513773490",
"id": "8856852243084375456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84faf63625376560b7ca9697d09564cd537a1323\"}}",
"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\": \"84faf63625376560b7ca9697d09564cd537a1323\", \"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-us-central1-b-32-1-nlke\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17050, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"4ce2e2135123407e09e37eb2f3400fd263febecc\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"84faf63625376560b7ca9697d09564cd537a1323\", \"got_revision_cp\": \"refs/heads/master@{#848093}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"fd95b5debcbd6ac0436ac015ce9da1b1582ff166\", \"got_v8_revision_cp\": \"refs/heads/9.0.95@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"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\": \"84faf63625376560b7ca9697d09564cd537a1323\", \"swarm_hashes_refs/heads/master(at){#848093}_without_patch\": {\"absl_hardening_tests\": \"23b00be4c030ad1bc44033283160f057c5c24902\", \"accessibility_unittests\": \"1e2b7793b9aa53adcfa5b54ed200fada6c2b05b9\", \"angle_unittests\": \"c5e557b9e879b895cc0983f6ad959a9624fbbd8c\", \"app_shell_unittests\": \"b3a7f795335bb378d0e775913ec8537ed5b5436f\", \"aura_unittests\": \"256cc3b050cd0b1f16daead4cd5c0d850ebd894e\", \"base_unittests\": \"04a0728c81afe3b0e8a02c8c412e0a6141c95648\", \"base_util_unittests\": \"5e10271b9e832daf8a013695d073523b78ed651c\", \"blink_common_unittests\": \"c1e9da14d551035697bc9c3541b5126869777f33\", \"blink_fuzzer_unittests\": \"3a77d6b07007714a3f77bf8ab4d41066abf9bc93\", \"blink_heap_unittests\": \"a864ce353ed8d6b2d3c94eef017609b7b08bf867\", \"blink_platform_unittests\": \"f808480e5a543fe7feeb860fd4790c0ed0ad51b4\", \"blink_unittests\": \"2749e8557955a37f61144a5555b466b431d16940\", \"boringssl_crypto_tests\": \"85461afdcd83f8402d16f94061db2bf7db09c26e\", \"boringssl_ssl_tests\": \"2b9b41b748ecb6df750d8b2902abeb165f024218\", \"browser_switcher_bho_unittests\": \"83fba5a6944dee1b90eb522afb6931482ef51d79\", \"browser_tests\": \"33f6c97e7b7d849ae673dc4d0e4c4803163d849e\", \"capture_unittests\": \"b11ac94e9b0e0b93c25f74528b3dbbe06ddd8690\", \"cast_unittests\": \"815e3b6161fcf63e0c40f8fc3cd3b72c1d5c045d\", \"cc_unittests\": \"37472ddcc055b7256ede337e2028faba4effec0b\", \"chrome_app_unittests\": \"6b3dd8e22d74187e7d7b7cfa349ab67ad3092e19\", \"chrome_cleaner_unittests\": \"9d89702b69b170e92e8c4698383f5d8ef2b9155f\", \"chrome_elf_unittests\": \"ec435c4e93c9e06d4d7ef1ec81b0952ec17e598d\", \"chromedriver_unittests\": \"8c0a3026bc9f608ef0ece2e1c5e4228649c14f4f\", \"components_browsertests\": \"c9d8cd199f47e8a055921d3c194e9dc984caa101\", \"components_unittests\": \"03064dfe228642e3993e986420ade43aa162e6ae\", \"compositor_unittests\": \"aa72930236ff6a77c11987efc2cee46f3e4cba51\", \"content_browsertests\": \"9df49382f5efe964e0eb56f6832b2c42a29aef02\", \"content_unittests\": \"b89e795d40f827444d10dbf4ef8c4b38ba7e73c8\", \"courgette_unittests\": \"b72f3fa106f331fb2437e1ba977b0efde20d1984\", \"crashpad_tests\": \"dd6f7af6bb8d001c974fc6c1388c4198170e0cfd\", \"cronet_tests\": \"7a6f1f930d0ad1991782785bc2354878f08ed6a8\", \"cronet_unittests\": \"3addd297b1fad787c68b23d600c25002813cdca6\", \"crypto_unittests\": \"3ae37ab647221d8221fa2eaa6edbb69277c5a1f5\", \"delayloads_unittests\": \"99309cfbdbea69ae4161ae73fa486d3e2194c4f9\", \"device_unittests\": \"7ab87b983def623bd51ead5098c6446036b315c0\", \"display_unittests\": \"f96def95d8d266baa5308ca628ebe72bd4e0559e\", \"elevation_service_unittests\": \"c201de57fc7392074add8892c13714a41c825488\", \"events_unittests\": \"40a92fa66a8c4034f1202f70d218bd2c498d6461\", \"extensions_browsertests\": \"6024bf1ed222e86a0efb1e92c7423cfab024749d\", \"extensions_unittests\": \"039ed02fb658ea5ff78804c8ca805c328a4a2603\", \"filesystem_service_unittests\": \"34281305b4cc08c97c0ee7aea4261594e2bf3880\", \"gcm_unit_tests\": \"a1397967e66b5f55823882ed828b3af91ba6fa28\", \"gcp_unittests\": \"c39191dfdf5ea0215d05530e49970f265ba07b2c\", \"gfx_unittests\": \"d6a7fe9834fbb80a90b6ebee4df2f692f4783894\", \"gin_unittests\": \"7b9d1ef1b2865ea70b022444878747eda590f320\", \"google_apis_unittests\": \"03ec6df7e96715bae47df990348c6ae4cc689f5c\", \"gpu_unittests\": \"5d1ca95a970b96e534d47ff6dbcea27211faf1f6\", \"gwp_asan_unittests\": \"74be13c0121e25dab0e6202f1f596e89b43658e6\", \"headless_browsertests\": \"5a0b9eaaadfdd2ffcb51b621943187fb22607b65\", \"headless_unittests\": \"505befa2695a8b2142f58d0445f06c555fa05413\", \"install_static_unittests\": \"71e42a9bec5105237fe7bebe3d316caaa848158c\", \"installer_util_unittests\": \"931647d50527c236df8f72788692f6fe89a3fcfc\", \"interactive_ui_tests\": \"838db63fd99ffa141e71129202c0bd09cbb62ee5\", \"ipc_tests\": \"afee112fb2c8a97f71fdbab85a2744d689237d3b\", \"jingle_unittests\": \"804e1ff1126c2e20888a1ead3ea5d863a57a95ba\", \"latency_unittests\": \"3125fdc8417b19a863c8ee188c149a6298707740\", \"libjingle_xmpp_unittests\": \"66144e9fa91c75a880872fe6569e1b0edead3b5c\", \"liburlpattern_unittests\": \"f7e1ce055ba99af1b8b23225bba52178de3a0b66\", \"media_blink_unittests\": \"325ef605b2ea5ec3c7f61ff2ac1796cb25a6c6ed\", \"media_unittests\": \"ef1847ef51791a639155923ecb62c1c2192ff143\", \"message_center_unittests\": \"72e70e0cfd8ad24ec1ccb5e150a2f9441ed384be\", \"midi_unittests\": \"06d16ab9dc25031375ca4e61b1ec464f36470091\", \"mojo_core_unittests\": \"37b0971cf9e53ba27a50a30517bfe4f0b9b951e1\", \"mojo_unittests\": \"86ac07aae4486c1f3ab7a01fc627f28d079eee52\", \"nacl_loader_unittests\": \"3197306cb7180bcf72cbfe5d1289b6c63d05d498\", \"native_theme_unittests\": \"2018c4900ba0c069725f3d589015c89fe1862439\", \"net_unittests\": \"86ab0bac6da215d33df26203c781590f230b7e90\", \"notification_helper_unittests\": \"4297f39342a24bf1b56231e2a82fddc572605e8c\", \"pdf_unittests\": \"8ed73368d837400420e4816b82595a75471bfbe9\", \"perfetto_unittests\": \"5103ed8550c9328c63b41aac5a1b3adb5bf68548\", \"ppapi_unittests\": \"2ea764fcfa81ee81c7ae17ef6a6f2b82bec7cbc1\", \"printing_unittests\": \"625b9810d7f5cc9903448279ef538e8cc7eb8b99\", \"remoting_unittests\": \"8820bec1f6bf49a5b2ed260535f221fe11a40544\", \"sbox_integration_tests\": \"c0f330e57def7e469be905030b8628b7af7c9e77\", \"sbox_unittests\": \"96bed946d0dfe082325342f6c214e1a3e4973d10\", \"sbox_validation_tests\": \"4d9682925776f348fb6ff0881b56cf0ac661025f\", \"service_manager_unittests\": \"9cbcca4e93b3184a2cf3558b67fc167455223e07\", \"services_unittests\": \"75ebc5f1a2bbc872b6b970d08d39399e03882c9c\", \"setup_unittests\": \"b353676e690b2e5305c848b77c3917af0d30ab27\", \"shell_dialogs_unittests\": \"0d424330e7a60754a6e8006327192aa7d4c75483\", \"skia_unittests\": \"1e678a679c21535bd546272bb1f36606368c4b92\", \"snapshot_unittests\": \"39244460d4b7eaaa7b83e053b98332f3a4c0fa0d\", \"sql_unittests\": \"18e7c244202c2b22490ba5db9ca643398993a3b1\", \"storage_unittests\": \"7e6157fda57cd130b6e8ba5b1c5e1d42d3afeaa1\", \"sync_integration_tests\": \"b43b7ead103cde7fa65c32b7cc3c155f226bbde9\", \"traffic_annotation_auditor_unittests\": \"f085d2502258357611681d29e0faec6169c30089\", \"ui_base_unittests\": \"6ecc6cb15e2d648c475576c4f902bcaa90b9a793\", \"ui_touch_selection_unittests\": \"9215f8d73de368dd38b0d905227e67f031c29a33\", \"unit_tests\": \"3500db68aa968919c17380580b6917a83c2382bc\", \"updater_tests\": \"171456954c4b326080255d47957921ae52f7f258\", \"url_unittests\": \"491831bf1859f4ef407734f9ebcf05002f351a0f\", \"views_unittests\": \"10e79fbf73f513454f938a26e566f376700415fd\", \"viz_unittests\": \"3ed7b279d2525a568d1bb87436f8123465b98675\", \"vr_common_unittests\": \"6d895a2455cb33e993a724ba7b9fe4d8b244db4a\", \"vr_pixeltests\": \"b1cbf5d5ed7cfa9d19ef75d789f72444405855b5\", \"weblayer_browsertests\": \"78ebf2b89b61611dcbb3b65fda0219e5a3a40fd8\", \"weblayer_unittests\": \"48053fa6008fe75246e6f13c57a24ab750a95062\", \"wm_unittests\": \"16602a5b89a44fe9bd394c9f18413ed52fdc829b\", \"wtf_unittests\": \"82ce5e788239cdc0e0ea46889d91f25e0de4af82\", \"zlib_unittests\": \"8ec5b4c82e3a664a464adf3a95f5dec687d76126\", \"zucchini_unittests\": \"b879ea8651fe9e68063b9812f80a77801ee6c26f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-nlke\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611844821774570",
"status": "COMPLETED",
"status_changed_ts": "1611850861034825",
"tags": [
"build_address:luci.chromium.ci/win-asan/17050",
"builder:win-asan",
"buildset:commit/git/84faf63625376560b7ca9697d09564cd537a1323",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84faf63625376560b7ca9697d09564cd537a1323",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022282305229122544",
"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/8856852243084375456/+/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:51623cfb0ff24910",
"user_agent:luci-scheduler"
],
"updated_ts": "1611850861047694",
"url": "https://ci.chromium.org/b/8856852243084375456",
"utcnow_ts": "1611850862356521"
}
}
[16:21:02.364] Invocation finished in 1h45m50.019785934s with status SUCCEEDED