[10:09:58.907] New invocation is queued and will start shortly
[10:10:00.180] Starting the invocation (attempt 1)
[10:10:00.228] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:10:00.228] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:10:00.228] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026104064284829328",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"49089fa60c72425454bbd538a4574062403b9edf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026104064284829328",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/49089fa60c72425454bbd538a4574062403b9edf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49089fa60c72425454bbd538a4574062403b9edf",
"gitiles_ref:refs/heads/master"
]
}
[10:10:01.315] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608199800393565",
"id": "8860674002061262048",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"49089fa60c72425454bbd538a4574062403b9edf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608199800988871",
"tags": [
"build_address:luci.chromium.ci/win-asan/16279",
"builder:win-asan",
"buildset:commit/git/49089fa60c72425454bbd538a4574062403b9edf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49089fa60c72425454bbd538a4574062403b9edf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026104064284829328",
"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/8860674002061262048/+/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": "1608199800989017",
"url": "https://ci.chromium.org/b/8860674002061262048",
"utcnow_ts": "1608199801290034"
}
}
[10:10:01.315] Task URL: https://ci.chromium.org/b/8860674002061262048
[10:10:01.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:2:0) after 1m0s
[10:11:01.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:2:0)
[10:11:01.368] Timer tick, asking Buildbucket for the build status
[10:11:01.462] Build 8860674002061262048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:01.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:3:0) after 1m0s
[10:12:01.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:3:0)
[10:12:01.524] Timer tick, asking Buildbucket for the build status
[10:12:01.915] Build 8860674002061262048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:01.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:4:0) after 1m0s
[10:13:01.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:4:0)
[10:13:01.933] Timer tick, asking Buildbucket for the build status
[10:13:02.026] Build 8860674002061262048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:02.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:5:0) after 1m0s
[10:14:02.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:5:0)
[10:14:02.045] Timer tick, asking Buildbucket for the build status
[10:14:02.116] Build 8860674002061262048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:02.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:6:0) after 1m0s
[10:15:02.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:6:0)
[10:15:02.172] Timer tick, asking Buildbucket for the build status
[10:15:02.269] Build 8860674002061262048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:02.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:7:0) after 1m0s
[10:15:26.057] Received PubSub notification, asking Buildbucket for the build status
[10:15:26.127] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:02.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:7:0)
[10:16:02.241] Timer tick, asking Buildbucket for the build status
[10:16:02.314] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:02.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:9:0) after 1m0s
[10:17:02.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:9:0)
[10:17:02.366] Timer tick, asking Buildbucket for the build status
[10:17:02.488] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:02.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:10:0) after 1m0s
[10:18:02.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:10:0)
[10:18:02.547] Timer tick, asking Buildbucket for the build status
[10:18:02.651] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:02.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:11:0) after 1m0s
[10:19:02.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:11:0)
[10:19:02.641] Timer tick, asking Buildbucket for the build status
[10:19:02.695] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:02.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:12:0) after 1m0s
[10:20:02.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:12:0)
[10:20:02.743] Timer tick, asking Buildbucket for the build status
[10:20:02.851] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:02.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:13:0) after 1m0s
[10:21:02.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:13:0)
[10:21:02.877] Timer tick, asking Buildbucket for the build status
[10:21:02.944] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:02.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:14:0) after 1m0s
[10:22:02.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:14:0)
[10:22:02.970] Timer tick, asking Buildbucket for the build status
[10:22:03.035] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:03.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:15:0) after 1m0s
[10:23:03.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:15:0)
[10:23:03.127] Timer tick, asking Buildbucket for the build status
[10:23:03.228] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:03.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:16:0) after 1m0s
[10:24:03.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:16:0)
[10:24:03.244] Timer tick, asking Buildbucket for the build status
[10:24:03.328] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:03.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:17:0) after 1m0s
[10:25:03.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:17:0)
[10:25:03.499] Timer tick, asking Buildbucket for the build status
[10:25:03.605] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:03.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:18:0) after 1m0s
[10:26:03.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:18:0)
[10:26:03.660] Timer tick, asking Buildbucket for the build status
[10:26:03.734] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:03.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:19:0) after 1m0s
[10:27:03.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:19:0)
[10:27:03.799] Timer tick, asking Buildbucket for the build status
[10:27:03.932] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:03.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:20:0) after 1m0s
[10:28:04.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:20:0)
[10:28:04.085] Timer tick, asking Buildbucket for the build status
[10:28:04.195] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:04.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:21:0) after 1m0s
[10:29:04.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:21:0)
[10:29:04.200] Timer tick, asking Buildbucket for the build status
[10:29:04.264] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:04.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:22:0) after 1m0s
[10:30:04.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:22:0)
[10:30:04.360] Timer tick, asking Buildbucket for the build status
[10:30:04.481] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:04.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:23:0) after 1m0s
[10:31:04.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:23:0)
[10:31:04.870] Timer tick, asking Buildbucket for the build status
[10:31:04.996] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:04.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:24:0) after 1m0s
[10:32:05.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:24:0)
[10:32:05.183] Timer tick, asking Buildbucket for the build status
[10:32:05.333] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:05.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:25:0) after 1m0s
[10:33:05.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:25:0)
[10:33:05.386] Timer tick, asking Buildbucket for the build status
[10:33:05.531] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:05.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:26:0) after 1m0s
[10:34:05.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:26:0)
[10:34:05.647] Timer tick, asking Buildbucket for the build status
[10:34:05.791] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:05.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:27:0) after 1m0s
[10:35:05.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:27:0)
[10:35:05.981] Timer tick, asking Buildbucket for the build status
[10:35:06.302] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:06.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:28:0) after 1m0s
[10:36:06.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:28:0)
[10:36:06.427] Timer tick, asking Buildbucket for the build status
[10:36:06.483] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:06.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:29:0) after 1m0s
[10:37:06.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:29:0)
[10:37:06.575] Timer tick, asking Buildbucket for the build status
[10:37:06.652] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:06.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:30:0) after 1m0s
[10:38:06.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:30:0)
[10:38:06.703] Timer tick, asking Buildbucket for the build status
[10:38:06.756] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:06.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:31:0) after 1m0s
[10:39:06.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:31:0)
[10:39:06.894] Timer tick, asking Buildbucket for the build status
[10:39:06.960] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:06.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:32:0) after 1m0s
[10:40:07.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:32:0)
[10:40:07.042] Timer tick, asking Buildbucket for the build status
[10:40:07.198] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:07.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:33:0) after 1m0s
[10:41:07.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:33:0)
[10:41:07.256] Timer tick, asking Buildbucket for the build status
[10:41:07.344] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:07.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:34:0) after 1m0s
[10:42:07.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:34:0)
[10:42:07.387] Timer tick, asking Buildbucket for the build status
[10:42:07.816] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:07.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:35:0) after 1m0s
[10:43:07.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:35:0)
[10:43:07.916] Timer tick, asking Buildbucket for the build status
[10:43:08.224] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:08.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:36:0) after 1m0s
[10:44:08.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:36:0)
[10:44:08.418] Timer tick, asking Buildbucket for the build status
[10:44:08.478] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:08.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:37:0) after 1m0s
[10:45:08.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:37:0)
[10:45:08.584] Timer tick, asking Buildbucket for the build status
[10:45:08.685] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:08.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:38:0) after 1m0s
[10:46:08.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:38:0)
[10:46:08.758] Timer tick, asking Buildbucket for the build status
[10:46:08.833] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:08.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:39:0) after 1m0s
[10:47:08.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:39:0)
[10:47:08.861] Timer tick, asking Buildbucket for the build status
[10:47:08.930] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:08.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:40:0) after 1m0s
[10:48:09.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:40:0)
[10:48:09.027] Timer tick, asking Buildbucket for the build status
[10:48:09.090] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:09.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:41:0) after 1m0s
[10:49:09.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:41:0)
[10:49:09.198] Timer tick, asking Buildbucket for the build status
[10:49:09.257] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:09.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:42:0) after 1m0s
[10:50:09.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:42:0)
[10:50:09.279] Timer tick, asking Buildbucket for the build status
[10:50:09.380] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:09.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:43:0) after 1m0s
[10:51:09.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:43:0)
[10:51:09.395] Timer tick, asking Buildbucket for the build status
[10:51:10.326] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:10.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:44:0) after 1m0s
[10:52:10.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:44:0)
[10:52:10.373] Timer tick, asking Buildbucket for the build status
[10:52:10.699] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:10.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:45:0) after 1m0s
[10:53:10.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:45:0)
[10:53:10.717] Timer tick, asking Buildbucket for the build status
[10:53:11.098] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:11.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:46:0) after 1m0s
[10:54:11.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:46:0)
[10:54:11.153] Timer tick, asking Buildbucket for the build status
[10:54:11.530] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:11.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:47:0) after 1m0s
[10:55:11.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:47:0)
[10:55:11.571] Timer tick, asking Buildbucket for the build status
[10:55:12.201] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:12.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:48:0) after 1m0s
[10:56:12.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:48:0)
[10:56:12.362] Timer tick, asking Buildbucket for the build status
[10:56:12.522] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:12.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:49:0) after 1m0s
[10:57:12.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:49:0)
[10:57:12.689] Timer tick, asking Buildbucket for the build status
[10:57:12.762] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:12.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:50:0) after 1m0s
[10:58:12.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:50:0)
[10:58:12.862] Timer tick, asking Buildbucket for the build status
[10:58:13.259] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:13.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:51:0) after 1m0s
[10:59:13.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:51:0)
[10:59:13.250] Timer tick, asking Buildbucket for the build status
[10:59:13.384] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:13.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:52:0) after 1m0s
[11:00:13.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:52:0)
[11:00:13.413] Timer tick, asking Buildbucket for the build status
[11:00:13.546] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:13.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:53:0) after 1m0s
[11:01:13.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:53:0)
[11:01:13.571] Timer tick, asking Buildbucket for the build status
[11:01:13.662] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:13.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:54:0) after 1m0s
[11:02:13.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:54:0)
[11:02:13.684] Timer tick, asking Buildbucket for the build status
[11:02:13.924] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:13.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:55:0) after 1m0s
[11:03:13.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:55:0)
[11:03:13.938] Timer tick, asking Buildbucket for the build status
[11:03:16.125] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:16.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:56:0) after 1m0s
[11:04:16.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:56:0)
[11:04:16.291] Timer tick, asking Buildbucket for the build status
[11:04:16.358] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:16.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:57:0) after 1m0s
[11:05:16.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:57:0)
[11:05:16.509] Timer tick, asking Buildbucket for the build status
[11:05:16.636] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:16.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:58:0) after 1m0s
[11:06:16.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:58:0)
[11:06:16.721] Timer tick, asking Buildbucket for the build status
[11:06:16.785] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:16.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:59:0) after 1m0s
[11:07:16.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:59:0)
[11:07:16.956] Timer tick, asking Buildbucket for the build status
[11:07:17.029] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:17.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:60:0) after 1m0s
[11:08:17.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:60:0)
[11:08:17.125] Timer tick, asking Buildbucket for the build status
[11:08:18.115] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:18.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:61:0) after 1m0s
[11:09:18.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:61:0)
[11:09:18.324] Timer tick, asking Buildbucket for the build status
[11:09:18.399] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:18.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:62:0) after 1m0s
[11:10:18.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:62:0)
[11:10:18.529] Timer tick, asking Buildbucket for the build status
[11:10:18.636] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:18.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:63:0) after 1m0s
[11:11:18.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:63:0)
[11:11:18.695] Timer tick, asking Buildbucket for the build status
[11:11:18.904] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:18.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:64:0) after 1m0s
[11:12:19.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:64:0)
[11:12:19.008] Timer tick, asking Buildbucket for the build status
[11:12:19.062] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:19.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:65:0) after 1m0s
[11:13:19.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:65:0)
[11:13:19.077] Timer tick, asking Buildbucket for the build status
[11:13:19.192] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:19.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:66:0) after 1m0s
[11:14:19.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:66:0)
[11:14:19.302] Timer tick, asking Buildbucket for the build status
[11:14:19.405] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:19.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:67:0) after 1m0s
[11:15:19.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:67:0)
[11:15:19.426] Timer tick, asking Buildbucket for the build status
[11:15:19.807] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:19.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:68:0) after 1m0s
[11:16:21.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:68:0)
[11:16:21.437] Timer tick, asking Buildbucket for the build status
[11:16:21.789] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:21.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:69:0) after 1m0s
[11:17:21.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:69:0)
[11:17:21.963] Timer tick, asking Buildbucket for the build status
[11:17:22.032] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:22.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:70:0) after 1m0s
[11:18:22.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:70:0)
[11:18:22.296] Timer tick, asking Buildbucket for the build status
[11:18:22.404] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:22.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:71:0) after 1m0s
[11:19:22.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:71:0)
[11:19:22.649] Timer tick, asking Buildbucket for the build status
[11:19:22.760] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:22.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:72:0) after 1m0s
[11:20:22.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:72:0)
[11:20:22.937] Timer tick, asking Buildbucket for the build status
[11:20:23.246] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:23.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:73:0) after 1m0s
[11:21:23.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:73:0)
[11:21:23.355] Timer tick, asking Buildbucket for the build status
[11:21:23.444] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:23.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:74:0) after 1m0s
[11:22:23.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:74:0)
[11:22:23.618] Timer tick, asking Buildbucket for the build status
[11:22:24.066] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:24.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:75:0) after 1m0s
[11:23:24.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:75:0)
[11:23:24.100] Timer tick, asking Buildbucket for the build status
[11:23:24.409] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:24.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:76:0) after 1m0s
[11:24:24.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:76:0)
[11:24:24.446] Timer tick, asking Buildbucket for the build status
[11:24:24.830] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:24.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:77:0) after 1m0s
[11:25:24.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:77:0)
[11:25:24.894] Timer tick, asking Buildbucket for the build status
[11:25:25.227] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:25.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:78:0) after 1m0s
[11:26:25.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:78:0)
[11:26:25.327] Timer tick, asking Buildbucket for the build status
[11:26:25.432] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:25.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:79:0) after 1m0s
[11:27:25.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:79:0)
[11:27:25.504] Timer tick, asking Buildbucket for the build status
[11:27:25.634] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:25.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:80:0) after 1m0s
[11:28:25.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:80:0)
[11:28:25.755] Timer tick, asking Buildbucket for the build status
[11:28:26.038] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:26.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:81:0) after 1m0s
[11:29:26.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:81:0)
[11:29:26.119] Timer tick, asking Buildbucket for the build status
[11:29:26.321] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:26.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:82:0) after 1m0s
[11:30:26.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:82:0)
[11:30:26.444] Timer tick, asking Buildbucket for the build status
[11:30:26.552] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:26.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:83:0) after 1m0s
[11:31:26.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:83:0)
[11:31:26.590] Timer tick, asking Buildbucket for the build status
[11:31:26.724] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:26.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:84:0) after 1m0s
[11:32:26.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:84:0)
[11:32:26.790] Timer tick, asking Buildbucket for the build status
[11:32:26.861] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:26.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:85:0) after 1m0s
[11:33:27.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:85:0)
[11:33:27.112] Timer tick, asking Buildbucket for the build status
[11:33:27.211] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:27.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:86:0) after 1m0s
[11:34:27.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:86:0)
[11:34:27.488] Timer tick, asking Buildbucket for the build status
[11:34:27.600] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:27.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:87:0) after 1m0s
[11:35:27.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:87:0)
[11:35:27.693] Timer tick, asking Buildbucket for the build status
[11:35:27.794] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:27.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:88:0) after 1m0s
[11:36:27.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:88:0)
[11:36:27.922] Timer tick, asking Buildbucket for the build status
[11:36:28.001] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:28.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:89:0) after 1m0s
[11:37:28.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:89:0)
[11:37:28.022] Timer tick, asking Buildbucket for the build status
[11:37:28.112] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:28.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:90:0) after 1m0s
[11:38:28.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:90:0)
[11:38:28.137] Timer tick, asking Buildbucket for the build status
[11:38:28.199] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:28.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:91:0) after 1m0s
[11:39:28.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:91:0)
[11:39:28.263] Timer tick, asking Buildbucket for the build status
[11:39:28.401] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:28.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:92:0) after 1m0s
[11:40:28.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:92:0)
[11:40:28.458] Timer tick, asking Buildbucket for the build status
[11:40:28.602] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:28.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:93:0) after 1m0s
[11:41:28.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:93:0)
[11:41:28.664] Timer tick, asking Buildbucket for the build status
[11:41:28.735] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:28.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:94:0) after 1m0s
[11:42:28.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:94:0)
[11:42:28.989] Timer tick, asking Buildbucket for the build status
[11:42:29.156] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:29.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:95:0) after 1m0s
[11:43:29.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:95:0)
[11:43:29.375] Timer tick, asking Buildbucket for the build status
[11:43:29.811] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:29.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:96:0) after 1m0s
[11:44:29.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:96:0)
[11:44:29.862] Timer tick, asking Buildbucket for the build status
[11:44:29.949] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:29.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:97:0) after 1m0s
[11:45:30.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:97:0)
[11:45:30.073] Timer tick, asking Buildbucket for the build status
[11:45:30.195] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:30.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:98:0) after 1m0s
[11:46:30.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:98:0)
[11:46:30.257] Timer tick, asking Buildbucket for the build status
[11:46:30.519] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:30.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:99:0) after 1m0s
[11:47:30.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:99:0)
[11:47:30.516] Timer tick, asking Buildbucket for the build status
[11:47:30.636] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:30.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:100:0) after 1m0s
[11:48:30.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:100:0)
[11:48:30.753] Timer tick, asking Buildbucket for the build status
[11:48:30.833] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:30.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:101:0) after 1m0s
[11:49:30.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:101:0)
[11:49:30.931] Timer tick, asking Buildbucket for the build status
[11:49:31.413] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:31.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:102:0) after 1m0s
[11:50:31.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:102:0)
[11:50:31.522] Timer tick, asking Buildbucket for the build status
[11:50:31.635] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:31.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:103:0) after 1m0s
[11:51:32.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:103:0)
[11:51:32.210] Timer tick, asking Buildbucket for the build status
[11:51:32.282] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:32.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:104:0) after 1m0s
[11:52:32.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:104:0)
[11:52:32.304] Timer tick, asking Buildbucket for the build status
[11:52:32.393] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:32.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:105:0) after 1m0s
[11:53:32.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:105:0)
[11:53:32.436] Timer tick, asking Buildbucket for the build status
[11:53:32.504] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:32.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:106:0) after 1m0s
[11:54:32.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:106:0)
[11:54:32.616] Timer tick, asking Buildbucket for the build status
[11:54:32.692] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:32.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:107:0) after 1m0s
[11:55:32.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:107:0)
[11:55:32.713] Timer tick, asking Buildbucket for the build status
[11:55:32.811] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:32.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:108:0) after 1m0s
[11:56:32.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:108:0)
[11:56:32.896] Timer tick, asking Buildbucket for the build status
[11:56:32.957] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:32.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:109:0) after 1m0s
[11:57:32.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:109:0)
[11:57:32.992] Timer tick, asking Buildbucket for the build status
[11:57:33.409] Build 8860674002061262048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:33.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026104064284829328:110:0) after 1m0s
[11:58:13.751] Received PubSub notification, asking Buildbucket for the build status
[11:58:13.821] Build 8860674002061262048: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:58:13.821] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608206292934730",
"created_by": "project:chromium",
"created_ts": "1608199800393565",
"id": "8860674002061262048",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"49089fa60c72425454bbd538a4574062403b9edf\"}}",
"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\": \"49089fa60c72425454bbd538a4574062403b9edf\", \"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-5-miap\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16279, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"b31cd871ad1000a56302409b62d76e079f8a0b46\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"49089fa60c72425454bbd538a4574062403b9edf\", \"got_revision_cp\": \"refs/heads/master@{#837997}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"448c4502537e675b5c79ee3934ccf84f6be3fa51\", \"got_v8_revision_cp\": \"refs/heads/8.9.162@{#1}\", \"got_webrtc_revision\": \"8592111e458d253e53806db65d23d21bfe9d9db4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32849}\", \"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\": \"49089fa60c72425454bbd538a4574062403b9edf\", \"swarm_hashes_refs/heads/master(at){#837997}_without_patch\": {\"absl_hardening_tests\": \"11ca7b0d59d1822d8b4110c0c12c91d121ab599c\", \"accessibility_unittests\": \"624ea119eb3582ae9edec6d5475d4ec93cf53c67\", \"angle_unittests\": \"52e3bed6807f5b7fad2083cb73016a0ddfe0f2f0\", \"app_shell_unittests\": \"825bdc088f26296dbf2bf891d65271ac15d97187\", \"aura_unittests\": \"b1172034395d9c44ce84af4fb7357f9c68e70a31\", \"base_unittests\": \"7f4d610c2adddc80d5c191f990a4e3802d218093\", \"base_util_unittests\": \"9ee0b020bfa8daf587d889677d2e3b3233ccc7a5\", \"blink_common_unittests\": \"9aef8083119a718cc9b9b3fb225a8b4185266f5a\", \"blink_fuzzer_unittests\": \"4b3065f4a0f0c1045f8bd37517d663b8a38156c8\", \"blink_heap_unittests\": \"53c30cc00ee90f1f79de667f43a49adab7a4af93\", \"blink_platform_unittests\": \"89598ba0236bb949ac37ba8e7dca425a78341333\", \"blink_unittests\": \"c57e303b91cd59b3766fa2b87fc5808569461f2c\", \"boringssl_crypto_tests\": \"e5df87255db296cadf4b5da68fdfe171c199b0c2\", \"boringssl_ssl_tests\": \"a5bfc1035cea49909ca46c04449136d9b0e58d97\", \"browser_switcher_bho_unittests\": \"e4ba704278491c62c8129eba0c3459a36f3d2afa\", \"browser_tests\": \"11db59d53ced166113a7b72ec9f451dcb0bc7686\", \"capture_unittests\": \"6a4433aa30ffe7368488c73b47bdc58ba6606025\", \"cast_unittests\": \"45bbdf5ae532e429dfc72ff0d4e97cf7c8fe778b\", \"cc_unittests\": \"fa05687b6393ef1dcd9aba3a5a02c9399d5877f0\", \"chrome_app_unittests\": \"6fd7698102c3cc67683c0ba4d8bc5a9fbbd6ba7e\", \"chrome_cleaner_unittests\": \"77848d76413fe1a93b744427e0f1f92705330dac\", \"chrome_elf_unittests\": \"b73ee5372c8d525ddf046c7a52cce410d7abccd2\", \"chromedriver_unittests\": \"ca450b4d02d2fa237cdce78dde8eba52026e31fe\", \"components_browsertests\": \"99f4ba14a25de5484e150b164f0e50c087417b73\", \"components_unittests\": \"e60ea3030c3d3235488ffb40e5eed55c6ef53394\", \"compositor_unittests\": \"d76120ace8d69ccc7bdb844f53e6e0a2ade61764\", \"content_browsertests\": \"7f3164cc2f75d930dccd04ac169a33abfb31e54d\", \"content_unittests\": \"4702f187c5867781e899adbb3d802d644f71e728\", \"courgette_unittests\": \"f3825e13dbf9a01cb13677980c90d73c3760d91a\", \"crashpad_tests\": \"5cb46ab556a6b6aa00f8c8f61c8903cf16bb3c65\", \"cronet_tests\": \"169576488cf10d07fbc044463b823f958e25659a\", \"cronet_unittests\": \"b567decd3cb9fc1c40cbe18fd5ba385640e67353\", \"crypto_unittests\": \"7c700f39d9353b71ff913f2b0564b63e70468c92\", \"delayloads_unittests\": \"54c55dd9fa19f8df7a69ae7cdb900993a08d87bc\", \"device_unittests\": \"f9eab7dcdc27425b7cea3dbaf911a3fd8d336ae7\", \"display_unittests\": \"0cfa5576794a9357d3b9d6b96cac0baf28531180\", \"elevation_service_unittests\": \"23e98c4200d25e07b485e2e14796e7269741b1e3\", \"events_unittests\": \"bdbaa9da1add058b52af5ebcded2e0aab76fc8f0\", \"extensions_browsertests\": \"36973a00d1e34d4f888b1cfa1247a9d3c965bbf0\", \"extensions_unittests\": \"dcc2814e46e8da2d11d391e4c703b66f21d482f6\", \"filesystem_service_unittests\": \"2bc35df44f70548ab9543686b1338b8a039e9899\", \"gcm_unit_tests\": \"24610c66ce98d8f701b60cebff216d8664eeb5d6\", \"gcp_unittests\": \"554d11b4d9333d15406ab1a87db008110043872e\", \"gfx_unittests\": \"3b7a7e9cd914e42937dc2e93a05c4a531b69334b\", \"gin_unittests\": \"c55475cd3046c02ff7b955a66e38e8d2646f012a\", \"google_apis_unittests\": \"56cfeefe50a2c5b3be755cb5f750573f9af9619f\", \"gpu_unittests\": \"9b225cd27a7a4b5ac2221440c88383929017ae79\", \"gwp_asan_unittests\": \"f44b0ef70c0a01364c37e1f2573a9643ce423c7e\", \"headless_browsertests\": \"b9da98f48f19ac47fc0131d14e2d71c5f25fd5f8\", \"headless_unittests\": \"6ba234cbb0fd1067e6f4f3fd84c2a50c00b6f28e\", \"install_static_unittests\": \"8072db494be9689746ce4d01701281fc1c806e1f\", \"installer_util_unittests\": \"57bd81a6995127a7fffe1940fadc0b74a26f4994\", \"interactive_ui_tests\": \"012d2d8e06e7aa89761b503a61c9514bd7e679e5\", \"ipc_tests\": \"d0c5126bd2fc420d30a0a65de8a4cd5334c45f3c\", \"jingle_unittests\": \"4b2c0cba2c323fdc9fc429b47c1bf44a764130c7\", \"latency_unittests\": \"531c95dc3f5673d388ffc7fd48076f637564b238\", \"libjingle_xmpp_unittests\": \"cb6dea5c501860c6f7986888788426c53a6f4012\", \"liburlpattern_unittests\": \"c26816a753b9e74fbcfd4990e19a3c39c48d56bd\", \"media_blink_unittests\": \"f19406c41f32281fb1a6d9a3c3b52a24954f3147\", \"media_unittests\": \"91ab2a0c33bf72dc16801d180997bc3ea5c7351d\", \"message_center_unittests\": \"a141036925b281ec6f7fd7fdacc97b86ca9d3d64\", \"midi_unittests\": \"826e2604576c01ce65a1dbcf91a316544bef120f\", \"mojo_core_unittests\": \"9e22023e37951f85473766dccaec81c4a2daab57\", \"mojo_unittests\": \"68d7ad9b3d06cda6216c49a824117065fe07ac27\", \"nacl_loader_unittests\": \"2eada4648d559d45a24fed68c5c9b934310299c3\", \"native_theme_unittests\": \"2888b46803bfd369714034053cd5d4cc9e0b0deb\", \"net_unittests\": \"67a4c423b25ad75f7e9715909e576eef2d084182\", \"notification_helper_unittests\": \"8663226ae628918411635b191e498bad53455f00\", \"pdf_unittests\": \"eeed0bdc00d2d6e648202f4674ec376d3c32144c\", \"perfetto_unittests\": \"9c350295da9dd1755b93be5136221f4967b3fb37\", \"ppapi_unittests\": \"72751b937382aef5fbeb4044bdebc3a659fc4eea\", \"printing_unittests\": \"74d7c4845d7b824008756cb57abf9e7d8d53d725\", \"remoting_unittests\": \"0383d042d371eb436dc44e41055a72156d64a463\", \"sbox_integration_tests\": \"892e90d7055c2bdf72902d12a8700bfd852ac245\", \"sbox_unittests\": \"ecae38167b586cbe70e4dcaf343cb98f0ce2c853\", \"sbox_validation_tests\": \"afb0d991efc7c4ac2071e29cc24c5f469c25b0ed\", \"service_manager_unittests\": \"6fabde3a322aaefa186d4866eef29bf75ece30d1\", \"services_unittests\": \"6cd1cd86e009262bf8bb30a6540e8c06d491eb73\", \"setup_unittests\": \"18cfe058923416fa4c91632843e74c405f947e47\", \"shell_dialogs_unittests\": \"0ba5f67b0a41fdb9badf189909a0d5a1e8f04567\", \"skia_unittests\": \"3791109fc2d437cb2f25e50a427adc83ecf1efaa\", \"snapshot_unittests\": \"a494d2bdb17d32b67d7744b41a8be865386b2bbd\", \"sql_unittests\": \"31774917c03d89348e71a510de0563eb0f02e046\", \"storage_unittests\": \"7855c3441e8e7616fa08f1992f4a2aa139c9efee\", \"sync_integration_tests\": \"228700ad73e0936503d6dc00da01a7cd854c496f\", \"traffic_annotation_auditor_unittests\": \"6f1512177c099e83a1a42c21c1c9e77a54c77cf2\", \"ui_base_unittests\": \"134185e151286f6770de29dc7ad6e348bba922b0\", \"ui_touch_selection_unittests\": \"f641723fc3356d68d38de885d3864b9985b2ba3b\", \"unit_tests\": \"5b125c6ae2a16865d57d66a0d936475f16c4d6de\", \"updater_tests\": \"3f36bfe5a61587decb1ccd9a0971395b1d58b439\", \"url_unittests\": \"9e79d10301262e06e8c4f3d333f85506a071d3c6\", \"views_unittests\": \"a673d74ebba3788cf40372bc3c4165b90f991804\", \"viz_unittests\": \"3270f83b7a9b59e1a94d79b8c4a6fc117b4842eb\", \"vr_common_unittests\": \"9a7b8da38867c27b48e8d449ca3fc00fc5bb8b54\", \"vr_pixeltests\": \"3f5d6866da54c9faabb7c8a96feb9a6c3c3032a0\", \"weblayer_browsertests\": \"03a3a2fa4353e4ef792af299f648bb692387340b\", \"weblayer_unittests\": \"c2546cb384e0670e8b36158d39484674508073ce\", \"wm_unittests\": \"5e4effb423defbf2dd0c8aea28490b578f3e4c6c\", \"wtf_unittests\": \"3875f5787977f79b5a39395b61a1c5b4b75eb858\", \"zlib_unittests\": \"eb2fafb8712618e5750ea3cec3429a54ca41dad7\", \"zucchini_unittests\": \"28ef444e8ca5606bfdeafe62c37d779817e4443e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-miap\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608200125300045",
"status": "COMPLETED",
"status_changed_ts": "1608206293180354",
"tags": [
"build_address:luci.chromium.ci/win-asan/16279",
"builder:win-asan",
"buildset:commit/git/49089fa60c72425454bbd538a4574062403b9edf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49089fa60c72425454bbd538a4574062403b9edf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026104064284829328",
"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/8860674002061262048/+/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:5088ff11ee05eb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608206293184032",
"url": "https://ci.chromium.org/b/8860674002061262048",
"utcnow_ts": "1608206293813759"
}
}
[11:58:13.821] Invocation finished in 1h48m14.932477412s with status SUCCEEDED